Jun 06 07:25:00 rpi5-ws840 volumio[1619]: info: Executing endpoint metavolumio Jun 06 07:25:00 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 06 07:25:01 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 06 07:25:01 rpi5-ws840 volumio[1619]: info: Received Get System Info Jun 06 07:25:01 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 06 07:25:01 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 06 07:25:01 rpi5-ws840 volumio[1619]: info: Discovery: Getting this device information Jun 06 07:25:01 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioGetState Jun 06 07:25:01 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 06 07:25:02 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 06 07:25:02 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 06 07:25:07 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 06 07:25:07 rpi5-ws840 volumio[1619]: error: Failed request for metavolumio API Jun 06 07:25:08 rpi5-ws840 go-librespot[2033]: time="2025-06-06T07:25:08+02:00" level=trace msg="sent dealer ping" Jun 06 07:25:08 rpi5-ws840 go-librespot[2033]: time="2025-06-06T07:25:08+02:00" level=trace msg="received dealer pong" Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Enabling plugin peppyspectrum Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Loading plugin "peppyspectrum"... Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Preparing to generate the ALSA configuration file Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Reading ALSA contributions from plugins. Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Asound.conf file written Jun 06 07:25:13 rpi5-ws840 sudo[32639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 06 07:25:13 rpi5-ws840 sudo[32639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:13 rpi5-ws840 sudo[32639]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:13 rpi5-ws840 volumio[1619]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 06 07:25:13 rpi5-ws840 volumio[1619]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 06 07:25:13 rpi5-ws840 volumio[1619]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Output device has changed, restarting MPD Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 sudo[32647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 07:25:13 rpi5-ws840 vtcs[26508]: [2025-06-06 07:25:13.484] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 06 07:25:13 rpi5-ws840 vtcs[26508]: [2025-06-06 07:25:13.484] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jun 06 07:25:13 rpi5-ws840 sudo[32647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:13 rpi5-ws840 sudo[32647]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: PLUGIN START: peppyspectrum Jun 06 07:25:13 rpi5-ws840 sudo[32649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 07:25:13 rpi5-ws840 sudo[32649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:13 rpi5-ws840 sudo[32654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:13 rpi5-ws840 sudo[32654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Preparing to generate the ALSA configuration file Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Done. Jun 06 07:25:13 rpi5-ws840 systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 06 07:25:13 rpi5-ws840 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jun 06 07:25:13 rpi5-ws840 systemd[1]: vtcs.service: Killing process 26524 (vtcs) with signal SIGKILL. Jun 06 07:25:13 rpi5-ws840 systemd[1]: vtcs.service: Deactivated successfully. Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Reading ALSA contributions from plugins. Jun 06 07:25:13 rpi5-ws840 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jun 06 07:25:13 rpi5-ws840 volumio[1619]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: MPD Permissions set Jun 06 07:25:13 rpi5-ws840 volumio[1619]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jun 06 07:25:13 rpi5-ws840 sudo[32658]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:13 rpi5-ws840 sudo[32658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:13 rpi5-ws840 sudo[32654]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 sudo[32658]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Asound.conf file unchanged, so no further update is needed Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Output device has changed, restarting MPD Jun 06 07:25:13 rpi5-ws840 sudo[32668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 sudo[32668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:13 rpi5-ws840 sudo[32668]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:13 rpi5-ws840 sudo[32671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 07:25:13 rpi5-ws840 sudo[32671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Preparing to generate the ALSA configuration file Jun 06 07:25:13 rpi5-ws840 sudo[32676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:13 rpi5-ws840 sudo[32676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Reading ALSA contributions from plugins. Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: MPD Permissions set Jun 06 07:25:13 rpi5-ws840 sudo[32682]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:13 rpi5-ws840 sudo[32682]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 systemd[1]: mpd.service: Deactivated successfully. Jun 06 07:25:13 rpi5-ws840 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 06 07:25:13 rpi5-ws840 systemd[1]: mpd.service: Consumed 12.222s CPU time. Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Asound.conf file unchanged, so no further update is needed Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Output device has changed, restarting MPD Jun 06 07:25:13 rpi5-ws840 sudo[32676]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:13 rpi5-ws840 systemd[1]: mpd.socket: Deactivated successfully. Jun 06 07:25:13 rpi5-ws840 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 06 07:25:13 rpi5-ws840 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 sudo[32693]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 07:25:13 rpi5-ws840 sudo[32693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:13 rpi5-ws840 sudo[32693]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Preparing to generate the ALSA configuration file Jun 06 07:25:13 rpi5-ws840 sudo[32697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 07:25:13 rpi5-ws840 sudo[32697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:13 rpi5-ws840 sudo[32702]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:13 rpi5-ws840 sudo[32702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Reading ALSA contributions from plugins. Jun 06 07:25:13 rpi5-ws840 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 06 07:25:13 rpi5-ws840 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 06 07:25:13 rpi5-ws840 sudo[32706]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:13 rpi5-ws840 sudo[32706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: MPD Permissions set Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Asound.conf file unchanged, so no further update is needed Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: Output device has changed, restarting MPD Jun 06 07:25:13 rpi5-ws840 sudo[32682]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:13 rpi5-ws840 sudo[32716]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 07:25:13 rpi5-ws840 sudo[32716]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:13 rpi5-ws840 sudo[32716]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 systemd[1]: mpd.service: Deactivated successfully. Jun 06 07:25:13 rpi5-ws840 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 06 07:25:13 rpi5-ws840 sudo[32720]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 07:25:13 rpi5-ws840 sudo[32720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:13 rpi5-ws840 sudo[32702]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:13 rpi5-ws840 sudo[32706]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: MPD Permissions set Jun 06 07:25:13 rpi5-ws840 sudo[32725]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:13 rpi5-ws840 sudo[32725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 sudo[32727]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:13 rpi5-ws840 sudo[32727]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 07:25:13 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:13 rpi5-ws840 systemd[1]: mpd.socket: Deactivated successfully. Jun 06 07:25:13 rpi5-ws840 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 06 07:25:13 rpi5-ws840 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 06 07:25:13 rpi5-ws840 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 06 07:25:13 rpi5-ws840 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 06 07:25:13 rpi5-ws840 sudo[32727]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:13 rpi5-ws840 sudo[32725]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:13 rpi5-ws840 sudo[32739]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 06 07:25:13 rpi5-ws840 sudo[32739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 06 07:25:13 rpi5-ws840 sudo[32739]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:14 rpi5-ws840 mpd[32743]: 2025-06-06T07:25:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 06 07:25:15 rpi5-ws840 systemd[1]: Started mpd.service - Music Player Daemon. Jun 06 07:25:15 rpi5-ws840 sudo[32720]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:15 rpi5-ws840 sudo[32649]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:15 rpi5-ws840 sudo[32671]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:15 rpi5-ws840 sudo[32697]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:15 rpi5-ws840 volumio[1619]: error: MPD error: The expression evaluated to a falsy value: Jun 06 07:25:15 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:25:15 rpi5-ws840 volumio[1619]: error: The expression evaluated to a falsy value: Jun 06 07:25:15 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:25:15 rpi5-ws840 volumio[1619]: error: MPD error: The expression evaluated to a falsy value: Jun 06 07:25:15 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:25:15 rpi5-ws840 volumio[1619]: error: The expression evaluated to a falsy value: Jun 06 07:25:15 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:25:15 rpi5-ws840 volumio[1619]: error: MPD error: The expression evaluated to a falsy value: Jun 06 07:25:15 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:25:15 rpi5-ws840 volumio[1619]: error: The expression evaluated to a falsy value: Jun 06 07:25:15 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:25:15 rpi5-ws840 volumio[1619]: error: updateQueue error: null Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioStop Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CoreStateMachine::stop Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CoreStateMachine::stPlaybackTimer Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CoreStateMachine::updateTrackBlock Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrackBlock Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CoreStateMachine::serviceStop Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::serviceStop Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: [1749187515732] ControllerWebradio::stop Jun 06 07:25:15 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand stop Jun 06 07:25:15 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: PeppySpectrum ---peppyspectrum status stop Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: sendMpdCommand stop took 11 milliseconds Jun 06 07:25:15 rpi5-ws840 sudo[32747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 06 07:25:15 rpi5-ws840 sudo[32747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 06 07:25:15 rpi5-ws840 sudo[32747]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: peppyspectrum Daemon Stop Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: Received Get System Version Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: Received Get System Info Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: Discovery: Getting this device information Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioGetState Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:15 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:16 rpi5-ws840 sudo[32762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 06 07:25:16 rpi5-ws840 sudo[32762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:16 rpi5-ws840 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jun 06 07:25:16 rpi5-ws840 sudo[32762]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: Executing endpoint tc_getconfig Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 06 07:25:16 rpi5-ws840 vtcs[32765]: OpenSSL version mismatch. Built against 101010ef, you have 30000100 Jun 06 07:25:16 rpi5-ws840 vtcs[32765]: STARTING TidalConnect services, version: 1.5.0.45 Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:16 rpi5-ws840 vtcs[32765]: STARTED TidalConnect services. Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: Executing endpoint tc_connect Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: Connecting to TidalConnect Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::servicePushState Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::servicePushState Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jun 06 07:25:16 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:16 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:16 rpi5-ws840 vtcs[32765]: [2025-06-06 07:25:16.806] [tisoc] [error] [WebsocketServerTls.cpp:90] [WebsocketServerTls onTlsInit Error] use_certificate_chain: no start line Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: PeppySpectrum ---peppyspectrum status stop Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: PeppySpectrum ---peppyspectrum status stop Jun 06 07:25:16 rpi5-ws840 sudo[324]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 06 07:25:16 rpi5-ws840 sudo[324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:16 rpi5-ws840 sudo[326]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 06 07:25:16 rpi5-ws840 sudo[326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:16 rpi5-ws840 sudo[331]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 06 07:25:16 rpi5-ws840 sudo[331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:16 rpi5-ws840 sudo[342]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 06 07:25:16 rpi5-ws840 sudo[342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:16 rpi5-ws840 sudo[331]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:16 rpi5-ws840 sudo[324]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: peppyspectrum Daemon Stop Jun 06 07:25:16 rpi5-ws840 sudo[326]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: peppyspectrum Daemon Stop Jun 06 07:25:16 rpi5-ws840 sudo[342]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:16 rpi5-ws840 volumio[1619]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:16 rpi5-ws840 sudo[357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 06 07:25:16 rpi5-ws840 sudo[357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:16 rpi5-ws840 sudo[357]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:18 rpi5-ws840 volumio[1619]: info: Executing endpoint metavolumio Jun 06 07:25:18 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: Enabling plugin peppymeterbasic Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: Loading plugin "peppymeterbasic"... Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: Preparing to generate the ALSA configuration file Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: Reading ALSA contributions from plugins. Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: TidalConnect service started! Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: Asound.conf file written Jun 06 07:25:19 rpi5-ws840 sudo[385]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 06 07:25:19 rpi5-ws840 sudo[385]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:19 rpi5-ws840 sudo[385]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:19 rpi5-ws840 volumio[1619]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 06 07:25:19 rpi5-ws840 volumio[1619]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 06 07:25:19 rpi5-ws840 volumio[1619]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: Output device has changed, restarting MPD Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 sudo[391]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 07:25:19 rpi5-ws840 sudo[391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:19 rpi5-ws840 sudo[391]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:19 rpi5-ws840 sudo[393]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 07:25:19 rpi5-ws840 sudo[393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:19 rpi5-ws840 vtcs[32765]: [2025-06-06 07:25:19.713] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jun 06 07:25:19 rpi5-ws840 vtcs[32765]: [2025-06-06 07:25:19.715] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: PLUGIN START: peppymeterbasic Jun 06 07:25:19 rpi5-ws840 systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 06 07:25:19 rpi5-ws840 sudo[399]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:19 rpi5-ws840 sudo[399]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: Preparing to generate the ALSA configuration file Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: Done. Jun 06 07:25:19 rpi5-ws840 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: Reading ALSA contributions from plugins. Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: MPD Permissions set Jun 06 07:25:19 rpi5-ws840 volumio[1619]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Jun 06 07:25:19 rpi5-ws840 sudo[402]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:19 rpi5-ws840 sudo[402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:19 rpi5-ws840 systemd[1]: vtcs.service: Deactivated successfully. Jun 06 07:25:19 rpi5-ws840 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jun 06 07:25:19 rpi5-ws840 sudo[399]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 sudo[402]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: Asound.conf file unchanged, so no further update is needed Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: Output device has changed, restarting MPD Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 sudo[412]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 07:25:19 rpi5-ws840 sudo[412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:19 rpi5-ws840 sudo[412]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:19 rpi5-ws840 sudo[414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 07:25:19 rpi5-ws840 sudo[414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:19 rpi5-ws840 sudo[421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:19 rpi5-ws840 sudo[421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: MPD Permissions set Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 systemd[1]: mpd.service: Deactivated successfully. Jun 06 07:25:19 rpi5-ws840 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 06 07:25:19 rpi5-ws840 systemd[1]: mpd.service: Consumed 1.361s CPU time. Jun 06 07:25:19 rpi5-ws840 sudo[428]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:19 rpi5-ws840 sudo[428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: TidalConnect service started! Jun 06 07:25:19 rpi5-ws840 systemd[1]: mpd.socket: Deactivated successfully. Jun 06 07:25:19 rpi5-ws840 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 06 07:25:19 rpi5-ws840 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: TidalConnect service started! Jun 06 07:25:19 rpi5-ws840 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 06 07:25:19 rpi5-ws840 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 06 07:25:19 rpi5-ws840 sudo[421]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:19 rpi5-ws840 sudo[428]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:19 rpi5-ws840 volumio[1619]: info: TidalConnect service started! Jun 06 07:25:19 rpi5-ws840 sudo[438]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 06 07:25:19 rpi5-ws840 sudo[438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 06 07:25:19 rpi5-ws840 sudo[438]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:20 rpi5-ws840 mpd[442]: 2025-06-06T07:25:20 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 06 07:25:21 rpi5-ws840 systemd[1]: Started mpd.service - Music Player Daemon. Jun 06 07:25:21 rpi5-ws840 sudo[414]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:21 rpi5-ws840 sudo[393]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:21 rpi5-ws840 volumio[1619]: error: MPD error: The expression evaluated to a falsy value: Jun 06 07:25:21 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:25:21 rpi5-ws840 volumio[1619]: error: The expression evaluated to a falsy value: Jun 06 07:25:21 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:25:21 rpi5-ws840 volumio[1619]: error: updateQueue error: null Jun 06 07:25:21 rpi5-ws840 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 06 07:25:21 rpi5-ws840 dbus-daemon[953]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.628' (uid=0 pid=446 comm="timedatectl show --property=NTPSynchronized --valu") Jun 06 07:25:21 rpi5-ws840 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 06 07:25:21 rpi5-ws840 dbus-daemon[953]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 06 07:25:21 rpi5-ws840 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 06 07:25:21 rpi5-ws840 setdatetime-helper.sh[445]: Time is already synchronized. Jun 06 07:25:21 rpi5-ws840 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 06 07:25:21 rpi5-ws840 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: Stopping peppymeterbasic service Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: CoreStateMachine::stop Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: Disabling plugin peppymeterbasic Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: Preparing to generate the ALSA configuration file Jun 06 07:25:21 rpi5-ws840 sudo[450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:25:21 rpi5-ws840 sudo[450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: Reading ALSA contributions from plugins. Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: Asound.conf file written Jun 06 07:25:21 rpi5-ws840 sudo[454]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 06 07:25:21 rpi5-ws840 sudo[454]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:21 rpi5-ws840 sudo[454]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:21 rpi5-ws840 volumio[1619]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 06 07:25:21 rpi5-ws840 volumio[1619]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 06 07:25:21 rpi5-ws840 volumio[1619]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: Output device has changed, restarting MPD Jun 06 07:25:21 rpi5-ws840 sudo[450]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:21 rpi5-ws840 sudo[463]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 07:25:21 rpi5-ws840 sudo[463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:21 rpi5-ws840 sudo[463]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:21 rpi5-ws840 sudo[465]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 07:25:21 rpi5-ws840 sudo[465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: Done. Jun 06 07:25:21 rpi5-ws840 sudo[471]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: MPD Permissions set Jun 06 07:25:21 rpi5-ws840 sudo[471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:21 rpi5-ws840 sudo[473]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:21 rpi5-ws840 sudo[473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:21 rpi5-ws840 systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 07:25:21 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:21 rpi5-ws840 sudo[473]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:21 rpi5-ws840 sudo[471]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:22 rpi5-ws840 systemd[1]: mpd.service: Deactivated successfully. Jun 06 07:25:22 rpi5-ws840 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 06 07:25:22 rpi5-ws840 systemd[1]: mpd.service: Consumed 1.324s CPU time. Jun 06 07:25:22 rpi5-ws840 systemd[1]: mpd.socket: Deactivated successfully. Jun 06 07:25:22 rpi5-ws840 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 06 07:25:22 rpi5-ws840 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 06 07:25:22 rpi5-ws840 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 06 07:25:22 rpi5-ws840 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 06 07:25:22 rpi5-ws840 sudo[485]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 06 07:25:22 rpi5-ws840 sudo[485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 06 07:25:22 rpi5-ws840 sudo[485]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: Enabling plugin peppymeterbasic Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: Loading plugin "peppymeterbasic"... Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: Preparing to generate the ALSA configuration file Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: Reading ALSA contributions from plugins. Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: Asound.conf file written Jun 06 07:25:22 rpi5-ws840 sudo[490]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 06 07:25:22 rpi5-ws840 sudo[490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:22 rpi5-ws840 sudo[490]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:22 rpi5-ws840 volumio[1619]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 06 07:25:22 rpi5-ws840 volumio[1619]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 06 07:25:22 rpi5-ws840 volumio[1619]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: Output device has changed, restarting MPD Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 sudo[498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 07:25:22 rpi5-ws840 sudo[498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:22 rpi5-ws840 sudo[497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 07:25:22 rpi5-ws840 sudo[497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:22 rpi5-ws840 sudo[497]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: PLUGIN START: peppymeterbasic Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: Preparing to generate the ALSA configuration file Jun 06 07:25:22 rpi5-ws840 systemd[1]: mpd.service: Deactivated successfully. Jun 06 07:25:22 rpi5-ws840 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 06 07:25:22 rpi5-ws840 systemd[1]: mpd.socket: Deactivated successfully. Jun 06 07:25:22 rpi5-ws840 sudo[504]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:22 rpi5-ws840 sudo[504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:22 rpi5-ws840 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 06 07:25:22 rpi5-ws840 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: Done. Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: Reading ALSA contributions from plugins. Jun 06 07:25:22 rpi5-ws840 volumio[1619]: error: Upnp client error: Error: read ECONNRESET Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: MPD Permissions set Jun 06 07:25:22 rpi5-ws840 volumio[1619]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Jun 06 07:25:22 rpi5-ws840 sudo[508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:22 rpi5-ws840 sudo[508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 06 07:25:22 rpi5-ws840 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: Asound.conf file unchanged, so no further update is needed Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: Output device has changed, restarting MPD Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 sudo[517]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 07:25:22 rpi5-ws840 sudo[517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:22 rpi5-ws840 sudo[517]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:22 rpi5-ws840 sudo[508]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:22 rpi5-ws840 sudo[519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 07:25:22 rpi5-ws840 sudo[519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:22 rpi5-ws840 sudo[526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:22 rpi5-ws840 sudo[526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:22 rpi5-ws840 sudo[504]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: MPD Permissions set Jun 06 07:25:22 rpi5-ws840 sudo[533]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:22 rpi5-ws840 sudo[515]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 06 07:25:22 rpi5-ws840 sudo[515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 06 07:25:22 rpi5-ws840 sudo[515]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:22 rpi5-ws840 sudo[533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 systemd[1]: mpd.service: Deactivated successfully. Jun 06 07:25:22 rpi5-ws840 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 06 07:25:22 rpi5-ws840 systemd[1]: mpd.socket: Deactivated successfully. Jun 06 07:25:22 rpi5-ws840 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 06 07:25:22 rpi5-ws840 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:22 rpi5-ws840 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 06 07:25:22 rpi5-ws840 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 06 07:25:22 rpi5-ws840 sudo[526]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:22 rpi5-ws840 sudo[533]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:22 rpi5-ws840 sudo[541]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 06 07:25:22 rpi5-ws840 sudo[541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 06 07:25:22 rpi5-ws840 sudo[541]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:22 rpi5-ws840 sudo[555]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 06 07:25:22 rpi5-ws840 sudo[555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:22 rpi5-ws840 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jun 06 07:25:22 rpi5-ws840 sudo[555]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:22 rpi5-ws840 sudo[569]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 06 07:25:22 rpi5-ws840 sudo[569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: Executing endpoint tc_getconfig Jun 06 07:25:22 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 06 07:25:22 rpi5-ws840 sudo[569]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:22 rpi5-ws840 vtcs[558]: OpenSSL version mismatch. Built against 101010ef, you have 30000100 Jun 06 07:25:22 rpi5-ws840 vtcs[558]: STARTING TidalConnect services, version: 1.5.0.45 Jun 06 07:25:23 rpi5-ws840 vtcs[558]: STARTED TidalConnect services. Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: Executing endpoint tc_connect Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: Connecting to TidalConnect Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::servicePushState Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::servicePushState Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jun 06 07:25:23 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:23 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:23 rpi5-ws840 vtcs[558]: [2025-06-06 07:25:23.028] [tisoc] [error] [WebsocketServerTls.cpp:90] [WebsocketServerTls onTlsInit Error] use_certificate_chain: no start line Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: PeppySpectrum ---peppyspectrum status stop Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: PeppySpectrum ---peppyspectrum status stop Jun 06 07:25:23 rpi5-ws840 sudo[586]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 06 07:25:23 rpi5-ws840 sudo[586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:23 rpi5-ws840 sudo[588]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 06 07:25:23 rpi5-ws840 sudo[588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:23 rpi5-ws840 sudo[586]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: peppyspectrum Daemon Stop Jun 06 07:25:23 rpi5-ws840 sudo[588]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: peppyspectrum Daemon Stop Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: Stopping peppyspectrum service Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreStateMachine::stop Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: Disabling plugin peppyspectrum Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: Preparing to generate the ALSA configuration file Jun 06 07:25:23 rpi5-ws840 sudo[593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jun 06 07:25:23 rpi5-ws840 sudo[593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: Reading ALSA contributions from plugins. Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: Asound.conf file written Jun 06 07:25:23 rpi5-ws840 sudo[597]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 06 07:25:23 rpi5-ws840 sudo[597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:23 rpi5-ws840 sudo[597]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:23 rpi5-ws840 volumio[1619]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 06 07:25:23 rpi5-ws840 volumio[1619]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 06 07:25:23 rpi5-ws840 volumio[1619]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: Output device has changed, restarting MPD Jun 06 07:25:23 rpi5-ws840 sudo[593]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:23 rpi5-ws840 sudo[604]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 07:25:23 rpi5-ws840 sudo[604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:23 rpi5-ws840 sudo[604]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:23 rpi5-ws840 sudo[606]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 07:25:23 rpi5-ws840 sudo[606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:23 rpi5-ws840 vtcs[558]: [2025-06-06 07:25:23.307] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jun 06 07:25:23 rpi5-ws840 vtcs[558]: [2025-06-06 07:25:23.308] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: Done. Jun 06 07:25:23 rpi5-ws840 sudo[612]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:23 rpi5-ws840 sudo[612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: MPD Permissions set Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:23 rpi5-ws840 systemd[1]: mpd.service: Deactivated successfully. Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:23 rpi5-ws840 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 06 07:25:23 rpi5-ws840 sudo[615]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:23 rpi5-ws840 sudo[615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 07:25:23 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:25:23 rpi5-ws840 systemd[1]: mpd.socket: Deactivated successfully. Jun 06 07:25:23 rpi5-ws840 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 06 07:25:23 rpi5-ws840 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 06 07:25:23 rpi5-ws840 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 06 07:25:23 rpi5-ws840 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 06 07:25:23 rpi5-ws840 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jun 06 07:25:23 rpi5-ws840 systemd[1]: vtcs.service: Killing process 573 (vtcs) with signal SIGKILL. Jun 06 07:25:23 rpi5-ws840 systemd[1]: vtcs.service: Deactivated successfully. Jun 06 07:25:23 rpi5-ws840 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jun 06 07:25:23 rpi5-ws840 sudo[612]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:23 rpi5-ws840 sudo[615]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:23 rpi5-ws840 sudo[624]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 06 07:25:23 rpi5-ws840 sudo[624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 06 07:25:23 rpi5-ws840 sudo[624]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:24 rpi5-ws840 mpd[628]: 2025-06-06T07:25:24 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 06 07:25:24 rpi5-ws840 systemd[1]: Started mpd.service - Music Player Daemon. Jun 06 07:25:24 rpi5-ws840 sudo[498]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:24 rpi5-ws840 sudo[465]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:24 rpi5-ws840 sudo[519]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:24 rpi5-ws840 sudo[606]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:24 rpi5-ws840 volumio[1619]: error: MPD error: The expression evaluated to a falsy value: Jun 06 07:25:24 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:25:24 rpi5-ws840 volumio[1619]: error: The expression evaluated to a falsy value: Jun 06 07:25:24 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:25:24 rpi5-ws840 volumio[1619]: error: MPD error: The expression evaluated to a falsy value: Jun 06 07:25:24 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:25:24 rpi5-ws840 volumio[1619]: error: The expression evaluated to a falsy value: Jun 06 07:25:24 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:25:24 rpi5-ws840 volumio[1619]: error: MPD error: The expression evaluated to a falsy value: Jun 06 07:25:24 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:25:24 rpi5-ws840 volumio[1619]: error: The expression evaluated to a falsy value: Jun 06 07:25:24 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:25:24 rpi5-ws840 volumio[1619]: error: updateQueue error: null Jun 06 07:25:24 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:24 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:24 rpi5-ws840 volumio[1619]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:24 rpi5-ws840 volumio[1619]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:25 rpi5-ws840 sudo[640]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 06 07:25:25 rpi5-ws840 sudo[640]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: Executing endpoint metavolumio Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 06 07:25:25 rpi5-ws840 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jun 06 07:25:25 rpi5-ws840 sudo[640]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: Executing endpoint tc_getconfig Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 06 07:25:25 rpi5-ws840 vtcs[643]: OpenSSL version mismatch. Built against 101010ef, you have 30000100 Jun 06 07:25:25 rpi5-ws840 vtcs[643]: STARTING TidalConnect services, version: 1.5.0.45 Jun 06 07:25:25 rpi5-ws840 vtcs[643]: STARTED TidalConnect services. Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: Executing endpoint tc_connect Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: Connecting to TidalConnect Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::servicePushState Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::servicePushState Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jun 06 07:25:25 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:25 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:25 rpi5-ws840 vtcs[643]: [2025-06-06 07:25:25.191] [tisoc] [error] [WebsocketServerTls.cpp:90] [WebsocketServerTls onTlsInit Error] use_certificate_chain: no start line Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 06 07:25:25 rpi5-ws840 sudo[659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 06 07:25:25 rpi5-ws840 sudo[659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:25 rpi5-ws840 sudo[662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 06 07:25:25 rpi5-ws840 sudo[662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:25 rpi5-ws840 sudo[659]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: peppymeterbasic Daemon Stop Jun 06 07:25:25 rpi5-ws840 sudo[662]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: peppymeterbasic Daemon Stop Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:25 rpi5-ws840 sudo[676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 06 07:25:25 rpi5-ws840 sudo[676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:25 rpi5-ws840 sudo[676]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:25 rpi5-ws840 sudo[688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 06 07:25:25 rpi5-ws840 sudo[688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:25 rpi5-ws840 sudo[688]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:25 rpi5-ws840 volumio[1619]: error: Failed request for metavolumio API Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: TidalConnect service started! Jun 06 07:25:25 rpi5-ws840 volumio[1619]: info: TidalConnect service started! Jun 06 07:25:26 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:26 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:25:26 rpi5-ws840 volumio[1619]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:26 rpi5-ws840 volumio[1619]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:25:26 rpi5-ws840 sudo[702]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 06 07:25:26 rpi5-ws840 sudo[702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:26 rpi5-ws840 sudo[702]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: Executing endpoint metavolumio Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPlay Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: CoreStateMachine::play index undefined Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: CoreStateMachine::startPlaybackTimer Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: [1749187527460] ControllerWebradio::clearAddPlayTrack Jun 06 07:25:27 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand stop Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: sendMpdCommand stop took 0 milliseconds Jun 06 07:25:27 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand clear Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: Jun 06 07:25:27 rpi5-ws840 volumio[1619]: ---------------------------- MPD announces system playlist update Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: Ignoring MPD Status Update Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: sendMpdCommand clear took 1 milliseconds Jun 06 07:25:27 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand load "https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg" Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: Jun 06 07:25:27 rpi5-ws840 volumio[1619]: ---------------------------- MPD announces system playlist update Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: Ignoring MPD Status Update Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: Jun 06 07:25:27 rpi5-ws840 volumio[1619]: ---------------------------- MPD announces system playlist update Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: Ignoring MPD Status Update Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: Jun 06 07:25:27 rpi5-ws840 volumio[1619]: ---------------------------- MPD announces system playlist update Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: Ignoring MPD Status Update Jun 06 07:25:27 rpi5-ws840 volumio[1619]: error: updateQueue error: null Jun 06 07:25:27 rpi5-ws840 volumio[1619]: info: ------------------------------ 2ms Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: TidalConnect service started! Jun 06 07:25:28 rpi5-ws840 volumio[1619]: error: updateQueue error: null Jun 06 07:25:28 rpi5-ws840 volumio[1619]: error: updateQueue error: null Jun 06 07:25:28 rpi5-ws840 volumio[1619]: error: updateQueue error: null Jun 06 07:25:28 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg" Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: ------------------------------ 1150ms Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: ------------------------------ 1150ms Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: ------------------------------ 1149ms Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: Jun 06 07:25:28 rpi5-ws840 volumio[1619]: ---------------------------- MPD announces system playlist update Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: Ignoring MPD Status Update Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg" took 1 milliseconds Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 06 07:25:28 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand play Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: Jun 06 07:25:28 rpi5-ws840 volumio[1619]: ---------------------------- MPD announces system playlist update Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: Ignoring MPD Status Update Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: Jun 06 07:25:28 rpi5-ws840 volumio[1619]: ---------------------------- MPD announces system playlist update Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: Ignoring MPD Status Update Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: Jun 06 07:25:28 rpi5-ws840 volumio[1619]: ---------------------------- MPD announces system playlist update Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: Ignoring MPD Status Update Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: ------------------------------ 2ms Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: sendMpdCommand play took 2 milliseconds Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: ------------------------------ 2ms Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: ------------------------------ 1ms Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: ------------------------------ 1ms Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: TidalConnect service started! Jun 06 07:25:28 rpi5-ws840 volumio[1619]: info: TidalConnect service started! Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: Jun 06 07:25:29 rpi5-ws840 volumio[1619]: ---------------------------- MPD announces state update: player Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ControllerMpd::getState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand status Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: Jun 06 07:25:29 rpi5-ws840 volumio[1619]: ---------------------------- MPD announces state update: player Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ControllerMpd::getState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand status Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: Jun 06 07:25:29 rpi5-ws840 volumio[1619]: ---------------------------- MPD announces state update: player Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ControllerMpd::getState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand status Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: Jun 06 07:25:29 rpi5-ws840 volumio[1619]: ---------------------------- MPD announces state update: player Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ControllerMpd::getState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand status Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: Jun 06 07:25:29 rpi5-ws840 volumio[1619]: ---------------------------- MPD announces state update: player Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: sendMpdCommand status took 18 milliseconds Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ControllerMpd::getState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand status Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::parseState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: Jun 06 07:25:29 rpi5-ws840 volumio[1619]: ---------------------------- MPD announces state update: player Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ControllerMpd::getState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand status Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: sendMpdCommand status took 20 milliseconds Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: sendMpdCommand status took 20 milliseconds Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: sendMpdCommand status took 20 milliseconds Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: sendMpdCommand status took 2 milliseconds Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::parseState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::parseState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::parseState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::parseState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::parseTrackInfo Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ControllerMpd::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::servicePushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg","trackType":""} Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: CURRENT POSITION 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::syncState stateService play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::syncState currentStatus stop Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ------------------------------ 23ms Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: Jun 06 07:25:29 rpi5-ws840 volumio[1619]: ---------------------------- MPD announces state update: player Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ControllerMpd::getState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand status Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: Jun 06 07:25:29 rpi5-ws840 volumio[1619]: ---------------------------- MPD announces state update: player Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ControllerMpd::getState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand status Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: sendMpdCommand status took 3 milliseconds Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: sendMpdCommand status took 0 milliseconds Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: sendMpdCommand status took 1 milliseconds Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::parseState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::parseTrackInfo Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::parseTrackInfo Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::parseTrackInfo Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::parseTrackInfo Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::parseState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::parseState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ControllerMpd::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::servicePushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: STATE SERVICE {"status":"play","position":0,"seek":366,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg","trackType":""} Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: CURRENT POSITION 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::syncState stateService play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::syncState currentStatus play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: Received an update from plugin. extracting info from payload Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ControllerMpd::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::servicePushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: STATE SERVICE {"status":"play","position":0,"seek":366,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg","trackType":""} Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: CURRENT POSITION 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::syncState stateService play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::syncState currentStatus play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: Received an update from plugin. extracting info from payload Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ControllerMpd::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::servicePushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg","trackType":""} Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: CURRENT POSITION 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::syncState stateService play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::syncState currentStatus play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: Received an update from plugin. extracting info from payload Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ControllerMpd::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::servicePushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg","trackType":""} Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: CURRENT POSITION 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::syncState stateService play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::syncState currentStatus play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: Received an update from plugin. extracting info from payload Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ------------------------------ 51ms Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ------------------------------ 51ms Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ------------------------------ 50ms Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ------------------------------ 33ms Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: sendMpdCommand playlistinfo took 28 milliseconds Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: sendMpdCommand playlistinfo took 27 milliseconds Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: sendMpdCommand playlistinfo took 27 milliseconds Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::parseTrackInfo Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::parseTrackInfo Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: ControllerMpd::parseTrackInfo Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ControllerMpd::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::servicePushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg","trackType":""} Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: CURRENT POSITION 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::syncState stateService play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::syncState currentStatus play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: Received an update from plugin. extracting info from payload Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ControllerMpd::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::servicePushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg","trackType":""} Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: CURRENT POSITION 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::syncState stateService play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::syncState currentStatus play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: Received an update from plugin. extracting info from payload Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ControllerMpd::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::servicePushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg","artist":null,"album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/RADIO538.mp3?dist=tunein&DIST=TuneIn&TGT=TuneIn&maxServers=2&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTcwNTUwNDAwNywiaXNzIjoidGlzcnYifQ.L53zMea-xsfmkK_w6lQaKC0zlyQ8OXCVEDxr-yAtYDg","trackType":""} Jun 06 07:25:29 rpi5-ws840 volumio[1619]: verbose: CURRENT POSITION 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::syncState stateService play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::syncState currentStatus play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: Received an update from plugin. extracting info from payload Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ------------------------------ 66ms Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ------------------------------ 64ms Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: ------------------------------ 63ms Jun 06 07:25:29 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 06 07:25:29 rpi5-ws840 sudo[734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 06 07:25:29 rpi5-ws840 sudo[734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:29 rpi5-ws840 sudo[736]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 06 07:25:29 rpi5-ws840 sudo[736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 06 07:25:29 rpi5-ws840 sudo[740]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 06 07:25:29 rpi5-ws840 sudo[740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 06 07:25:29 rpi5-ws840 sudo[742]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 06 07:25:29 rpi5-ws840 sudo[742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:29 rpi5-ws840 sudo[745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 06 07:25:29 rpi5-ws840 sudo[745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 06 07:25:29 rpi5-ws840 sudo[748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 06 07:25:29 rpi5-ws840 sudo[748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:29 rpi5-ws840 sudo[752]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 06 07:25:29 rpi5-ws840 sudo[752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 06 07:25:29 rpi5-ws840 sudo[756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 06 07:25:29 rpi5-ws840 systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Jun 06 07:25:29 rpi5-ws840 sudo[756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 06 07:25:29 rpi5-ws840 sudo[759]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 06 07:25:29 rpi5-ws840 sudo[759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:29 rpi5-ws840 sudo[734]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 06 07:25:29 rpi5-ws840 sudo[763]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 06 07:25:29 rpi5-ws840 sudo[763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:29 rpi5-ws840 sudo[766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 06 07:25:29 rpi5-ws840 sudo[766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: peppymeterbasic Daemon Started Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: TidalConnect service started! Jun 06 07:25:29 rpi5-ws840 sudo[769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 06 07:25:29 rpi5-ws840 sudo[769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:29 rpi5-ws840 sudo[774]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 06 07:25:29 rpi5-ws840 sudo[771]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 06 07:25:29 rpi5-ws840 sudo[774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:29 rpi5-ws840 sudo[771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: touch_display: Setting screensaver timeout to 0 seconds. Jun 06 07:25:29 rpi5-ws840 sudo[736]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:29 rpi5-ws840 sudo[742]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:29 rpi5-ws840 sudo[769]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:29 rpi5-ws840 sudo[763]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:29 rpi5-ws840 sudo[740]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:29 rpi5-ws840 sudo[748]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:29 rpi5-ws840 sudo[766]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: peppymeterbasic Daemon Started Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: peppymeterbasic Daemon Started Jun 06 07:25:29 rpi5-ws840 sudo[756]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:29 rpi5-ws840 sudo[774]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:29 rpi5-ws840 sudo[745]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:29 rpi5-ws840 sudo[771]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:29 rpi5-ws840 sudo[752]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:29 rpi5-ws840 sudo[759]: pam_unix(sudo:session): session closed for user root Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: peppymeterbasic Daemon Started Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: peppymeterbasic Daemon Started Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: peppymeterbasic Daemon Started Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: peppymeterbasic Daemon Started Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: peppymeterbasic Daemon Started Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: peppymeterbasic Daemon Started Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: peppymeterbasic Daemon Started Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: peppymeterbasic Daemon Started Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: peppymeterbasic Daemon Started Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: peppymeterbasic Daemon Started Jun 06 07:25:29 rpi5-ws840 volumio[1619]: info: peppymeterbasic Daemon Started Jun 06 07:25:30 rpi5-ws840 volumio[762]: Failed to create /home/volumio/.cache/mesa_shader_cache for shader cache (Permission denied)---disabling. Jun 06 07:25:30 rpi5-ws840 volumio[762]: Failed to create /home/volumio/.cache/mesa_shader_cache_db for shader cache (Permission denied)---disabling. Jun 06 07:25:30 rpi5-ws840 volumio[762]: libpng warning: iCCP: known incorrect sRGB profile Jun 06 07:25:30 rpi5-ws840 volumio[762]: libpng warning: iCCP: known incorrect sRGB profile Jun 06 07:25:30 rpi5-ws840 volumio[762]: libpng warning: iCCP: known incorrect sRGB profile Jun 06 07:25:31 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioGetState Jun 06 07:25:32 rpi5-ws840 volumio[1619]: info: Executing endpoint metavolumio Jun 06 07:25:32 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 06 07:25:33 rpi5-ws840 volumio[1619]: info: Executing endpoint metavolumio Jun 06 07:25:33 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 06 07:25:38 rpi5-ws840 go-librespot[2033]: time="2025-06-06T07:25:38+02:00" level=trace msg="sent dealer ping" Jun 06 07:25:38 rpi5-ws840 go-librespot[2033]: time="2025-06-06T07:25:38+02:00" level=trace msg="received dealer pong" Jun 06 07:25:39 rpi5-ws840 volumio[1619]: error: Failed request for metavolumio API Jun 06 07:25:40 rpi5-ws840 volumio[1619]: error: Failed request for metavolumio API Jun 06 07:25:51 rpi5-ws840 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 06 07:26:08 rpi5-ws840 go-librespot[2033]: time="2025-06-06T07:26:08+02:00" level=trace msg="sent dealer ping" Jun 06 07:26:08 rpi5-ws840 go-librespot[2033]: time="2025-06-06T07:26:08+02:00" level=trace msg="received dealer pong" Jun 06 07:26:21 rpi5-ws840 go-librespot[2033]: time="2025-06-06T07:26:21+02:00" level=trace msg="received accesspoint ping" Jun 06 07:26:21 rpi5-ws840 go-librespot[2033]: time="2025-06-06T07:26:21+02:00" level=trace msg="received accesspoint pong ack" Jun 06 07:26:38 rpi5-ws840 go-librespot[2033]: time="2025-06-06T07:26:38+02:00" level=trace msg="sent dealer ping" Jun 06 07:26:38 rpi5-ws840 go-librespot[2033]: time="2025-06-06T07:26:38+02:00" level=trace msg="received dealer pong" Jun 06 07:26:49 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 06 07:26:49 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: Enabling plugin peppyspectrum Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: Loading plugin "peppyspectrum"... Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: Preparing to generate the ALSA configuration file Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: Reading ALSA contributions from plugins. Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: Asound.conf file written Jun 06 07:26:55 rpi5-ws840 sudo[1114]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 06 07:26:55 rpi5-ws840 sudo[1114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:55 rpi5-ws840 sudo[1114]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:55 rpi5-ws840 volumio[1619]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 06 07:26:55 rpi5-ws840 volumio[1619]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 06 07:26:55 rpi5-ws840 volumio[1619]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: Output device has changed, restarting MPD Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:55 rpi5-ws840 sudo[1120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 07:26:55 rpi5-ws840 sudo[1120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:55 rpi5-ws840 sudo[1120]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:55 rpi5-ws840 vtcs[643]: [2025-06-06 07:26:55.919] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jun 06 07:26:55 rpi5-ws840 vtcs[643]: [2025-06-06 07:26:55.919] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 06 07:26:55 rpi5-ws840 sudo[1122]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 07:26:55 rpi5-ws840 sudo[1122]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: PLUGIN START: peppyspectrum Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: Preparing to generate the ALSA configuration file Jun 06 07:26:55 rpi5-ws840 systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: Done. Jun 06 07:26:55 rpi5-ws840 sudo[1128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:26:55 rpi5-ws840 sudo[1128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: Reading ALSA contributions from plugins. Jun 06 07:26:55 rpi5-ws840 volumio[1619]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: MPD Permissions set Jun 06 07:26:55 rpi5-ws840 volumio[1619]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Jun 06 07:26:55 rpi5-ws840 sudo[1131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:55 rpi5-ws840 sudo[1131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:55 rpi5-ws840 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 07:26:55 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:55 rpi5-ws840 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jun 06 07:26:56 rpi5-ws840 systemd[1]: vtcs.service: Deactivated successfully. Jun 06 07:26:56 rpi5-ws840 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jun 06 07:26:56 rpi5-ws840 dbus-daemon[953]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.632' (uid=0 pid=1139 comm="timedatectl show --property=NTPSynchronized --valu") Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: Asound.conf file unchanged, so no further update is needed Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: Output device has changed, restarting MPD Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 sudo[1142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 07:26:56 rpi5-ws840 sudo[1142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:56 rpi5-ws840 sudo[1142]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:56 rpi5-ws840 sudo[1144]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 07:26:56 rpi5-ws840 sudo[1144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:56 rpi5-ws840 sudo[1131]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:56 rpi5-ws840 sudo[1128]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:56 rpi5-ws840 sudo[1149]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:26:56 rpi5-ws840 sudo[1149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: Preparing to generate the ALSA configuration file Jun 06 07:26:56 rpi5-ws840 sudo[1160]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:26:56 rpi5-ws840 sudo[1160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: MPD Permissions set Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: Reading ALSA contributions from plugins. Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 06 07:26:56 rpi5-ws840 systemd[1]: mpd.service: Deactivated successfully. Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 06 07:26:56 rpi5-ws840 systemd[1]: mpd.service: Consumed 2.827s CPU time. Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: Asound.conf file unchanged, so no further update is needed Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: Output device has changed, restarting MPD Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 06 07:26:56 rpi5-ws840 sudo[1169]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 sudo[1169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:56 rpi5-ws840 sudo[1169]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:56 rpi5-ws840 systemd[1]: mpd.socket: Deactivated successfully. Jun 06 07:26:56 rpi5-ws840 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 06 07:26:56 rpi5-ws840 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 06 07:26:56 rpi5-ws840 sudo[1171]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 07:26:56 rpi5-ws840 sudo[1171]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: Preparing to generate the ALSA configuration file Jun 06 07:26:56 rpi5-ws840 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 06 07:26:56 rpi5-ws840 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 06 07:26:56 rpi5-ws840 sudo[1178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:26:56 rpi5-ws840 sudo[1178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: Reading ALSA contributions from plugins. Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: MPD Permissions set Jun 06 07:26:56 rpi5-ws840 sudo[1182]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:26:56 rpi5-ws840 sudo[1182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 sudo[1149]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 sudo[1160]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: Asound.conf file unchanged, so no further update is needed Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: Output device has changed, restarting MPD Jun 06 07:26:56 rpi5-ws840 systemd[1]: mpd.service: Deactivated successfully. Jun 06 07:26:56 rpi5-ws840 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 06 07:26:56 rpi5-ws840 dbus-daemon[953]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 06 07:26:56 rpi5-ws840 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 setdatetime-helper.sh[1129]: Time is already synchronized. Jun 06 07:26:56 rpi5-ws840 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 06 07:26:56 rpi5-ws840 sudo[1192]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 06 07:26:56 rpi5-ws840 sudo[1192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:56 rpi5-ws840 sudo[1192]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:56 rpi5-ws840 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 06 07:26:56 rpi5-ws840 sudo[1194]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 06 07:26:56 rpi5-ws840 sudo[1194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:56 rpi5-ws840 sudo[1182]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:56 rpi5-ws840 sudo[1178]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: MPD Permissions set Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 sudo[1203]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:26:56 rpi5-ws840 sudo[1203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:56 rpi5-ws840 systemd[1]: mpd.socket: Deactivated successfully. Jun 06 07:26:56 rpi5-ws840 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 06 07:26:56 rpi5-ws840 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 06 07:26:56 rpi5-ws840 sudo[1206]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 06 07:26:56 rpi5-ws840 sudo[1206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 06 07:26:56 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 06 07:26:56 rpi5-ws840 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 06 07:26:56 rpi5-ws840 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 06 07:26:56 rpi5-ws840 systemd[1]: mpd.service: Deactivated successfully. Jun 06 07:26:56 rpi5-ws840 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 06 07:26:56 rpi5-ws840 sudo[1203]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:56 rpi5-ws840 sudo[1206]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:56 rpi5-ws840 systemd[1]: mpd.socket: Deactivated successfully. Jun 06 07:26:56 rpi5-ws840 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 06 07:26:56 rpi5-ws840 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 06 07:26:56 rpi5-ws840 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 06 07:26:56 rpi5-ws840 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 06 07:26:56 rpi5-ws840 sudo[1225]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 06 07:26:56 rpi5-ws840 sudo[1225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 06 07:26:56 rpi5-ws840 sudo[1225]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:57 rpi5-ws840 mpd[1227]: 2025-06-06T07:26:57 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 06 07:26:57 rpi5-ws840 systemd[1]: Started mpd.service - Music Player Daemon. Jun 06 07:26:57 rpi5-ws840 sudo[1122]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:57 rpi5-ws840 sudo[1171]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:57 rpi5-ws840 sudo[1144]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:57 rpi5-ws840 sudo[1194]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:57 rpi5-ws840 volumio[1619]: error: MPD error: The expression evaluated to a falsy value: Jun 06 07:26:57 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:26:57 rpi5-ws840 volumio[1619]: error: The expression evaluated to a falsy value: Jun 06 07:26:57 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:26:57 rpi5-ws840 volumio[1619]: error: MPD error: The expression evaluated to a falsy value: Jun 06 07:26:57 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:26:57 rpi5-ws840 volumio[1619]: error: The expression evaluated to a falsy value: Jun 06 07:26:57 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:26:57 rpi5-ws840 volumio[1619]: error: MPD error: The expression evaluated to a falsy value: Jun 06 07:26:57 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:26:57 rpi5-ws840 volumio[1619]: error: The expression evaluated to a falsy value: Jun 06 07:26:57 rpi5-ws840 volumio[1619]: assert.ok(self.idling) Jun 06 07:26:57 rpi5-ws840 volumio[1619]: error: updateQueue error: null Jun 06 07:26:58 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioGetState Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:26:59 rpi5-ws840 sudo[1241]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 06 07:26:59 rpi5-ws840 sudo[1241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:59 rpi5-ws840 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jun 06 07:26:59 rpi5-ws840 sudo[1241]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: Executing endpoint tc_getconfig Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 06 07:26:59 rpi5-ws840 vtcs[1244]: OpenSSL version mismatch. Built against 101010ef, you have 30000100 Jun 06 07:26:59 rpi5-ws840 vtcs[1244]: STARTING TidalConnect services, version: 1.5.0.45 Jun 06 07:26:59 rpi5-ws840 sudo[1258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 06 07:26:59 rpi5-ws840 sudo[1258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:26:59 rpi5-ws840 vtcs[1244]: STARTED TidalConnect services. Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: TidalConnect service stoped! Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: Executing endpoint tc_connect Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: Connecting to TidalConnect Jun 06 07:26:59 rpi5-ws840 sudo[1258]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::servicePushState Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::servicePushState Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: CoreStateMachine::pushState Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: CoreCommandRouter::volumioPushState Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: CorePlayQueue::getTrack 0 Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Jun 06 07:26:59 rpi5-ws840 vtcs[1244]: [2025-06-06 07:26:59.276] [tisoc] [error] [WebsocketServerTls.cpp:90] [WebsocketServerTls onTlsInit Error] use_certificate_chain: no start line Jun 06 07:26:59 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:26:59 rpi5-ws840 volumio[1619]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: PeppyMeterBasic ---peppymeterbasic status play Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: PeppySpectrum ---peppyspectrum status play Jun 06 07:26:59 rpi5-ws840 sudo[1286]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 06 07:26:59 rpi5-ws840 sudo[1286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: PeppySpectrum ---peppyspectrum status play Jun 06 07:26:59 rpi5-ws840 sudo[1288]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jun 06 07:26:59 rpi5-ws840 sudo[1288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:26:59 rpi5-ws840 volumio[1619]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 06 07:26:59 rpi5-ws840 sudo[1291]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jun 06 07:26:59 rpi5-ws840 sudo[1291]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:59 rpi5-ws840 volumio[1619]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 06 07:26:59 rpi5-ws840 sudo[1294]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jun 06 07:26:59 rpi5-ws840 sudo[1294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 06 07:26:59 rpi5-ws840 volumio[1619]: Error: ENOENT: no such file or directory, unlink '/tmp/Xa65oBmCNQBDG21ySqOtJrVUEDxWwUGAiX2oLnrLuqDIXAtWfhTUsPsUH7GD1SQEN3niFdB6tddezEqveRoplQPT143qkaJBgHmb' Jun 06 07:26:59 rpi5-ws840 volumio[1619]: at Object.unlinkSync (node:fs:1899:3) Jun 06 07:26:59 rpi5-ws840 volumio[1619]: at ControllerTidalconnect.deleteCertificate (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:11238) Jun 06 07:26:59 rpi5-ws840 volumio[1619]: at TidalConnectClient. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:2774) Jun 06 07:26:59 rpi5-ws840 volumio[1619]: at TidalConnectClient.emit (node:events:514:28) Jun 06 07:26:59 rpi5-ws840 volumio[1619]: at TidalConnectClient.parseData (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:44661) Jun 06 07:26:59 rpi5-ws840 volumio[1619]: at /myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41631 Jun 06 07:26:59 rpi5-ws840 volumio[1619]: at Array.forEach () Jun 06 07:26:59 rpi5-ws840 volumio[1619]: at Socket. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41509) Jun 06 07:26:59 rpi5-ws840 volumio[1619]: at Socket.emit (node:events:514:28) Jun 06 07:26:59 rpi5-ws840 volumio[1619]: at addChunk (node:internal/streams/readable:343:12) { Jun 06 07:26:59 rpi5-ws840 volumio[1619]: errno: -2, Jun 06 07:26:59 rpi5-ws840 volumio[1619]: syscall: 'unlink', Jun 06 07:26:59 rpi5-ws840 volumio[1619]: code: 'ENOENT', Jun 06 07:26:59 rpi5-ws840 volumio[1619]: path: '/tmp/Xa65oBmCNQBDG21ySqOtJrVUEDxWwUGAiX2oLnrLuqDIXAtWfhTUsPsUH7GD1SQEN3niFdB6tddezEqveRoplQPT143qkaJBgHmb' Jun 06 07:26:59 rpi5-ws840 volumio[1619]: } Jun 06 07:26:59 rpi5-ws840 volumio[1619]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 06 07:26:59 rpi5-ws840 sudo[1286]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:59 rpi5-ws840 sudo[1288]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:59 rpi5-ws840 systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Jun 06 07:26:59 rpi5-ws840 sudo[1294]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:59 rpi5-ws840 sudo[1291]: pam_unix(sudo:session): session closed for user root Jun 06 07:26:59 rpi5-ws840 sudo[1327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-06 07:25' Jun 06 07:26:59 rpi5-ws840 sudo[1327]: 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="a42e8353c03f8616e137cf760ea618e88c395461" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Jun 2 16:20:51 UTC 2025" VOLUMIO_VERSION="4.010" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f51ec0ad91e50bdfdb863e902a9e8fa2"