Feb 07 21:11:16 volumio volumio[1481]: info: Preload queue cleared Feb 07 21:11:16 volumio volumio[1481]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::ClearQueue Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::stop Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::stPlaybackTimer Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::updateTrackBlock Feb 07 21:11:16 volumio volumio[1481]: info: CorePlayQueue::getTrackBlock Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::pushState Feb 07 21:11:16 volumio volumio[1481]: info: CorePlayQueue::getTrack 4 Feb 07 21:11:16 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 21:11:16 volumio volumio[1481]: info: CoreCommandRouter::volumioPushState Feb 07 21:11:16 volumio volumio[1481]: info: MRS: Pushing multiroomSync output update for this device Feb 07 21:11:16 volumio volumio[1481]: info: MRS: Pushing multiroomSync output Feb 07 21:11:16 volumio volumio[1481]: info: CoreCommandRouter::volumioGetState Feb 07 21:11:16 volumio volumio[1481]: info: CorePlayQueue::getTrack 4 Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::serviceStop Feb 07 21:11:16 volumio volumio[1481]: info: CorePlayQueue::getTrack 4 Feb 07 21:11:16 volumio volumio[1481]: info: CoreCommandRouter::serviceStop Feb 07 21:11:16 volumio volumio[1481]: info: [1770473476867] ControllerTidal::stop Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 07 21:11:16 volumio volumio[1481]: info: ControllerMpd::stop Feb 07 21:11:16 volumio volumio[1481]: verbose: ControllerMpd::sendMpdCommand stop Feb 07 21:11:16 volumio volumio[1481]: info: CorePlayQueue::clearPlayQueue Feb 07 21:11:16 volumio volumio[1481]: info: CorePlayQueue::saveQueue Feb 07 21:11:16 volumio volumio[1481]: info: CoreCommandRouter::volumioPushQueue Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::addQueueItems Feb 07 21:11:16 volumio volumio[1481]: info: CorePlayQueue::addQueueItems Feb 07 21:11:16 volumio volumio[1481]: info: Preload queue cleared Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961163 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961163 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961164 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961164 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961165 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961165 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961166 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961166 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961167 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961167 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961168 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961168 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961169 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961169 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961170 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961170 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961171 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961171 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961172 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961172 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961173 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961173 Feb 07 21:11:16 volumio volumio[1481]: info: CoreCommandRouter::volumioPushQueue Feb 07 21:11:16 volumio volumio[1481]: info: CorePlayQueue::saveQueue Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::updateTrackBlock Feb 07 21:11:16 volumio volumio[1481]: info: CorePlayQueue::getTrackBlock Feb 07 21:11:16 volumio volumio[1481]: info: CoreCommandRouter::volumioGetState Feb 07 21:11:16 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Feb 07 21:11:16 volumio volumio[1481]: info: CoreCommandRouter::volumioPlay Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::play index 10 Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::addQueueItems Feb 07 21:11:16 volumio volumio[1481]: info: CorePlayQueue::addQueueItems Feb 07 21:11:16 volumio volumio[1481]: info: Preload queue cleared Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961174 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961174 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961175 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961175 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961176 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961176 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961177 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961177 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961178 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961178 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961179 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961179 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961180 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961180 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961181 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961181 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961182 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961182 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961183 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961183 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961184 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961184 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961185 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961185 Feb 07 21:11:16 volumio volumio[1481]: info: Adding Item to queue: tidal://song/3961186 Feb 07 21:11:16 volumio volumio[1481]: info: Using cached record of: tidal://song/3961186 Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::stop Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 21:11:16 volumio volumio[1481]: info: CoreCommandRouter::volumioPushQueue Feb 07 21:11:16 volumio volumio[1481]: info: CorePlayQueue::saveQueue Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::play index undefined Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::updateTrackBlock Feb 07 21:11:16 volumio volumio[1481]: info: CorePlayQueue::getTrackBlock Feb 07 21:11:16 volumio volumio[1481]: info: CorePlayQueue::getTrack 10 Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::startPlaybackTimer Feb 07 21:11:16 volumio volumio[1481]: info: CorePlayQueue::getTrack 10 Feb 07 21:11:16 volumio volumio[1481]: info: [1770473476910] ControllerTidal::clearAddPlayTrack Feb 07 21:11:16 volumio volumio[1481]: info: Getting stream with soundQuality HI_RES Feb 07 21:11:16 volumio volumio[1481]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 07 21:11:16 volumio volumio[1481]: info: PeppySpectrum ---peppyspectrum status stop Feb 07 21:11:16 volumio sudo[5017]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 07 21:11:16 volumio sudo[5017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 21:11:16 volumio sudo[5024]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 07 21:11:16 volumio sudo[5024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 21:11:16 volumio volumio[1481]: info: Feb 07 21:11:16 volumio volumio[1481]: ---------------------------- MPD announces state update: player Feb 07 21:11:16 volumio volumio[1481]: info: sendMpdCommand stop took 85 milliseconds Feb 07 21:11:16 volumio volumio[1481]: info: ControllerMpd::getState Feb 07 21:11:16 volumio volumio[1481]: verbose: ControllerMpd::sendMpdCommand status Feb 07 21:11:16 volumio volumio[1481]: info: sendMpdCommand status took 3 milliseconds Feb 07 21:11:16 volumio volumio[1481]: verbose: ControllerMpd::parseState Feb 07 21:11:16 volumio volumio[1481]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 21:11:16 volumio volumio[1481]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 07 21:11:16 volumio volumio[1481]: verbose: ControllerMpd::parseTrackInfo Feb 07 21:11:16 volumio volumio[1481]: info: ControllerMpd::pushState Feb 07 21:11:16 volumio volumio[1481]: info: CoreCommandRouter::servicePushState Feb 07 21:11:16 volumio volumio[1481]: info: CoreStateMachine::pushState Feb 07 21:11:16 volumio volumio[1481]: info: CorePlayQueue::getTrack 10 Feb 07 21:11:16 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 21:11:16 volumio volumio[1481]: info: CoreCommandRouter::volumioPushState Feb 07 21:11:16 volumio volumio[1481]: info: MRS: Pushing multiroomSync output update for this device Feb 07 21:11:16 volumio volumio[1481]: info: MRS: Pushing multiroomSync output Feb 07 21:11:16 volumio volumio[1481]: info: CoreCommandRouter::volumioGetState Feb 07 21:11:16 volumio volumio[1481]: info: CorePlayQueue::getTrack 10 Feb 07 21:11:16 volumio volumio[1481]: info: CorePlayQueue::getTrack 10 Feb 07 21:11:16 volumio volumio[1481]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Feb 07 21:11:16 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Feb 07 21:11:16 volumio volumio[1481]: info: ------------------------------ 30ms Feb 07 21:11:16 volumio volumio[1481]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 07 21:11:16 volumio sudo[5024]: pam_unix(sudo:session): session closed for user root Feb 07 21:11:16 volumio volumio[1481]: info: PeppySpectrum ---peppyspectrum status stop Feb 07 21:11:17 volumio sudo[5029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 07 21:11:17 volumio sudo[5029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 21:11:17 volumio volumio[1481]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 07 21:11:17 volumio volumio[1481]: info: peppyspectrum Daemon Stop Feb 07 21:11:17 volumio sudo[5031]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Feb 07 21:11:17 volumio sudo[5031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 21:11:17 volumio sudo[5031]: pam_unix(sudo:session): session closed for user root Feb 07 21:11:17 volumio volumio[1481]: info: peppyspectrum Daemon Stop Feb 07 21:11:17 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Feb 07 21:11:17 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Feb 07 21:11:17 volumio systemd[1]: peppymeterbasic.service: Consumed 7.631s CPU time. Feb 07 21:11:17 volumio sudo[5029]: pam_unix(sudo:session): session closed for user root Feb 07 21:11:17 volumio volumio[1481]: info: peppymeterbasic Daemon Stop Feb 07 21:11:17 volumio sudo[5017]: pam_unix(sudo:session): session closed for user root Feb 07 21:11:17 volumio volumio[1481]: info: peppymeterbasic Daemon Stop Feb 07 21:11:17 volumio volumio[1481]: info: getStreamUrl took 713 milliseconds Feb 07 21:11:17 volumio volumio[1481]: verbose: ControllerMpd::sendMpdCommand stop Feb 07 21:11:17 volumio volumio[1481]: info: sendMpdCommand stop took 0 milliseconds Feb 07 21:11:17 volumio volumio[1481]: verbose: ControllerMpd::sendMpdCommand clear Feb 07 21:11:17 volumio volumio[1481]: info: Feb 07 21:11:17 volumio volumio[1481]: ---------------------------- MPD announces system playlist update Feb 07 21:11:17 volumio volumio[1481]: info: Ignoring MPD Status Update Feb 07 21:11:17 volumio volumio[1481]: info: sendMpdCommand clear took 1 milliseconds Feb 07 21:11:17 volumio volumio[1481]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1MTAxZjQwNWZmN2RhNTI4MmU4Y2RlZGJkODNlZTFjMy5tcDQ/0.flac?token=1770477077~YTZmZmZjMjgyOTI5YTIwZDM5MWU1NmM4MDQ4NTJlZjAxOGJmZjVmYQ==" Feb 07 21:11:17 volumio volumio[1481]: error: updateQueue error: null Feb 07 21:11:17 volumio volumio[1481]: info: ------------------------------ 0ms Feb 07 21:11:17 volumio volumio[1481]: info: Feb 07 21:11:17 volumio volumio[1481]: ---------------------------- MPD announces system playlist update Feb 07 21:11:17 volumio volumio[1481]: info: Ignoring MPD Status Update Feb 07 21:11:17 volumio volumio[1481]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1MTAxZjQwNWZmN2RhNTI4MmU4Y2RlZGJkODNlZTFjMy5tcDQ/0.flac?token=1770477077~YTZmZmZjMjgyOTI5YTIwZDM5MWU1NmM4MDQ4NTJlZjAxOGJmZjVmYQ==" took 1 milliseconds Feb 07 21:11:17 volumio volumio[1481]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 07 21:11:17 volumio volumio[1481]: verbose: ControllerMpd::sendMpdCommand play Feb 07 21:11:17 volumio volumio[1481]: info: ------------------------------ 1ms Feb 07 21:11:17 volumio volumio[1481]: info: sendMpdCommand play took 0 milliseconds Feb 07 21:11:18 volumio volumio[1481]: info: Feb 07 21:11:18 volumio volumio[1481]: ---------------------------- MPD announces state update: player Feb 07 21:11:18 volumio volumio[1481]: info: ControllerMpd::getState Feb 07 21:11:18 volumio volumio[1481]: verbose: ControllerMpd::sendMpdCommand status Feb 07 21:11:18 volumio volumio[1481]: info: sendMpdCommand status took 14 milliseconds Feb 07 21:11:18 volumio volumio[1481]: verbose: ControllerMpd::parseState Feb 07 21:11:18 volumio volumio[1481]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 21:11:18 volumio volumio[1481]: info: Feb 07 21:11:18 volumio volumio[1481]: ---------------------------- MPD announces state update: player Feb 07 21:11:18 volumio volumio[1481]: info: ControllerMpd::getState Feb 07 21:11:18 volumio volumio[1481]: verbose: ControllerMpd::sendMpdCommand status Feb 07 21:11:18 volumio volumio[1481]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 21:11:18 volumio volumio[1481]: verbose: ControllerMpd::parseTrackInfo Feb 07 21:11:18 volumio volumio[1481]: info: ControllerMpd::pushState Feb 07 21:11:18 volumio volumio[1481]: info: CoreCommandRouter::servicePushState Feb 07 21:11:18 volumio volumio[1481]: info: CorePlayQueue::getTrack 10 Feb 07 21:11:18 volumio volumio[1481]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":311,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1770477077~YTZmZmZjMjgyOTI5YTIwZDM5MWU1NmM4MDQ4NTJlZjAxOGJmZjVmYQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1MTAxZjQwNWZmN2RhNTI4MmU4Y2RlZGJkODNlZTFjMy5tcDQ/0.flac?token=1770477077~YTZmZmZjMjgyOTI5YTIwZDM5MWU1NmM4MDQ4NTJlZjAxOGJmZjVmYQ==","trackType":"tidal"} Feb 07 21:11:18 volumio volumio[1481]: verbose: CURRENT POSITION 10 Feb 07 21:11:18 volumio volumio[1481]: info: CoreStateMachine::syncState stateService play Feb 07 21:11:18 volumio volumio[1481]: info: CoreStateMachine::syncState currentStatus stop Feb 07 21:11:18 volumio volumio[1481]: info: ------------------------------ 15ms Feb 07 21:11:18 volumio volumio[1481]: info: sendMpdCommand status took 1 milliseconds Feb 07 21:11:18 volumio volumio[1481]: verbose: ControllerMpd::parseState Feb 07 21:11:18 volumio volumio[1481]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 07 21:11:18 volumio volumio[1481]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 07 21:11:18 volumio volumio[1481]: verbose: ControllerMpd::parseTrackInfo Feb 07 21:11:18 volumio volumio[1481]: info: ControllerMpd::pushState Feb 07 21:11:18 volumio volumio[1481]: info: CoreCommandRouter::servicePushState Feb 07 21:11:18 volumio volumio[1481]: info: CorePlayQueue::getTrack 10 Feb 07 21:11:18 volumio volumio[1481]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":311,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1770477077~YTZmZmZjMjgyOTI5YTIwZDM5MWU1NmM4MDQ4NTJlZjAxOGJmZjVmYQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1MTAxZjQwNWZmN2RhNTI4MmU4Y2RlZGJkODNlZTFjMy5tcDQ/0.flac?token=1770477077~YTZmZmZjMjgyOTI5YTIwZDM5MWU1NmM4MDQ4NTJlZjAxOGJmZjVmYQ==","trackType":"tidal"} Feb 07 21:11:18 volumio volumio[1481]: verbose: CURRENT POSITION 10 Feb 07 21:11:18 volumio volumio[1481]: info: CoreStateMachine::syncState stateService play Feb 07 21:11:18 volumio volumio[1481]: info: CoreStateMachine::syncState currentStatus play Feb 07 21:11:18 volumio volumio[1481]: info: Received an update from plugin. extracting info from payload Feb 07 21:11:18 volumio volumio[1481]: info: CoreStateMachine::pushState Feb 07 21:11:18 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 07 21:11:18 volumio volumio[1481]: info: CoreCommandRouter::volumioPushState Feb 07 21:11:18 volumio volumio[1481]: info: MRS: Pushing multiroomSync output update for this device Feb 07 21:11:18 volumio volumio[1481]: info: MRS: Pushing multiroomSync output Feb 07 21:11:18 volumio volumio[1481]: info: CoreCommandRouter::volumioGetState Feb 07 21:11:18 volumio volumio[1481]: info: CoreStateMachine::pushState Feb 07 21:11:18 volumio volumio[1481]: info: CoreCommandRouter::volumioPushState Feb 07 21:11:18 volumio volumio[1481]: info: MRS: Pushing multiroomSync output update for this device Feb 07 21:11:18 volumio volumio[1481]: info: MRS: Pushing multiroomSync output Feb 07 21:11:18 volumio volumio[1481]: info: CoreCommandRouter::volumioGetState Feb 07 21:11:18 volumio volumio[1481]: info: ------------------------------ 26ms Feb 07 21:11:18 volumio volumio[1481]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 07 21:11:18 volumio volumio[1481]: info: PeppyMeterBasic ---peppymeterbasic status play Feb 07 21:11:18 volumio volumio[1481]: info: PeppySpectrum ---peppyspectrum status play Feb 07 21:11:18 volumio sudo[5039]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 07 21:11:18 volumio sudo[5039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 21:11:18 volumio volumio[1481]: info: PeppySpectrum ---peppyspectrum status play Feb 07 21:11:18 volumio sudo[5041]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Feb 07 21:11:18 volumio sudo[5041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 21:11:18 volumio sudo[5044]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 07 21:11:18 volumio sudo[5044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 21:11:18 volumio sudo[5048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 07 21:11:18 volumio sudo[5048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 21:11:18 volumio volumio[1481]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 07 21:11:18 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Feb 07 21:11:18 volumio sudo[5041]: pam_unix(sudo:session): session closed for user root Feb 07 21:11:18 volumio sudo[5039]: pam_unix(sudo:session): session closed for user root Feb 07 21:11:18 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Feb 07 21:11:18 volumio volumio[1481]: info: peppymeterbasic Daemon Started Feb 07 21:11:18 volumio sudo[5044]: pam_unix(sudo:session): session closed for user root Feb 07 21:11:18 volumio volumio[1481]: info: peppymeterbasic Daemon Started Feb 07 21:11:18 volumio sudo[5048]: pam_unix(sudo:session): session closed for user root Feb 07 21:11:18 volumio volumio[1481]: info: peppyspectrum Daemon Started Feb 07 21:11:18 volumio volumio[1481]: info: peppyspectrum Daemon Started Feb 07 21:11:18 volumio systemd[1]: peppyspectrum.service: Deactivated successfully. Feb 07 21:11:18 volumio volumio[5055]: libpng warning: iCCP: known incorrect sRGB profile Feb 07 21:11:18 volumio volumio[5055]: libpng warning: iCCP: known incorrect sRGB profile Feb 07 21:12:04 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 07 21:12:04 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 07 21:12:04 volumio sudo[5143]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 21:12:04 volumio sudo[5143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 21:12:04 volumio sudo[5143]: pam_unix(sudo:session): session closed for user root Feb 07 21:12:04 volumio sudo[5145]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 21:12:04 volumio sudo[5145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 21:12:04 volumio sudo[5145]: pam_unix(sudo:session): session closed for user root Feb 07 21:12:04 volumio volumio[1481]: verbose: New Socket.io Connection to 192.168.50.224 from 192.168.50.127 UA: Mozilla/5.0 (Linux; Android 16; V2413 Build/BQ2A.250610.001-BP2A.250605.031.A3_V000L1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Feb 07 21:12:04 volumio sudo[5149]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 21:12:04 volumio sudo[5149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 21:12:04 volumio sudo[5149]: pam_unix(sudo:session): session closed for user root Feb 07 21:12:04 volumio sudo[5151]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 21:12:04 volumio sudo[5151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 21:12:04 volumio sudo[5151]: pam_unix(sudo:session): session closed for user root Feb 07 21:12:05 volumio volumio[1481]: verbose: New Socket.io Connection to 192.168.50.224 from 192.168.50.127 UA: Mozilla/5.0 (Linux; Android 16; V2413 Build/BQ2A.250610.001-BP2A.250605.031.A3_V000L1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Feb 07 21:12:05 volumio volumio[1481]: info: CoreCommandRouter::volumioGetState Feb 07 21:12:05 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 07 21:12:05 volumio volumio[1481]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 07 21:12:05 volumio volumio[1481]: info: Listing playlists Feb 07 21:12:05 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 07 21:12:05 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 07 21:12:05 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 07 21:12:05 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 07 21:12:06 volumio volumio[1481]: info: CoreCommandRouter::volumioGetVisibleSources Feb 07 21:12:06 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 07 21:12:06 volumio volumio[1481]: info: CoreCommandRouter::volumioGetQueue Feb 07 21:12:06 volumio volumio[1481]: info: CoreStateMachine::getQueue Feb 07 21:12:06 volumio volumio[1481]: info: CorePlayQueue::getQueue Feb 07 21:12:08 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Feb 07 21:12:08 volumio volumio[1481]: info: browseTIDALUri took 144 milliseconds Feb 07 21:12:08 volumio volumio[1481]: info: Preload queue cleared Feb 07 21:12:08 volumio volumio[1481]: info: Preload queue cleared Feb 07 21:12:23 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 07 21:12:23 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 07 21:12:24 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 07 21:12:24 volumio volumio[1481]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 07 21:12:24 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 07 21:12:24 volumio volumio[1481]: info: Received Get System Version Feb 07 21:12:24 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 07 21:12:24 volumio volumio[1481]: info: Received Get System Info Feb 07 21:12:24 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 21:12:24 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 21:12:24 volumio volumio[1481]: info: Discovery: Getting this device information Feb 07 21:12:24 volumio volumio[1481]: info: CoreCommandRouter::volumioGetState Feb 07 21:12:24 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 21:12:24 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 07 21:12:24 volumio volumio[1481]: info: Received Get System Info Feb 07 21:12:24 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 07 21:12:24 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 07 21:12:24 volumio volumio[1481]: info: Discovery: Getting this device information Feb 07 21:12:24 volumio volumio[1481]: info: CoreCommandRouter::volumioGetState Feb 07 21:12:24 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 07 21:12:27 volumio volumio[1481]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 07 21:12:33 volumio volumio[1481]: info: Received OAUTH Data Feb 07 21:12:33 volumio volumio[1481]: info: Executing Spotify Oauth Login Feb 07 21:12:33 volumio volumio[1481]: info: Saving Spotify Refresh Token Feb 07 21:12:33 volumio sudo[5207]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 07 21:12:33 volumio sudo[5207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 21:12:33 volumio sudo[5207]: pam_unix(sudo:session): session closed for user root Feb 07 21:12:33 volumio sudo[5209]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 07 21:12:33 volumio sudo[5209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 21:12:33 volumio sudo[5209]: pam_unix(sudo:session): session closed for user root Feb 07 21:12:33 volumio volumio[1481]: verbose: New Socket.io Connection to 192.168.50.224 from 192.168.50.127 UA: Mozilla/5.0 (Linux; Android 16; V2413 Build/BQ2A.250610.001-BP2A.250605.031.A3_V000L1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.109 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Feb 07 21:12:33 volumio volumio[1481]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 07 21:12:33 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 07 21:12:33 volumio volumio[1481]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 07 21:12:33 volumio volumio[1481]: info: CoreCommandRouter::volumioGetState Feb 07 21:12:33 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 07 21:12:33 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 07 21:12:33 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 07 21:12:33 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 07 21:12:33 volumio volumio[1481]: info: New Spotify access tokenBQA1S4GPoM... Feb 07 21:12:33 volumio volumio[1481]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 07 21:12:33 volumio volumio[1481]: SPOTIFY: User informations: {"country":"TH","display_name":"เต้ยโศก","email":"mueller.aon74@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31zdrdvtbtdie26smwkiicilvcdu"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31zdrdvtbtdie26smwkiicilvcdu","id":"31zdrdvtbtdie26smwkiicilvcdu","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee8567a8466589c2ec16f81d6ee5","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b8267a8466589c2ec16f81d6ee5","width":64}],"product":"premium","type":"user","uri":"spotify:user:31zdrdvtbtdie26smwkiicilvcdu"} Feb 07 21:12:33 volumio volumio[1481]: info: Creating Spotify config file Feb 07 21:12:33 volumio volumio[1481]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 07 21:12:33 volumio volumio[1481]: info: Spotify config file written Feb 07 21:12:33 volumio sudo[5214]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 07 21:12:33 volumio sudo[5214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 07 21:12:34 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 07 21:12:34 volumio systemd[1]: go-librespot-daemon.service: Killing process 2440 (go-librespot) with signal SIGKILL. Feb 07 21:12:34 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 07 21:12:34 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 07 21:12:34 volumio volumio[1481]: info: Connection to go-librespot Websocket closed Feb 07 21:12:34 volumio volumio[1481]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 21:12:34 volumio volumio[1481]: Error: socket hang up Feb 07 21:12:34 volumio volumio[1481]: at connResetException (node:internal/errors:720:14) Feb 07 21:12:34 volumio volumio[1481]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 07 21:12:34 volumio volumio[1481]: at Socket.emit (node:events:526:35) Feb 07 21:12:34 volumio volumio[1481]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 07 21:12:34 volumio volumio[1481]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 07 21:12:34 volumio volumio[1481]: code: 'ECONNRESET', Feb 07 21:12:34 volumio volumio[1481]: response: undefined Feb 07 21:12:34 volumio volumio[1481]: } Feb 07 21:12:34 volumio volumio[1481]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 21:12:34 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 07 21:12:34 volumio go-librespot[5216]: go-librespot daemon starting... Feb 07 21:12:34 volumio sudo[5214]: pam_unix(sudo:session): session closed for user root Feb 07 21:12:34 volumio go-librespot[5228]: time="2026-02-07T21:12:34+07:00" level=info msg="running go-librespot 0.6.2" Feb 07 21:12:34 volumio go-librespot[5228]: time="2026-02-07T21:12:34+07:00" level=debug msg="app state loaded" Feb 07 21:12:34 volumio go-librespot[5228]: time="2026-02-07T21:12:34+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 07 21:12:34 volumio go-librespot[5228]: time="2026-02-07T21:12:34+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 07 21:12:34 volumio go-librespot[5228]: time="2026-02-07T21:12:34+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 07 21:12:34 volumio go-librespot[5228]: time="2026-02-07T21:12:34+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 07 21:12:34 volumio go-librespot[5228]: time="2026-02-07T21:12:34+07:00" level=info msg="zeroconf server listening on port 42987" Feb 07 21:12:34 volumio sudo[5241]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-07 21:11' Feb 07 21:12:34 volumio sudo[5241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"