Jun 28 17:30:07 volumio volumio[1435]: info: CorePlayQueue::getTrack 0 Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: tidal , goto Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::volumioGetState Jun 28 17:30:07 volumio volumio[1435]: info: Listing playlists Jun 28 17:30:07 volumio volumio[1435]: info: Listing playlists Jun 28 17:30:07 volumio volumio[1435]: info: explodeTIDALUri took 75 milliseconds Jun 28 17:30:07 volumio volumio[1435]: info: browseTIDALUri took 35 milliseconds Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint getSimilarAlbums Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint getSimilarAlbums Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint getSimilarAlbums Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint getSimilarAlbums Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint getSimilarAlbums Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint getSimilarAlbums Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint getSimilarAlbums Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:07 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:07 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:12 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 28 17:30:12 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:12 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:12 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:12 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:14 volumio volumio[1435]: error: Failed request for metavolumio API Jun 28 17:30:14 volumio volumio[1435]: error: Failed request for metavolumio API Jun 28 17:30:14 volumio volumio[1435]: error: Failed request for metavolumio API Jun 28 17:30:14 volumio volumio[1435]: error: Failed request for metavolumio API Jun 28 17:30:17 volumio volumio[1435]: info: CoreCommandRouter::volumioGetState Jun 28 17:30:17 volumio go-librespot[1827]: time="2025-06-28T17:30:17+02:00" level=trace msg="sent dealer ping" Jun 28 17:30:17 volumio go-librespot[1827]: time="2025-06-28T17:30:17+02:00" level=trace msg="received dealer pong" Jun 28 17:30:19 volumio volumio[1435]: error: Failed request for metavolumio API Jun 28 17:30:22 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:22 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:22 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:22 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:24 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 28 17:30:24 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 28 17:30:24 volumio volumio[1435]: info: Discovery: Getting this device information Jun 28 17:30:24 volumio volumio[1435]: info: CoreCommandRouter::volumioGetState Jun 28 17:30:24 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 28 17:30:27 volumio volumio[1435]: info: CoreCommandRouter::volumioGetState Jun 28 17:30:27 volumio volumio[1435]: info: Listing playlists Jun 28 17:30:27 volumio volumio[1435]: info: Listing playlists Jun 28 17:30:29 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:29 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:29 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:29 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:30 volumio volumio[1435]: error: Failed request for metavolumio API Jun 28 17:30:37 volumio volumio[1435]: info: CoreCommandRouter::volumioPrevious Jun 28 17:30:37 volumio volumio[1435]: info: CoreStateMachine::previous Jun 28 17:30:37 volumio volumio[1435]: info: [1751124637113] ControllerTidal::seek Jun 28 17:30:37 volumio volumio[1435]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 28 17:30:37 volumio volumio[1435]: info: ControllerMpd::seek Jun 28 17:30:37 volumio volumio[1435]: error: null Jun 28 17:30:37 volumio volumio[1435]: info: Jun 28 17:30:37 volumio volumio[1435]: ---------------------------- MPD announces state update: player Jun 28 17:30:37 volumio volumio[1435]: info: ControllerMpd::getState Jun 28 17:30:37 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand status Jun 28 17:30:37 volumio volumio[1435]: info: sendMpdCommand status took 1 milliseconds Jun 28 17:30:37 volumio volumio[1435]: verbose: ControllerMpd::parseState Jun 28 17:30:37 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 17:30:37 volumio volumio[1435]: info: Jun 28 17:30:37 volumio volumio[1435]: ---------------------------- MPD announces state update: player Jun 28 17:30:37 volumio volumio[1435]: info: ControllerMpd::getState Jun 28 17:30:37 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand status Jun 28 17:30:37 volumio volumio[1435]: info: Jun 28 17:30:37 volumio volumio[1435]: ---------------------------- MPD announces state update: player Jun 28 17:30:37 volumio volumio[1435]: info: ControllerMpd::getState Jun 28 17:30:37 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand status Jun 28 17:30:37 volumio volumio[1435]: info: Jun 28 17:30:37 volumio volumio[1435]: ---------------------------- MPD announces state update: player Jun 28 17:30:37 volumio volumio[1435]: info: ControllerMpd::getState Jun 28 17:30:37 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand status Jun 28 17:30:37 volumio volumio[1435]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 28 17:30:37 volumio volumio[1435]: info: sendMpdCommand status took 3 milliseconds Jun 28 17:30:37 volumio volumio[1435]: info: sendMpdCommand status took 3 milliseconds Jun 28 17:30:37 volumio volumio[1435]: info: sendMpdCommand status took 3 milliseconds Jun 28 17:30:37 volumio volumio[1435]: verbose: ControllerMpd::parseTrackInfo Jun 28 17:30:37 volumio volumio[1435]: verbose: ControllerMpd::parseState Jun 28 17:30:37 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 17:30:37 volumio volumio[1435]: verbose: ControllerMpd::parseState Jun 28 17:30:37 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 17:30:37 volumio volumio[1435]: verbose: ControllerMpd::parseState Jun 28 17:30:37 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 17:30:37 volumio volumio[1435]: info: ControllerMpd::pushState Jun 28 17:30:37 volumio volumio[1435]: info: CoreCommandRouter::servicePushState Jun 28 17:30:37 volumio volumio[1435]: info: CorePlayQueue::getTrack 0 Jun 28 17:30:37 volumio volumio[1435]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"795 Kbps","isStreaming":false,"title":"0.flac?token=1751128182~YzkzOTVjODdmMzczZmRiYjZkZDE5MzE5NzQ0NmZkODc2MWE5MTZkMg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicwZjNhNWIxMzQ4ODg4ZTAyZGRlODUwY2UxODkzNWQxOV82MS5tcDQ/0.flac?token=1751128182~YzkzOTVjODdmMzczZmRiYjZkZDE5MzE5NzQ0NmZkODc2MWE5MTZkMg==","trackType":"tidal"} Jun 28 17:30:37 volumio volumio[1435]: verbose: CURRENT POSITION 0 Jun 28 17:30:37 volumio volumio[1435]: info: CoreStateMachine::syncState stateService play Jun 28 17:30:37 volumio volumio[1435]: info: CoreStateMachine::syncState currentStatus play Jun 28 17:30:37 volumio volumio[1435]: info: Received an update from plugin. extracting info from payload Jun 28 17:30:37 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:30:37 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 17:30:37 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:30:37 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:30:37 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:30:37 volumio volumio[1435]: info: ------------------------------ 13ms Jun 28 17:30:37 volumio volumio[1435]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 28 17:30:37 volumio volumio[1435]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 28 17:30:37 volumio volumio[1435]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 28 17:30:37 volumio volumio[1435]: verbose: ControllerMpd::parseTrackInfo Jun 28 17:30:37 volumio volumio[1435]: verbose: ControllerMpd::parseTrackInfo Jun 28 17:30:37 volumio volumio[1435]: verbose: ControllerMpd::parseTrackInfo Jun 28 17:30:37 volumio volumio[1435]: info: ControllerMpd::pushState Jun 28 17:30:37 volumio volumio[1435]: info: CoreCommandRouter::servicePushState Jun 28 17:30:37 volumio volumio[1435]: info: CorePlayQueue::getTrack 0 Jun 28 17:30:37 volumio volumio[1435]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"795 Kbps","isStreaming":false,"title":"0.flac?token=1751128182~YzkzOTVjODdmMzczZmRiYjZkZDE5MzE5NzQ0NmZkODc2MWE5MTZkMg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicwZjNhNWIxMzQ4ODg4ZTAyZGRlODUwY2UxODkzNWQxOV82MS5tcDQ/0.flac?token=1751128182~YzkzOTVjODdmMzczZmRiYjZkZDE5MzE5NzQ0NmZkODc2MWE5MTZkMg==","trackType":"tidal"} Jun 28 17:30:37 volumio volumio[1435]: verbose: CURRENT POSITION 0 Jun 28 17:30:37 volumio volumio[1435]: info: CoreStateMachine::syncState stateService play Jun 28 17:30:37 volumio volumio[1435]: info: CoreStateMachine::syncState currentStatus play Jun 28 17:30:37 volumio volumio[1435]: info: Received an update from plugin. extracting info from payload Jun 28 17:30:37 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:30:37 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:30:37 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:30:37 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:30:37 volumio volumio[1435]: info: ControllerMpd::pushState Jun 28 17:30:37 volumio volumio[1435]: info: CoreCommandRouter::servicePushState Jun 28 17:30:37 volumio volumio[1435]: info: CorePlayQueue::getTrack 0 Jun 28 17:30:37 volumio volumio[1435]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"795 Kbps","isStreaming":false,"title":"0.flac?token=1751128182~YzkzOTVjODdmMzczZmRiYjZkZDE5MzE5NzQ0NmZkODc2MWE5MTZkMg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicwZjNhNWIxMzQ4ODg4ZTAyZGRlODUwY2UxODkzNWQxOV82MS5tcDQ/0.flac?token=1751128182~YzkzOTVjODdmMzczZmRiYjZkZDE5MzE5NzQ0NmZkODc2MWE5MTZkMg==","trackType":"tidal"} Jun 28 17:30:37 volumio volumio[1435]: verbose: CURRENT POSITION 0 Jun 28 17:30:37 volumio volumio[1435]: info: CoreStateMachine::syncState stateService play Jun 28 17:30:37 volumio volumio[1435]: info: CoreStateMachine::syncState currentStatus play Jun 28 17:30:37 volumio volumio[1435]: info: Received an update from plugin. extracting info from payload Jun 28 17:30:37 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:30:37 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:30:37 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:30:37 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:30:37 volumio volumio[1435]: info: ControllerMpd::pushState Jun 28 17:30:37 volumio volumio[1435]: info: CoreCommandRouter::servicePushState Jun 28 17:30:37 volumio volumio[1435]: info: CorePlayQueue::getTrack 0 Jun 28 17:30:37 volumio volumio[1435]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"795 Kbps","isStreaming":false,"title":"0.flac?token=1751128182~YzkzOTVjODdmMzczZmRiYjZkZDE5MzE5NzQ0NmZkODc2MWE5MTZkMg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicwZjNhNWIxMzQ4ODg4ZTAyZGRlODUwY2UxODkzNWQxOV82MS5tcDQ/0.flac?token=1751128182~YzkzOTVjODdmMzczZmRiYjZkZDE5MzE5NzQ0NmZkODc2MWE5MTZkMg==","trackType":"tidal"} Jun 28 17:30:37 volumio volumio[1435]: verbose: CURRENT POSITION 0 Jun 28 17:30:37 volumio volumio[1435]: info: CoreStateMachine::syncState stateService play Jun 28 17:30:37 volumio volumio[1435]: info: CoreStateMachine::syncState currentStatus play Jun 28 17:30:37 volumio volumio[1435]: info: Received an update from plugin. extracting info from payload Jun 28 17:30:37 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:30:37 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:30:37 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:30:37 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:30:37 volumio volumio[1435]: info: ------------------------------ 28ms Jun 28 17:30:37 volumio volumio[1435]: info: ------------------------------ 28ms Jun 28 17:30:37 volumio volumio[1435]: info: ------------------------------ 27ms Jun 28 17:30:37 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:30:37 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:30:37 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:30:37 volumio sudo[12577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:30:37 volumio sudo[12577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:30:37 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:30:37 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:30:37 volumio sudo[12579]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:30:37 volumio sudo[12579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:30:37 volumio sudo[12577]: pam_unix(sudo:session): session closed for user root Jun 28 17:30:37 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:30:37 volumio sudo[12582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:30:37 volumio sudo[12582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:30:37 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:30:37 volumio sudo[12584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:30:37 volumio sudo[12584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:30:37 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:30:37 volumio sudo[12587]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:30:37 volumio sudo[12590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:30:37 volumio sudo[12587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:30:37 volumio sudo[12590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:30:37 volumio sudo[12579]: pam_unix(sudo:session): session closed for user root Jun 28 17:30:37 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:30:37 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:30:37 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:30:37 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:30:37 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:30:37 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:30:37 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:30:37 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:30:37 volumio sudo[12593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:30:37 volumio sudo[12593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:30:37 volumio sudo[12597]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:30:37 volumio sudo[12597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:30:37 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:30:37 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:30:37 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:30:37 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:30:37 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:30:37 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:30:37 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:30:37 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:30:37 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:30:37 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:30:37 volumio volumio[1435]: info: CoreCommandRouter::volumioGetState Jun 28 17:30:37 volumio sudo[12597]: pam_unix(sudo:session): session closed for user root Jun 28 17:30:37 volumio sudo[12590]: pam_unix(sudo:session): session closed for user root Jun 28 17:30:37 volumio sudo[12587]: pam_unix(sudo:session): session closed for user root Jun 28 17:30:37 volumio sudo[12582]: pam_unix(sudo:session): session closed for user root Jun 28 17:30:37 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:30:37 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:30:37 volumio sudo[12584]: pam_unix(sudo:session): session closed for user root Jun 28 17:30:37 volumio sudo[12593]: pam_unix(sudo:session): session closed for user root Jun 28 17:30:37 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:30:37 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:30:37 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:30:37 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:30:40 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:40 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:40 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:30:40 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:30:44 volumio volumio[1435]: Searching all installed plugins Jun 28 17:30:44 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 17:30:44 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: , search Jun 28 17:30:44 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 28 17:30:44 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jun 28 17:30:44 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jun 28 17:30:44 volumio volumio[1435]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jun 28 17:30:44 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 28 17:30:44 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: spop , search Jun 28 17:30:44 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 28 17:30:44 volumio volumio[1435]: info: searchTIDALUri took 74 milliseconds Jun 28 17:30:44 volumio volumio[1435]: info: search took 76 milliseconds Jun 28 17:30:46 volumio volumio[1435]: info: Une erreur s'est produite lors de l'accès à SHOUTCAST Jun 28 17:30:46 volumio volumio[1435]: info: All search sources collected, pushing search results Jun 28 17:30:47 volumio volumio[1435]: info: CoreCommandRouter::volumioGetState Jun 28 17:30:47 volumio volumio[1435]: info: Listing playlists Jun 28 17:30:47 volumio volumio[1435]: info: Listing playlists Jun 28 17:30:47 volumio go-librespot[1827]: time="2025-06-28T17:30:47+02:00" level=trace msg="sent dealer ping" Jun 28 17:30:47 volumio go-librespot[1827]: time="2025-06-28T17:30:47+02:00" level=trace msg="received dealer pong" Jun 28 17:30:54 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 28 17:30:54 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 28 17:30:54 volumio volumio[1435]: info: Discovery: Getting this device information Jun 28 17:30:54 volumio volumio[1435]: info: CoreCommandRouter::volumioGetState Jun 28 17:30:54 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 28 17:30:57 volumio volumio[1435]: info: CoreCommandRouter::volumioGetState Jun 28 17:30:59 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 28 17:30:59 volumio dbus-daemon[881]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.103' (uid=0 pid=12631 comm="timedatectl show --property=NTPSynchronized --valu") Jun 28 17:30:59 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 28 17:30:59 volumio dbus-daemon[881]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 28 17:30:59 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 28 17:30:59 volumio setdatetime-helper.sh[12630]: Time is already synchronized. Jun 28 17:30:59 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 28 17:30:59 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 28 17:31:07 volumio volumio[1435]: info: CoreCommandRouter::volumioGetState Jun 28 17:31:07 volumio volumio[1435]: info: Listing playlists Jun 28 17:31:07 volumio volumio[1435]: info: Listing playlists Jun 28 17:31:17 volumio volumio[1435]: info: CoreCommandRouter::volumioGetState Jun 28 17:31:17 volumio go-librespot[1827]: time="2025-06-28T17:31:17+02:00" level=trace msg="sent dealer ping" Jun 28 17:31:17 volumio go-librespot[1827]: time="2025-06-28T17:31:17+02:00" level=trace msg="received dealer pong" Jun 28 17:31:19 volumio volumio[1435]: info: Preload queue cleared Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::ClearQueue Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::stop Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::stPlaybackTimer Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::updateTrackBlock Jun 28 17:31:19 volumio volumio[1435]: info: CorePlayQueue::getTrackBlock Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:19 volumio volumio[1435]: info: CorePlayQueue::getTrack 0 Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::serviceStop Jun 28 17:31:19 volumio volumio[1435]: info: CorePlayQueue::getTrack 0 Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::serviceStop Jun 28 17:31:19 volumio volumio[1435]: info: [1751124679105] ControllerTidal::stop Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 28 17:31:19 volumio volumio[1435]: info: ControllerMpd::stop Jun 28 17:31:19 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand stop Jun 28 17:31:19 volumio volumio[1435]: info: CorePlayQueue::clearPlayQueue Jun 28 17:31:19 volumio volumio[1435]: info: CorePlayQueue::saveQueue Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::volumioPushQueue Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::addQueueItems Jun 28 17:31:19 volumio volumio[1435]: info: CorePlayQueue::addQueueItems Jun 28 17:31:19 volumio volumio[1435]: info: Preload queue cleared Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/92354194 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/92354194 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/2570244 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/2570244 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/2570242 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/2570242 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/2570249 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/2570249 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/365301349 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/365301349 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/2530225 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/2530225 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/2570246 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/2570246 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/321635714 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/321635714 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/416609397 Jun 28 17:31:19 volumio volumio[1435]: info: Using cached record of: tidal://song/416609397 Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/430794749 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/430794749 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/153488122 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/153488122 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/430794750 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/430794750 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/109860373 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/109860373 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/430794751 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/430794751 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/430794753 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/430794753 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/430794752 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/430794752 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/92354187 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/92354187 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 28 17:31:19 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Volumio is not playing Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Clipped samples monitor stopped Jun 28 17:31:19 volumio volumio[1435]: info: Jun 28 17:31:19 volumio volumio[1435]: ---------------------------- MPD announces state update: player Jun 28 17:31:19 volumio volumio[1435]: info: sendMpdCommand stop took 74 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: ControllerMpd::getState Jun 28 17:31:19 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand status Jun 28 17:31:19 volumio volumio[1435]: info: Jun 28 17:31:19 volumio volumio[1435]: ---------------------------- MPD announces state update: player Jun 28 17:31:19 volumio volumio[1435]: info: ControllerMpd::getState Jun 28 17:31:19 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand status Jun 28 17:31:19 volumio volumio[1435]: info: Jun 28 17:31:19 volumio volumio[1435]: ---------------------------- MPD announces state update: player Jun 28 17:31:19 volumio volumio[1435]: info: ControllerMpd::getState Jun 28 17:31:19 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand status Jun 28 17:31:19 volumio sudo[12665]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 28 17:31:19 volumio sudo[12665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:19 volumio volumio[1435]: info: Jun 28 17:31:19 volumio volumio[1435]: ---------------------------- MPD announces state update: player Jun 28 17:31:19 volumio volumio[1435]: info: ControllerMpd::getState Jun 28 17:31:19 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand status Jun 28 17:31:19 volumio volumio[1435]: info: sendMpdCommand status took 6 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: sendMpdCommand status took 8 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: sendMpdCommand status took 7 milliseconds Jun 28 17:31:19 volumio volumio[1435]: verbose: ControllerMpd::parseState Jun 28 17:31:19 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 17:31:19 volumio volumio[1435]: verbose: ControllerMpd::parseState Jun 28 17:31:19 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 17:31:19 volumio volumio[1435]: verbose: ControllerMpd::parseState Jun 28 17:31:19 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 17:31:19 volumio volumio[1435]: info: sendMpdCommand status took 12 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 28 17:31:19 volumio volumio[1435]: verbose: ControllerMpd::parseState Jun 28 17:31:19 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 17:31:19 volumio volumio[1435]: verbose: ControllerMpd::parseTrackInfo Jun 28 17:31:19 volumio volumio[1435]: verbose: ControllerMpd::parseTrackInfo Jun 28 17:31:19 volumio volumio[1435]: verbose: ControllerMpd::parseTrackInfo Jun 28 17:31:19 volumio volumio[1435]: info: ControllerMpd::pushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::servicePushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 17:31:19 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:19 volumio volumio[1435]: info: CorePlayQueue::getTrack 0 Jun 28 17:31:19 volumio volumio[1435]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1751128182~YzkzOTVjODdmMzczZmRiYjZkZDE5MzE5NzQ0NmZkODc2MWE5MTZkMg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicwZjNhNWIxMzQ4ODg4ZTAyZGRlODUwY2UxODkzNWQxOV82MS5tcDQ/0.flac?token=1751128182~YzkzOTVjODdmMzczZmRiYjZkZDE5MzE5NzQ0NmZkODc2MWE5MTZkMg==","trackType":"tidal"} Jun 28 17:31:19 volumio volumio[1435]: verbose: CURRENT POSITION 0 Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::syncState stateService stop Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::syncState currentStatus stop Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:19 volumio volumio[1435]: info: No code Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:19 volumio volumio[1435]: info: ControllerMpd::pushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::servicePushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:19 volumio volumio[1435]: info: CorePlayQueue::getTrack 0 Jun 28 17:31:19 volumio volumio[1435]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1751128182~YzkzOTVjODdmMzczZmRiYjZkZDE5MzE5NzQ0NmZkODc2MWE5MTZkMg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicwZjNhNWIxMzQ4ODg4ZTAyZGRlODUwY2UxODkzNWQxOV82MS5tcDQ/0.flac?token=1751128182~YzkzOTVjODdmMzczZmRiYjZkZDE5MzE5NzQ0NmZkODc2MWE5MTZkMg==","trackType":"tidal"} Jun 28 17:31:19 volumio volumio[1435]: verbose: CURRENT POSITION 0 Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::syncState stateService stop Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::syncState currentStatus stop Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:19 volumio volumio[1435]: info: No code Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:19 volumio volumio[1435]: info: ControllerMpd::pushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::servicePushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:19 volumio volumio[1435]: info: CorePlayQueue::getTrack 0 Jun 28 17:31:19 volumio volumio[1435]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1751128182~YzkzOTVjODdmMzczZmRiYjZkZDE5MzE5NzQ0NmZkODc2MWE5MTZkMg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicwZjNhNWIxMzQ4ODg4ZTAyZGRlODUwY2UxODkzNWQxOV82MS5tcDQ/0.flac?token=1751128182~YzkzOTVjODdmMzczZmRiYjZkZDE5MzE5NzQ0NmZkODc2MWE5MTZkMg==","trackType":"tidal"} Jun 28 17:31:19 volumio volumio[1435]: verbose: CURRENT POSITION 0 Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::syncState stateService stop Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::syncState currentStatus stop Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:19 volumio volumio[1435]: info: No code Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:19 volumio volumio[1435]: info: ------------------------------ 49ms Jun 28 17:31:19 volumio volumio[1435]: info: ------------------------------ 48ms Jun 28 17:31:19 volumio volumio[1435]: info: ------------------------------ 47ms Jun 28 17:31:19 volumio volumio[1435]: info: sendMpdCommand playlistinfo took 41 milliseconds Jun 28 17:31:19 volumio volumio[1435]: verbose: ControllerMpd::parseTrackInfo Jun 28 17:31:19 volumio volumio[1435]: info: ControllerMpd::pushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::servicePushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:19 volumio volumio[1435]: info: CorePlayQueue::getTrack 0 Jun 28 17:31:19 volumio volumio[1435]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1751128182~YzkzOTVjODdmMzczZmRiYjZkZDE5MzE5NzQ0NmZkODc2MWE5MTZkMg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicwZjNhNWIxMzQ4ODg4ZTAyZGRlODUwY2UxODkzNWQxOV82MS5tcDQ/0.flac?token=1751128182~YzkzOTVjODdmMzczZmRiYjZkZDE5MzE5NzQ0NmZkODc2MWE5MTZkMg==","trackType":"tidal"} Jun 28 17:31:19 volumio volumio[1435]: verbose: CURRENT POSITION 0 Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::syncState stateService stop Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::syncState currentStatus stop Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:19 volumio volumio[1435]: info: No code Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:19 volumio volumio[1435]: info: ------------------------------ 68ms Jun 28 17:31:19 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:19 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:19 volumio sudo[12670]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:19 volumio sudo[12670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:19 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:19 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:19 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:19 volumio sudo[12675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:19 volumio sudo[12675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:19 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:19 volumio sudo[12665]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:19 volumio sudo[12680]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:19 volumio sudo[12680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:19 volumio sudo[12677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:19 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:19 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Jun 28 17:31:19 volumio sudo[12677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:19 volumio systemd[1]: peppymeterbasic.service: Consumed 1min 19.945s CPU time. Jun 28 17:31:19 volumio sudo[12673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:19 volumio sudo[12673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:19 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:19 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:19 volumio sudo[12685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:19 volumio sudo[12685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:19 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:19 volumio sudo[12686]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:19 volumio sudo[12686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:19 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:19 volumio sudo[12689]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:19 volumio sudo[12689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:19 volumio sudo[12691]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:19 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:19 volumio sudo[12691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:19 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:19 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:19 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:19 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:19 volumio sudo[12697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:19 volumio sudo[12697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:19 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:19 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:19 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:19 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:19 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:19 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:19 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:19 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:19 volumio sudo[12698]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:19 volumio sudo[12698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:19 volumio sudo[12701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:19 volumio sudo[12701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:19 volumio volumio[1435]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:19 volumio volumio[1435]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 28 17:31:19 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Jun 28 17:31:19 volumio sudo[12697]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:19 volumio sudo[12673]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:19 volumio sudo[12680]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:19 volumio sudo[12685]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:19 volumio sudo[12675]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:19 volumio sudo[12670]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:19 volumio sudo[12686]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:19 volumio sudo[12701]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:19 volumio sudo[12677]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:19 volumio sudo[12689]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:19 volumio sudo[12691]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:19 volumio sudo[12698]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:19 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppymeterbasic.service Jun 28 17:31:19 volumio volumio[1435]: Job for peppymeterbasic.service canceled. Jun 28 17:31:19 volumio volumio[1435]: info: explodeTIDALUri took 493 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: explodeTIDALUri took 489 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: explodeTIDALUri took 493 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: explodeTIDALUri took 495 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: explodeTIDALUri took 496 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:19 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:19 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:19 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:19 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:19 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:19 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:19 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:19 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:19 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:19 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:19 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:19 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:19 volumio volumio[1435]: info: explodeTIDALUri took 510 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: explodeTIDALUri took 521 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: explodeTIDALUri took 541 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: explodeTIDALUri took 548 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: explodeTIDALUri took 563 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: explodeTIDALUri took 603 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: explodeTIDALUri took 607 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: explodeTIDALUri took 625 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: explodeTIDALUri took 732 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: explodeTIDALUri took 758 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: explodeTIDALUri took 769 milliseconds Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::volumioPushQueue Jun 28 17:31:19 volumio volumio[1435]: info: CorePlayQueue::saveQueue Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::updateTrackBlock Jun 28 17:31:19 volumio volumio[1435]: info: CorePlayQueue::getTrackBlock Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::volumioPlay Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::play index 16 Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::addQueueItems Jun 28 17:31:19 volumio volumio[1435]: info: CorePlayQueue::addQueueItems Jun 28 17:31:19 volumio volumio[1435]: info: Preload queue cleared Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/224206262 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/224206262 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/440320998 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/440320998 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: Adding Item to queue: tidal://song/160871351 Jun 28 17:31:19 volumio volumio[1435]: info: Exploding uri tidal://song/160871351 in service tidal Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::stop Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::play index undefined Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 17:31:19 volumio volumio[1435]: info: CorePlayQueue::getTrack 16 Jun 28 17:31:19 volumio volumio[1435]: info: CoreStateMachine::startPlaybackTimer Jun 28 17:31:19 volumio volumio[1435]: info: CorePlayQueue::getTrack 16 Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::volumioGetVisibleSources Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 17:31:19 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 28 17:31:19 volumio volumio[1435]: info: [1751124679885] ControllerTidal::clearAddPlayTrack Jun 28 17:31:19 volumio volumio[1435]: info: Getting stream with soundQuality HI_RES Jun 28 17:31:19 volumio volumio[12706]: failed to open /dev/dri/renderD128: Permission denied Jun 28 17:31:19 volumio volumio[12706]: glx: failed to create dri3 screen Jun 28 17:31:19 volumio volumio[12706]: failed to load driver: vc4 Jun 28 17:31:19 volumio volumio[12706]: failed to open /dev/dri/renderD128: Permission denied Jun 28 17:31:19 volumio volumio[12706]: glx: failed to create dri2 screen Jun 28 17:31:19 volumio volumio[12706]: failed to load driver: vc4 Jun 28 17:31:20 volumio volumio[1435]: info: explodeTIDALUri took 120 milliseconds Jun 28 17:31:20 volumio volumio[1435]: info: explodeTIDALUri took 126 milliseconds Jun 28 17:31:20 volumio volumio[1435]: info: explodeTIDALUri took 133 milliseconds Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::volumioPushQueue Jun 28 17:31:20 volumio volumio[1435]: info: CorePlayQueue::saveQueue Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::updateTrackBlock Jun 28 17:31:20 volumio volumio[1435]: info: CorePlayQueue::getTrackBlock Jun 28 17:31:20 volumio volumio[12706]: failed to open /dev/dri/renderD128: Permission denied Jun 28 17:31:20 volumio volumio[12706]: glx: failed to create dri3 screen Jun 28 17:31:20 volumio volumio[12706]: failed to load driver: vc4 Jun 28 17:31:20 volumio volumio[1435]: info: getStreamUrl took 157 milliseconds Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand stop Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand stop took 1 milliseconds Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand clear Jun 28 17:31:20 volumio volumio[1435]: info: Jun 28 17:31:20 volumio volumio[1435]: ---------------------------- MPD announces system playlist update Jun 28 17:31:20 volumio volumio[1435]: info: Ignoring MPD Status Update Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand clear took 0 milliseconds Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1OGE1NGI4ZTcxODI3MGY1YmExOTc3OTYxY2NiZjBmNi5tcDQ/0.flac?token=1751128279~ZGYxNmUwNGRkOTJlMjg3ZmY4OTUyODJkY2VkN2E5ZmEwMTJjZDk2Yw==" Jun 28 17:31:20 volumio volumio[1435]: info: Jun 28 17:31:20 volumio volumio[1435]: ---------------------------- MPD announces system playlist update Jun 28 17:31:20 volumio volumio[1435]: info: Ignoring MPD Status Update Jun 28 17:31:20 volumio volumio[1435]: error: updateQueue error: null Jun 28 17:31:20 volumio volumio[1435]: info: Jun 28 17:31:20 volumio volumio[1435]: ---------------------------- MPD announces system playlist update Jun 28 17:31:20 volumio volumio[1435]: info: Ignoring MPD Status Update Jun 28 17:31:20 volumio volumio[1435]: info: ------------------------------ 0ms Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1OGE1NGI4ZTcxODI3MGY1YmExOTc3OTYxY2NiZjBmNi5tcDQ/0.flac?token=1751128279~ZGYxNmUwNGRkOTJlMjg3ZmY4OTUyODJkY2VkN2E5ZmEwMTJjZDk2Yw==" took 0 milliseconds Jun 28 17:31:20 volumio volumio[1435]: info: ------------------------------ 1ms Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand play Jun 28 17:31:20 volumio volumio[1435]: info: Jun 28 17:31:20 volumio volumio[1435]: ---------------------------- MPD announces system playlist update Jun 28 17:31:20 volumio volumio[1435]: info: Ignoring MPD Status Update Jun 28 17:31:20 volumio volumio[1435]: info: Jun 28 17:31:20 volumio volumio[1435]: ---------------------------- MPD announces system playlist update Jun 28 17:31:20 volumio volumio[1435]: info: Ignoring MPD Status Update Jun 28 17:31:20 volumio volumio[1435]: info: Jun 28 17:31:20 volumio volumio[1435]: ---------------------------- MPD announces system playlist update Jun 28 17:31:20 volumio volumio[1435]: info: Ignoring MPD Status Update Jun 28 17:31:20 volumio volumio[1435]: info: ------------------------------ 1ms Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand play took 1 milliseconds Jun 28 17:31:20 volumio volumio[1435]: info: ------------------------------ 1ms Jun 28 17:31:20 volumio volumio[1435]: info: ------------------------------ 1ms Jun 28 17:31:20 volumio volumio[1435]: info: ------------------------------ 1ms Jun 28 17:31:20 volumio volumio[1435]: info: Jun 28 17:31:20 volumio volumio[1435]: ---------------------------- MPD announces system playlist update Jun 28 17:31:20 volumio volumio[1435]: info: Ignoring MPD Status Update Jun 28 17:31:20 volumio volumio[1435]: info: Jun 28 17:31:20 volumio volumio[1435]: ---------------------------- MPD announces system playlist update Jun 28 17:31:20 volumio volumio[1435]: info: Ignoring MPD Status Update Jun 28 17:31:20 volumio volumio[1435]: info: ------------------------------ 1ms Jun 28 17:31:20 volumio volumio[1435]: info: ------------------------------ 1ms Jun 28 17:31:20 volumio volumio[12706]: failed to open /dev/dri/renderD128: Permission denied Jun 28 17:31:20 volumio volumio[12706]: glx: failed to create dri2 screen Jun 28 17:31:20 volumio volumio[12706]: failed to load driver: vc4 Jun 28 17:31:20 volumio volumio[1435]: info: Jun 28 17:31:20 volumio volumio[1435]: ---------------------------- MPD announces state update: player Jun 28 17:31:20 volumio volumio[1435]: info: ControllerMpd::getState Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand status Jun 28 17:31:20 volumio volumio[1435]: info: Jun 28 17:31:20 volumio volumio[1435]: ---------------------------- MPD announces state update: player Jun 28 17:31:20 volumio volumio[1435]: info: ControllerMpd::getState Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand status Jun 28 17:31:20 volumio volumio[1435]: info: Jun 28 17:31:20 volumio volumio[1435]: ---------------------------- MPD announces state update: player Jun 28 17:31:20 volumio volumio[1435]: info: ControllerMpd::getState Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand status Jun 28 17:31:20 volumio volumio[1435]: info: Jun 28 17:31:20 volumio volumio[1435]: ---------------------------- MPD announces state update: player Jun 28 17:31:20 volumio volumio[1435]: info: Jun 28 17:31:20 volumio volumio[1435]: ---------------------------- MPD announces state update: player Jun 28 17:31:20 volumio volumio[1435]: info: ControllerMpd::getState Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand status Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand status took 1 milliseconds Jun 28 17:31:20 volumio volumio[1435]: info: ControllerMpd::getState Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand status Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::parseState Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 17:31:20 volumio volumio[1435]: info: Jun 28 17:31:20 volumio volumio[1435]: ---------------------------- MPD announces state update: player Jun 28 17:31:20 volumio volumio[1435]: info: ControllerMpd::getState Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand status Jun 28 17:31:20 volumio volumio[1435]: info: Jun 28 17:31:20 volumio volumio[1435]: ---------------------------- MPD announces state update: player Jun 28 17:31:20 volumio volumio[1435]: info: ControllerMpd::getState Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand status Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand status took 6 milliseconds Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::parseState Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand status took 7 milliseconds Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand status took 6 milliseconds Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand status took 6 milliseconds Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand status took 6 milliseconds Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand status took 6 milliseconds Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::parseState Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::parseState Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::parseState Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::parseTrackInfo Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::parseState Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::parseState Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::parseTrackInfo Jun 28 17:31:20 volumio volumio[1435]: info: ControllerMpd::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::servicePushState Jun 28 17:31:20 volumio volumio[1435]: info: CorePlayQueue::getTrack 16 Jun 28 17:31:20 volumio volumio[1435]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1751128279~ZGYxNmUwNGRkOTJlMjg3ZmY4OTUyODJkY2VkN2E5ZmEwMTJjZDk2Yw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1OGE1NGI4ZTcxODI3MGY1YmExOTc3OTYxY2NiZjBmNi5tcDQ/0.flac?token=1751128279~ZGYxNmUwNGRkOTJlMjg3ZmY4OTUyODJkY2VkN2E5ZmEwMTJjZDk2Yw==","trackType":"tidal"} Jun 28 17:31:20 volumio volumio[1435]: verbose: CURRENT POSITION 16 Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::syncState stateService play Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::syncState currentStatus stop Jun 28 17:31:20 volumio volumio[1435]: info: ControllerMpd::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::servicePushState Jun 28 17:31:20 volumio volumio[1435]: info: CorePlayQueue::getTrack 16 Jun 28 17:31:20 volumio volumio[1435]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1751128279~ZGYxNmUwNGRkOTJlMjg3ZmY4OTUyODJkY2VkN2E5ZmEwMTJjZDk2Yw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1OGE1NGI4ZTcxODI3MGY1YmExOTc3OTYxY2NiZjBmNi5tcDQ/0.flac?token=1751128279~ZGYxNmUwNGRkOTJlMjg3ZmY4OTUyODJkY2VkN2E5ZmEwMTJjZDk2Yw==","trackType":"tidal"} Jun 28 17:31:20 volumio volumio[1435]: verbose: CURRENT POSITION 16 Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::syncState stateService play Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::syncState currentStatus play Jun 28 17:31:20 volumio volumio[1435]: info: Received an update from plugin. extracting info from payload Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:20 volumio volumio[12706]: Failed to create /home/volumio/.cache/mesa_shader_cache for shader cache (Permission denied)---disabling. Jun 28 17:31:20 volumio volumio[12706]: Failed to create /home/volumio/.cache/mesa_shader_cache_db for shader cache (Permission denied)---disabling. Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:20 volumio volumio[1435]: info: ------------------------------ 14ms Jun 28 17:31:20 volumio volumio[1435]: info: ------------------------------ 15ms Jun 28 17:31:20 volumio volumio[1435]: info: Jun 28 17:31:20 volumio volumio[1435]: ---------------------------- MPD announces state update: player Jun 28 17:31:20 volumio volumio[1435]: info: ControllerMpd::getState Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand status Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::parseTrackInfo Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::parseTrackInfo Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::parseTrackInfo Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::parseTrackInfo Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::parseTrackInfo Jun 28 17:31:20 volumio volumio[1435]: info: ControllerMpd::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::servicePushState Jun 28 17:31:20 volumio volumio[1435]: info: CorePlayQueue::getTrack 16 Jun 28 17:31:20 volumio volumio[1435]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1751128279~ZGYxNmUwNGRkOTJlMjg3ZmY4OTUyODJkY2VkN2E5ZmEwMTJjZDk2Yw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1OGE1NGI4ZTcxODI3MGY1YmExOTc3OTYxY2NiZjBmNi5tcDQ/0.flac?token=1751128279~ZGYxNmUwNGRkOTJlMjg3ZmY4OTUyODJkY2VkN2E5ZmEwMTJjZDk2Yw==","trackType":"tidal"} Jun 28 17:31:20 volumio volumio[1435]: verbose: CURRENT POSITION 16 Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::syncState stateService play Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::syncState currentStatus play Jun 28 17:31:20 volumio volumio[1435]: info: Received an update from plugin. extracting info from payload Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:20 volumio volumio[1435]: info: ControllerMpd::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::servicePushState Jun 28 17:31:20 volumio volumio[1435]: info: CorePlayQueue::getTrack 16 Jun 28 17:31:20 volumio volumio[1435]: verbose: STATE SERVICE {"status":"play","position":0,"seek":275,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"400 Kbps","isStreaming":false,"title":"0.flac?token=1751128279~ZGYxNmUwNGRkOTJlMjg3ZmY4OTUyODJkY2VkN2E5ZmEwMTJjZDk2Yw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1OGE1NGI4ZTcxODI3MGY1YmExOTc3OTYxY2NiZjBmNi5tcDQ/0.flac?token=1751128279~ZGYxNmUwNGRkOTJlMjg3ZmY4OTUyODJkY2VkN2E5ZmEwMTJjZDk2Yw==","trackType":"tidal"} Jun 28 17:31:20 volumio volumio[1435]: verbose: CURRENT POSITION 16 Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::syncState stateService play Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::syncState currentStatus play Jun 28 17:31:20 volumio volumio[1435]: info: Received an update from plugin. extracting info from payload Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:20 volumio volumio[1435]: info: ControllerMpd::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::servicePushState Jun 28 17:31:20 volumio volumio[1435]: info: CorePlayQueue::getTrack 16 Jun 28 17:31:20 volumio volumio[1435]: verbose: STATE SERVICE {"status":"play","position":0,"seek":343,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"400 Kbps","isStreaming":false,"title":"0.flac?token=1751128279~ZGYxNmUwNGRkOTJlMjg3ZmY4OTUyODJkY2VkN2E5ZmEwMTJjZDk2Yw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1OGE1NGI4ZTcxODI3MGY1YmExOTc3OTYxY2NiZjBmNi5tcDQ/0.flac?token=1751128279~ZGYxNmUwNGRkOTJlMjg3ZmY4OTUyODJkY2VkN2E5ZmEwMTJjZDk2Yw==","trackType":"tidal"} Jun 28 17:31:20 volumio volumio[1435]: verbose: CURRENT POSITION 16 Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::syncState stateService play Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::syncState currentStatus play Jun 28 17:31:20 volumio volumio[1435]: info: Received an update from plugin. extracting info from payload Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:20 volumio volumio[1435]: info: ControllerMpd::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::servicePushState Jun 28 17:31:20 volumio volumio[1435]: info: CorePlayQueue::getTrack 16 Jun 28 17:31:20 volumio volumio[1435]: verbose: STATE SERVICE {"status":"play","position":0,"seek":412,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"560 Kbps","isStreaming":false,"title":"0.flac?token=1751128279~ZGYxNmUwNGRkOTJlMjg3ZmY4OTUyODJkY2VkN2E5ZmEwMTJjZDk2Yw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1OGE1NGI4ZTcxODI3MGY1YmExOTc3OTYxY2NiZjBmNi5tcDQ/0.flac?token=1751128279~ZGYxNmUwNGRkOTJlMjg3ZmY4OTUyODJkY2VkN2E5ZmEwMTJjZDk2Yw==","trackType":"tidal"} Jun 28 17:31:20 volumio volumio[1435]: verbose: CURRENT POSITION 16 Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::syncState stateService play Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::syncState currentStatus play Jun 28 17:31:20 volumio volumio[1435]: info: Received an update from plugin. extracting info from payload Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:20 volumio volumio[1435]: info: ControllerMpd::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::servicePushState Jun 28 17:31:20 volumio volumio[1435]: info: CorePlayQueue::getTrack 16 Jun 28 17:31:20 volumio volumio[1435]: verbose: STATE SERVICE {"status":"play","position":0,"seek":481,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"755 Kbps","isStreaming":false,"title":"0.flac?token=1751128279~ZGYxNmUwNGRkOTJlMjg3ZmY4OTUyODJkY2VkN2E5ZmEwMTJjZDk2Yw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1OGE1NGI4ZTcxODI3MGY1YmExOTc3OTYxY2NiZjBmNi5tcDQ/0.flac?token=1751128279~ZGYxNmUwNGRkOTJlMjg3ZmY4OTUyODJkY2VkN2E5ZmEwMTJjZDk2Yw==","trackType":"tidal"} Jun 28 17:31:20 volumio volumio[1435]: verbose: CURRENT POSITION 16 Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::syncState stateService play Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::syncState currentStatus play Jun 28 17:31:20 volumio volumio[1435]: info: Received an update from plugin. extracting info from payload Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:20 volumio volumio[1435]: info: ------------------------------ 72ms Jun 28 17:31:20 volumio volumio[1435]: info: ------------------------------ 73ms Jun 28 17:31:20 volumio volumio[1435]: info: ------------------------------ 72ms Jun 28 17:31:20 volumio volumio[1435]: info: ------------------------------ 74ms Jun 28 17:31:20 volumio volumio[1435]: info: ------------------------------ 74ms Jun 28 17:31:20 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:20 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:20 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:20 volumio sudo[12743]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:20 volumio sudo[12743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:20 volumio sudo[12745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:20 volumio sudo[12745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:20 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:20 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:20 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:20 volumio sudo[12751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:20 volumio sudo[12751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:20 volumio sudo[12743]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:20 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:20 volumio sudo[12745]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:20 volumio sudo[12756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:20 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:20 volumio sudo[12756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:20 volumio sudo[12749]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:20 volumio sudo[12749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:20 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:20 volumio sudo[12753]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:20 volumio sudo[12753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:20 volumio sudo[12758]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:20 volumio sudo[12758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:20 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:20 volumio sudo[12762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:20 volumio sudo[12762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:20 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:20 volumio sudo[12769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:20 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:20 volumio sudo[12766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:20 volumio sudo[12766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:20 volumio sudo[12769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:20 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:20 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:20 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:20 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:20 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:20 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:20 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:20 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:20 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:20 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:20 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:20 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:20 volumio sudo[12775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:20 volumio sudo[12775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:20 volumio sudo[12771]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:20 volumio sudo[12771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand status took 255 milliseconds Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::parseState Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 17:31:20 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:20 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:20 volumio volumio[1435]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 28 17:31:20 volumio volumio[1435]: verbose: ControllerMpd::parseTrackInfo Jun 28 17:31:20 volumio volumio[1435]: info: ControllerMpd::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::servicePushState Jun 28 17:31:20 volumio volumio[1435]: info: CorePlayQueue::getTrack 16 Jun 28 17:31:20 volumio volumio[1435]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"774 Kbps","isStreaming":false,"title":"0.flac?token=1751128279~ZGYxNmUwNGRkOTJlMjg3ZmY4OTUyODJkY2VkN2E5ZmEwMTJjZDk2Yw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1OGE1NGI4ZTcxODI3MGY1YmExOTc3OTYxY2NiZjBmNi5tcDQ/0.flac?token=1751128279~ZGYxNmUwNGRkOTJlMjg3ZmY4OTUyODJkY2VkN2E5ZmEwMTJjZDk2Yw==","trackType":"tidal"} Jun 28 17:31:20 volumio volumio[1435]: verbose: CURRENT POSITION 16 Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::syncState stateService play Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::syncState currentStatus play Jun 28 17:31:20 volumio volumio[1435]: info: Received an update from plugin. extracting info from payload Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreStateMachine::pushState Jun 28 17:31:20 volumio volumio[1435]: info: CoreCommandRouter::volumioPushState Jun 28 17:31:20 volumio volumio[1435]: info: ------------------------------ 264ms Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:20 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:20 volumio volumio[1435]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 28 17:31:20 volumio sudo[12753]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:20 volumio sudo[12758]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:20 volumio sudo[12769]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:20 volumio sudo[12751]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:20 volumio sudo[12766]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:20 volumio sudo[12762]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:20 volumio sudo[12749]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:20 volumio sudo[12756]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:20 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:20 volumio volumio[1435]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Volumio is playing Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:20 volumio sudo[12792]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:20 volumio sudo[12792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:20 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:20 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:20 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:20 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:20 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:20 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:20 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:20 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:20 volumio volumio[1435]: info: FusionDsp - Clipping Monitor started Jun 28 17:31:20 volumio sudo[12793]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 28 17:31:20 volumio sudo[12793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 28 17:31:20 volumio sudo[12775]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:20 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:20 volumio sudo[12771]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:20 volumio sudo[12793]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:20 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:20 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:20 volumio sudo[12792]: pam_unix(sudo:session): session closed for user root Jun 28 17:31:20 volumio volumio[1435]: info: peppymeterbasic Daemon Started Jun 28 17:31:22 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:31:22 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:31:22 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:31:22 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:31:22 volumio volumio[1435]: info: Executing endpoint metavolumio Jun 28 17:31:22 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 28 17:31:24 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 28 17:31:24 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 28 17:31:24 volumio volumio[1435]: info: Discovery: Getting this device information Jun 28 17:31:24 volumio volumio[1435]: info: CoreCommandRouter::volumioGetState Jun 28 17:31:24 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 28 17:31:27 volumio volumio[1435]: info: CoreCommandRouter::volumioGetState Jun 28 17:31:27 volumio volumio[1435]: info: Listing playlists Jun 28 17:31:27 volumio volumio[1435]: info: Listing playlists Jun 28 17:31:28 volumio volumio[1435]: Searching all installed plugins Jun 28 17:31:28 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 17:31:28 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: , search Jun 28 17:31:28 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 28 17:31:28 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jun 28 17:31:28 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jun 28 17:31:28 volumio volumio[1435]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jun 28 17:31:28 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 28 17:31:28 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: spop , search Jun 28 17:31:28 volumio volumio[1435]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 28 17:31:28 volumio volumio[1435]: info: searchTIDALUri took 75 milliseconds Jun 28 17:31:28 volumio volumio[1435]: info: search took 75 milliseconds Jun 28 17:31:28 volumio volumio[1435]: Use XMLElement.setAttribute instead Jun 28 17:31:28 volumio volumio[1435]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 17:31:28 volumio volumio[1435]: TypeError: children[i].attr(...).value is not a function Jun 28 17:31:28 volumio volumio[1435]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Jun 28 17:31:28 volumio volumio[1435]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 28 17:31:28 volumio volumio[1435]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 17:31:28 volumio sudo[12827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-28 17:30' Jun 28 17:31:28 volumio sudo[12827]: 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="cee9bb5b0ee50e4898478097a64b5c6c77d5a212" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="b0310d60a07948a2a8a2e8db322034758c86aad6" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 21 15:01:23 UTC 2025" VOLUMIO_VERSION="4.013" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6df2d5f0b86e6f2c0cd4dc68c66de48c"