Jun 07 23:25:00 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 07 23:25:00 volumiopi5 volumio[1245]: info: CURURI: music-library/USB/Music/Song Jun 07 23:25:00 volumiopi5 volumio[1245]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 07 23:25:00 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 07 23:25:00 volumiopi5 volumio[1245]: info: Preload queue cleared Jun 07 23:25:01 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:01 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:01 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 07 23:25:01 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 07 23:25:01 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 07 23:25:01 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioGetBrowseSources Jun 07 23:25:01 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 07 23:25:02 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 07 23:25:02 volumiopi5 volumio[1245]: info: CURURI: music-library/USB/Music/Music Jun 07 23:25:02 volumiopi5 volumio[1245]: info: Preload queue cleared Jun 07 23:25:08 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 07 23:25:08 volumiopi5 volumio[1245]: info: CURURI: music-library/USB/Music/Song Jun 07 23:25:08 volumiopi5 volumio[1245]: info: Preload queue cleared Jun 07 23:25:11 volumiopi5 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 07 23:25:16 volumiopi5 volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 23:25:16 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 07 23:25:16 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:25:16 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 23:25:16 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:16 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:16 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:16 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:16 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:16 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:16 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:16 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 07 23:25:16 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Preparing to save Alsa Options, stopping services first Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"MPD-8"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Device 5 does not have any Mixer Control Available Jun 07 23:25:19 volumiopi5 vtcs[2035]: [2025-06-07 23:25:19.027] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 07 23:25:19 volumiopi5 vtcs[2035]: [2025-06-07 23:25:19.027] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Jun 07 23:25:19 volumiopi5 vtcs[2035]: [2025-06-07 23:25:19.027] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jun 07 23:25:19 volumiopi5 sudo[26273]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:25:19 volumiopi5 sudo[26273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Updating Volume Controller Parameters: Device: 5 Name: MPD-8 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Disabling external Volume Control Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:19 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:19 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Preparing to generate the ALSA configuration file Jun 07 23:25:19 volumiopi5 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jun 07 23:25:19 volumiopi5 systemd[1]: vtcs.service: Killing process 2042 (vtcs) with signal SIGKILL. Jun 07 23:25:19 volumiopi5 systemd[1]: vtcs.service: Deactivated successfully. Jun 07 23:25:19 volumiopi5 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jun 07 23:25:19 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:25:19 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:25:19 volumiopi5 volumio[1245]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VzJQUcaZQzWpGRJApJ75M58icWf2.df6dee348338ad12fdeeefa5343c579b.state.status' Jun 07 23:25:19 volumiopi5 volumio[1245]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 07 23:25:19 volumiopi5 volumio[1245]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 07 23:25:19 volumiopi5 volumio[1245]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Reading ALSA contributions from plugins. Jun 07 23:25:19 volumiopi5 sudo[26273]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 sudo[26280]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:25:19 volumiopi5 sudo[26280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 23:25:19 volumiopi5 sudo[26280]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Asound.conf file written Jun 07 23:25:19 volumiopi5 sudo[26308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 07 23:25:19 volumiopi5 sudo[26308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 sudo[26308]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 volumio[1245]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 07 23:25:19 volumiopi5 volumio[1245]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Output device has changed, restarting MPD Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Output device has changed, restarting Shairport Sync Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:25:19 volumiopi5 sudo[26315]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 23:25:19 volumiopi5 sudo[26316]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 23:25:19 volumiopi5 sudo[26316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 sudo[26315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 sudo[26315]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 23:25:19 volumiopi5 volumio[1245]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Output device has changed, restarting MPD Jun 07 23:25:19 volumiopi5 systemd[1]: musicservicesshield.service: Deactivated successfully. Jun 07 23:25:19 volumiopi5 systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Jun 07 23:25:19 volumiopi5 systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Jun 07 23:25:19 volumiopi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 07 23:25:19 volumiopi5 sudo[26326]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:25:19 volumiopi5 sudo[26326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 sudo[26328]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 23:25:19 volumiopi5 sudo[26328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 sudo[26328]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Output device has changed, restarting Shairport Sync Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:25:19 volumiopi5 systemd[1]: mpd.service: Deactivated successfully. Jun 07 23:25:19 volumiopi5 sudo[26326]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 sudo[26333]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 23:25:19 volumiopi5 sudo[26333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 07 23:25:19 volumiopi5 systemd[1]: mpd.socket: Deactivated successfully. Jun 07 23:25:19 volumiopi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 07 23:25:19 volumiopi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 23:25:19 volumiopi5 volumio[1245]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 sudo[26344]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:25:19 volumiopi5 sudo[26344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 sudo[26345]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:25:19 volumiopi5 sudo[26345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 volumio[1245]: info: MPD Permissions set Jun 07 23:25:19 volumiopi5 volumio[1245]: info: MPD Permissions set Jun 07 23:25:19 volumiopi5 sudo[26348]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:25:19 volumiopi5 sudo[26348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 07 23:25:19 volumiopi5 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 systemd[1]: mpd.service: Deactivated successfully. Jun 07 23:25:19 volumiopi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 07 23:25:19 volumiopi5 systemd[1]: mpd.socket: Deactivated successfully. Jun 07 23:25:19 volumiopi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 07 23:25:19 volumiopi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Starting Shairport Sync Jun 07 23:25:19 volumiopi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Starting Shairport Sync Jun 07 23:25:19 volumiopi5 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Preparing to generate the ALSA configuration file Jun 07 23:25:19 volumiopi5 sudo[26373]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 23:25:19 volumiopi5 sudo[26373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 sudo[26344]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 sudo[26375]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 23:25:19 volumiopi5 volumio[1245]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 07 23:25:19 volumiopi5 sudo[26345]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 volumio[1245]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 07 23:25:19 volumiopi5 sudo[26375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 volumio[1245]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Reading ALSA contributions from plugins. Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Asound.conf file written Jun 07 23:25:19 volumiopi5 sudo[26381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 07 23:25:19 volumiopi5 sudo[26381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 sudo[26381]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 sudo[26348]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 sudo[26372]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 07 23:25:19 volumiopi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 07 23:25:19 volumiopi5 sudo[26372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 07 23:25:19 volumiopi5 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 07 23:25:19 volumiopi5 volumio[1245]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 07 23:25:19 volumiopi5 volumio[1245]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Output device has changed, restarting MPD Jun 07 23:25:19 volumiopi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 07 23:25:19 volumiopi5 systemd[1]: shairport-sync.service: Consumed 1.136s CPU time. Jun 07 23:25:19 volumiopi5 sudo[26372]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Output device has changed, restarting Shairport Sync Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:25:19 volumiopi5 sudo[26392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 23:25:19 volumiopi5 sudo[26393]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 23:25:19 volumiopi5 sudo[26393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 sudo[26392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 23:25:19 volumiopi5 volumio[1245]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 sudo[26392]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Preparing to generate the ALSA configuration file Jun 07 23:25:19 volumiopi5 sudo[26403]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:25:19 volumiopi5 sudo[26403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 volumio[1245]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 07 23:25:19 volumiopi5 volumio[1245]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 07 23:25:19 volumiopi5 volumio[1245]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Reading ALSA contributions from plugins. Jun 07 23:25:19 volumiopi5 volumio[1245]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Jun 07 23:25:19 volumiopi5 volumio[1245]: info: MPD Permissions set Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 sudo[26406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:25:19 volumiopi5 sudo[26406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Starting Shairport Sync Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Asound.conf file unchanged, so no further update is needed Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Output device has changed, restarting MPD Jun 07 23:25:19 volumiopi5 sudo[26415]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 23:25:19 volumiopi5 sudo[26415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Output device has changed, restarting Shairport Sync Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:25:19 volumiopi5 sudo[26417]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 23:25:19 volumiopi5 sudo[26417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 sudo[26417]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 23:25:19 volumiopi5 volumio[1245]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 sudo[26420]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 23:25:19 volumiopi5 sudo[26420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 07 23:25:19 volumiopi5 sudo[26373]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 sudo[26375]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 sudo[26432]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:25:19 volumiopi5 volumio[1245]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Jun 07 23:25:19 volumiopi5 volumio[1245]: info: MPD Permissions set Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Shairport-Sync Started Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Shairport-Sync Started Jun 07 23:25:19 volumiopi5 sudo[26432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Preparing to generate the ALSA configuration file Jun 07 23:25:19 volumiopi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 07 23:25:19 volumiopi5 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 07 23:25:19 volumiopi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 07 23:25:19 volumiopi5 systemd[1]: mpd.service: Deactivated successfully. Jun 07 23:25:19 volumiopi5 sudo[26447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:25:19 volumiopi5 sudo[26447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 07 23:25:19 volumiopi5 volumio[1245]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 07 23:25:19 volumiopi5 volumio[1245]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 07 23:25:19 volumiopi5 volumio[1245]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Reading ALSA contributions from plugins. Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 sudo[26403]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 sudo[26406]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 systemd[1]: mpd.socket: Deactivated successfully. Jun 07 23:25:19 volumiopi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 07 23:25:19 volumiopi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Starting Shairport Sync Jun 07 23:25:19 volumiopi5 sudo[26459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 23:25:19 volumiopi5 sudo[26459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Asound.conf file written Jun 07 23:25:19 volumiopi5 sudo[26463]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 07 23:25:19 volumiopi5 sudo[26463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 sudo[26463]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 volumio[1245]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 07 23:25:19 volumiopi5 volumio[1245]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Output device has changed, restarting MPD Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Output device has changed, restarting Shairport Sync Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:25:19 volumiopi5 sudo[26469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 23:25:19 volumiopi5 sudo[26469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 07 23:25:19 volumiopi5 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 07 23:25:19 volumiopi5 sudo[26469]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 07 23:25:19 volumiopi5 sudo[26415]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 sudo[26471]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 23:25:19 volumiopi5 volumio[1245]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 sudo[26471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 sudo[26432]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 sudo[26475]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 07 23:25:19 volumiopi5 sudo[26475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 07 23:25:19 volumiopi5 sudo[26475]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 sudo[26498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:25:19 volumiopi5 sudo[26498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 volumio[1245]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Jun 07 23:25:19 volumiopi5 sudo[26495]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:25:19 volumiopi5 volumio[1245]: info: MPD Permissions set Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Shairport-Sync Started Jun 07 23:25:19 volumiopi5 sudo[26495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Starting Shairport Sync Jun 07 23:25:19 volumiopi5 sudo[26511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 23:25:19 volumiopi5 sudo[26511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:19 volumiopi5 sudo[26447]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 07 23:25:19 volumiopi5 systemd[1]: mpd.service: Deactivated successfully. Jun 07 23:25:19 volumiopi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 07 23:25:19 volumiopi5 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 07 23:25:19 volumiopi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 07 23:25:19 volumiopi5 sudo[26498]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 sudo[26495]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 systemd[1]: mpd.socket: Deactivated successfully. Jun 07 23:25:19 volumiopi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 07 23:25:19 volumiopi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 07 23:25:19 volumiopi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 07 23:25:19 volumiopi5 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 07 23:25:19 volumiopi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 07 23:25:19 volumiopi5 sudo[26511]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 sudo[26459]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Shairport-Sync Started Jun 07 23:25:19 volumiopi5 volumio[1245]: info: Shairport-Sync Started Jun 07 23:25:19 volumiopi5 volumio[1245]: (node:1245) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 07 23:25:19 volumiopi5 volumio[1245]: (Use `node --trace-warnings ...` to show where the warning was created) Jun 07 23:25:19 volumiopi5 volumio[1245]: (node:1245) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 07 23:25:19 volumiopi5 volumio[1245]: (node:1245) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 07 23:25:19 volumiopi5 volumio[1245]: (node:1245) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 07 23:25:19 volumiopi5 volumio[1245]: (node:1245) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 07 23:25:19 volumiopi5 sudo[26517]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 07 23:25:19 volumiopi5 sudo[26517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 07 23:25:19 volumiopi5 sudo[26517]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:20 volumiopi5 mpd[26534]: 2025-06-07T23:25:20 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 07 23:25:20 volumiopi5 systemd[1]: Started mpd.service - Music Player Daemon. Jun 07 23:25:20 volumiopi5 sudo[26420]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:20 volumiopi5 sudo[26333]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:20 volumiopi5 sudo[26393]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:20 volumiopi5 sudo[26471]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:20 volumiopi5 sudo[26316]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:20 volumiopi5 volumio[1245]: error: MPD error: The expression evaluated to a falsy value: Jun 07 23:25:20 volumiopi5 volumio[1245]: assert.ok(self.idling) Jun 07 23:25:20 volumiopi5 volumio[1245]: error: The expression evaluated to a falsy value: Jun 07 23:25:20 volumiopi5 volumio[1245]: assert.ok(self.idling) Jun 07 23:25:20 volumiopi5 volumio[1245]: error: MPD error: The expression evaluated to a falsy value: Jun 07 23:25:20 volumiopi5 volumio[1245]: assert.ok(self.idling) Jun 07 23:25:20 volumiopi5 volumio[1245]: error: The expression evaluated to a falsy value: Jun 07 23:25:20 volumiopi5 volumio[1245]: assert.ok(self.idling) Jun 07 23:25:20 volumiopi5 volumio[1245]: error: MPD error: The expression evaluated to a falsy value: Jun 07 23:25:20 volumiopi5 volumio[1245]: assert.ok(self.idling) Jun 07 23:25:20 volumiopi5 volumio[1245]: error: The expression evaluated to a falsy value: Jun 07 23:25:20 volumiopi5 volumio[1245]: assert.ok(self.idling) Jun 07 23:25:20 volumiopi5 volumio[1245]: error: MPD error: The expression evaluated to a falsy value: Jun 07 23:25:20 volumiopi5 volumio[1245]: assert.ok(self.idling) Jun 07 23:25:20 volumiopi5 volumio[1245]: error: The expression evaluated to a falsy value: Jun 07 23:25:20 volumiopi5 volumio[1245]: assert.ok(self.idling) Jun 07 23:25:20 volumiopi5 systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Jun 07 23:25:20 volumiopi5 volumio[1245]: error: updateQueue error: null Jun 07 23:25:20 volumiopi5 bash[26559]: cset: --> shielding system active with Jun 07 23:25:20 volumiopi5 bash[26559]: cset: "system" cpuset of CPUSPEC(2-3) with 181 tasks running Jun 07 23:25:20 volumiopi5 bash[26559]: cset: "user" cpuset of CPUSPEC(0-1) with 3 tasks running Jun 07 23:25:20 volumiopi5 systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Jun 07 23:25:22 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:25:22 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:25:22 volumiopi5 volumio[1245]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:25:22 volumiopi5 volumio[1245]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:25:22 volumiopi5 sudo[26591]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 07 23:25:22 volumiopi5 sudo[26591]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:22 volumiopi5 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jun 07 23:25:22 volumiopi5 sudo[26591]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:22 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:25:22 volumiopi5 volumio[1245]: info: Executing endpoint tc_getconfig Jun 07 23:25:22 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 07 23:25:22 volumiopi5 vtcs[26602]: OpenSSL version mismatch. Built against 101010ef, you have 30000100 Jun 07 23:25:22 volumiopi5 vtcs[26602]: STARTING TidalConnect services, version: 1.5.0.45 Jun 07 23:25:22 volumiopi5 vtcs[26602]: STARTED TidalConnect services. Jun 07 23:25:22 volumiopi5 volumio[1245]: info: Executing endpoint tc_connect Jun 07 23:25:22 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 07 23:25:22 volumiopi5 volumio[1245]: info: Connecting to TidalConnect Jun 07 23:25:22 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:25:22 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:22 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:22 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:22 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:22 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:22 volumiopi5 volumio[1245]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jun 07 23:25:22 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:25:22 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:22 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:22 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:22 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:22 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:22 volumiopi5 volumio[1245]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jun 07 23:25:22 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:25:22 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:25:22 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:25:22 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:25:22 volumiopi5 volumio[1245]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VzJQUcaZQzWpGRJApJ75M58icWf2.df6dee348338ad12fdeeefa5343c579b.state.status' Jun 07 23:25:22 volumiopi5 volumio[1245]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VzJQUcaZQzWpGRJApJ75M58icWf2.df6dee348338ad12fdeeefa5343c579b.state.status' Jun 07 23:25:22 volumiopi5 vtcs[26602]: [2025-06-07 23:25:22.260] [tisoc] [error] [WebsocketServerTls.cpp:90] [WebsocketServerTls onTlsInit Error] use_certificate_chain: no start line Jun 07 23:25:22 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:25:22 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:25:22 volumiopi5 volumio[1245]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:25:22 volumiopi5 volumio[1245]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:25:22 volumiopi5 sudo[26627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 07 23:25:22 volumiopi5 sudo[26627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:22 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:25:22 volumiopi5 sudo[26627]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:22 volumiopi5 volumio[1245]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:25:22 volumiopi5 volumio[1245]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:25:22 volumiopi5 sudo[26639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 07 23:25:22 volumiopi5 sudo[26639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:22 volumiopi5 sudo[26639]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:22 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:25:22 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:25:22 volumiopi5 volumio[1245]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:25:22 volumiopi5 volumio[1245]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:25:22 volumiopi5 sudo[26651]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 07 23:25:22 volumiopi5 sudo[26651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:22 volumiopi5 sudo[26651]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:22 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:25:22 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:25:22 volumiopi5 volumio[1245]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:25:22 volumiopi5 volumio[1245]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:25:22 volumiopi5 sudo[26666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 07 23:25:22 volumiopi5 sudo[26666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:22 volumiopi5 sudo[26666]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:22 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:25:22 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:25:22 volumiopi5 volumio[1245]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:25:22 volumiopi5 volumio[1245]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:25:22 volumiopi5 sudo[26678]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 07 23:25:22 volumiopi5 sudo[26678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:22 volumiopi5 sudo[26678]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:23 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 07 23:25:23 volumiopi5 volumio[1245]: info: CoreStateMachine::getcurrentVolume Jun 07 23:25:23 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioRetrievevolume Jun 07 23:25:23 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:23 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:23 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:23 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:23 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:25:23 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:25:23 volumiopi5 volumio[1245]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.VzJQUcaZQzWpGRJApJ75M58icWf2.df6dee348338ad12fdeeefa5343c579b.state.status' Jun 07 23:25:25 volumiopi5 volumio[1245]: info: TidalConnect service started! Jun 07 23:25:25 volumiopi5 volumio[1245]: info: TidalConnect service started! Jun 07 23:25:25 volumiopi5 volumio[1245]: info: TidalConnect service started! Jun 07 23:25:25 volumiopi5 volumio[1245]: info: TidalConnect service started! Jun 07 23:25:25 volumiopi5 volumio[1245]: info: TidalConnect service started! Jun 07 23:25:25 volumiopi5 volumio[1245]: info: TidalConnect service started! Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Preload queue cleared Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::ClearQueue Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::stop Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::serviceStop Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Received STOP, but no service to execute it Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::clearPlayQueue Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::saveQueue Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushQueue Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::addQueueItems Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::addQueueItems Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Preload queue cleared Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Adding Item to queue: music-library/USB/Music/Song/Andrea Bocelli - Romanza Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Exploding uri music-library/USB/Music/Song/Andrea Bocelli - Romanza in service mpd Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ALBUMART /albumart?cacheid=352&web=//extralarge&path=%2Fmnt%2FUSB%2FMusic%2FSong%2FAndrea%20Bocelli%20-%20Romanza%2F01-Andrea%20Bocelli-Por%20ti%20volar%C3%A9%20(Con%20te%20partir%C3%B2).wav&metadata=false Jun 07 23:25:28 volumiopi5 volumio[1245]: info: URI /mnt/USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ALBUMART /albumart?cacheid=352&web=//extralarge&path=%2Fmnt%2FUSB%2FMusic%2FSong%2FAndrea%20Bocelli%20-%20Romanza%2F02-Andrea%20Bocelli%20feat.%20Gerardina%20Trovato-Vivere.wav&metadata=false Jun 07 23:25:28 volumiopi5 volumio[1245]: info: URI /mnt/USB/Music/Song/Andrea Bocelli - Romanza/02-Andrea Bocelli feat. Gerardina Trovato-Vivere.wav Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ALBUMART /albumart?cacheid=352&web=//extralarge&path=%2Fmnt%2FUSB%2FMusic%2FSong%2FAndrea%20Bocelli%20-%20Romanza%2F03-Andrea%20Bocelli-Per%20amore.wav&metadata=false Jun 07 23:25:28 volumiopi5 volumio[1245]: info: URI /mnt/USB/Music/Song/Andrea Bocelli - Romanza/03-Andrea Bocelli-Per amore.wav Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ALBUMART /albumart?cacheid=352&web=//extralarge&path=%2Fmnt%2FUSB%2FMusic%2FSong%2FAndrea%20Bocelli%20-%20Romanza%2F04-Andrea%20Bocelli-Il%20mare%20calmo%20della%20sera.wav&metadata=false Jun 07 23:25:28 volumiopi5 volumio[1245]: info: URI /mnt/USB/Music/Song/Andrea Bocelli - Romanza/04-Andrea Bocelli-Il mare calmo della sera.wav Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ALBUMART /albumart?cacheid=352&web=//extralarge&path=%2Fmnt%2FUSB%2FMusic%2FSong%2FAndrea%20Bocelli%20-%20Romanza%2F05-Andrea%20Bocelli-Caruso.wav&metadata=false Jun 07 23:25:28 volumiopi5 volumio[1245]: info: URI /mnt/USB/Music/Song/Andrea Bocelli - Romanza/05-Andrea Bocelli-Caruso.wav Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ALBUMART /albumart?cacheid=352&web=//extralarge&path=%2Fmnt%2FUSB%2FMusic%2FSong%2FAndrea%20Bocelli%20-%20Romanza%2F06-Andrea%20Bocelli-Macchine%20da%20guerra.wav&metadata=false Jun 07 23:25:28 volumiopi5 volumio[1245]: info: URI /mnt/USB/Music/Song/Andrea Bocelli - Romanza/06-Andrea Bocelli-Macchine da guerra.wav Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ALBUMART /albumart?cacheid=352&web=//extralarge&path=%2Fmnt%2FUSB%2FMusic%2FSong%2FAndrea%20Bocelli%20-%20Romanza%2F07-Andrea%20Bocelli-Le%20tue%20parole.wav&metadata=false Jun 07 23:25:28 volumiopi5 volumio[1245]: info: URI /mnt/USB/Music/Song/Andrea Bocelli - Romanza/07-Andrea Bocelli-Le tue parole.wav Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ALBUMART /albumart?cacheid=352&web=//extralarge&path=%2Fmnt%2FUSB%2FMusic%2FSong%2FAndrea%20Bocelli%20-%20Romanza%2F08-Andrea%20Bocelli%20%26%20Giorgia-Vivo%20per%20lei.wav&metadata=false Jun 07 23:25:28 volumiopi5 volumio[1245]: info: URI /mnt/USB/Music/Song/Andrea Bocelli - Romanza/08-Andrea Bocelli & Giorgia-Vivo per lei.wav Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ALBUMART /albumart?cacheid=352&web=//extralarge&path=%2Fmnt%2FUSB%2FMusic%2FSong%2FAndrea%20Bocelli%20-%20Romanza%2F09-Andrea%20Bocelli-Romanza.wav&metadata=false Jun 07 23:25:28 volumiopi5 volumio[1245]: info: URI /mnt/USB/Music/Song/Andrea Bocelli - Romanza/09-Andrea Bocelli-Romanza.wav Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ALBUMART /albumart?cacheid=352&web=//extralarge&path=%2Fmnt%2FUSB%2FMusic%2FSong%2FAndrea%20Bocelli%20-%20Romanza%2F10-Andrea%20Bocelli-La%20Luna%20che%20non%20c%E2%80%99%C3%A8.wav&metadata=false Jun 07 23:25:28 volumiopi5 volumio[1245]: info: URI /mnt/USB/Music/Song/Andrea Bocelli - Romanza/10-Andrea Bocelli-La Luna che non c’è.wav Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ALBUMART /albumart?cacheid=352&web=//extralarge&path=%2Fmnt%2FUSB%2FMusic%2FSong%2FAndrea%20Bocelli%20-%20Romanza%2F11-Andrea%20Bocelli-Rapsodia%20(Rhapsody).wav&metadata=false Jun 07 23:25:28 volumiopi5 volumio[1245]: info: URI /mnt/USB/Music/Song/Andrea Bocelli - Romanza/11-Andrea Bocelli-Rapsodia (Rhapsody).wav Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ALBUMART /albumart?cacheid=352&web=//extralarge&path=%2Fmnt%2FUSB%2FMusic%2FSong%2FAndrea%20Bocelli%20-%20Romanza%2F12-Andrea%20Bocelli-Voglio%20restare%20cos%C3%AC%20(I%20Want%20to%20Stay%20Like%20This).wav&metadata=false Jun 07 23:25:28 volumiopi5 volumio[1245]: info: URI /mnt/USB/Music/Song/Andrea Bocelli - Romanza/12-Andrea Bocelli-Voglio restare così (I Want to Stay Like This).wav Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ALBUMART /albumart?cacheid=352&web=//extralarge&path=%2Fmnt%2FUSB%2FMusic%2FSong%2FAndrea%20Bocelli%20-%20Romanza%2F13-Andrea%20Bocelli-E%20chiove%20(And%20It%20Rains).wav&metadata=false Jun 07 23:25:28 volumiopi5 volumio[1245]: info: URI /mnt/USB/Music/Song/Andrea Bocelli - Romanza/13-Andrea Bocelli-E chiove (And It Rains).wav Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ALBUMART /albumart?cacheid=352&web=//extralarge&path=%2Fmnt%2FUSB%2FMusic%2FSong%2FAndrea%20Bocelli%20-%20Romanza%2F14-Andrea%20Bocelli%20feat.%20John%20Miles-Miserere.wav&metadata=false Jun 07 23:25:28 volumiopi5 volumio[1245]: info: URI /mnt/USB/Music/Song/Andrea Bocelli - Romanza/14-Andrea Bocelli feat. John Miles-Miserere.wav Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ALBUMART /albumart?cacheid=352&web=//extralarge&path=%2Fmnt%2FUSB%2FMusic%2FSong%2FAndrea%20Bocelli%20-%20Romanza%2F15-Sarah%20Brightman%20%26%20Andrea%20Bocelli-Time%20to%20Say%20Goodbye.wav&metadata=false Jun 07 23:25:28 volumiopi5 volumio[1245]: info: URI /mnt/USB/Music/Song/Andrea Bocelli - Romanza/15-Sarah Brightman & Andrea Bocelli-Time to Say Goodbye.wav Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushQueue Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::saveQueue Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::updateTrackBlock Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrackBlock Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPlay Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: UNSET VOLATILE: Service: undefined Jun 07 23:25:28 volumiopi5 volumio[1245]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth Jun 07 23:25:28 volumiopi5 volumio[1245]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Jun 07 23:25:28 volumiopi5 volumio[1245]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::play index 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 07 23:25:28 volumiopi5 volumio[1245]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::stop Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::resetVolumioState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::getcurrentVolume Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioRetrievevolume Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::play index undefined Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioStop Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::stop Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::startPlaybackTimer Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::clearAddPlayTracks USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand stop Jun 07 23:25:28 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:25:28 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand stop took 8 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand clear Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces system playlist update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Ignoring MPD Status Update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand clear took 1 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand add "USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav" Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces system playlist update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Ignoring MPD Status Update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces system playlist update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Ignoring MPD Status Update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces system playlist update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Ignoring MPD Status Update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces system playlist update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Ignoring MPD Status Update Jun 07 23:25:28 volumiopi5 volumio[1245]: error: updateQueue error: null Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces system playlist update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Ignoring MPD Status Update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ------------------------------ 2ms Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand add "USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav" took 1 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ------------------------------ 1ms Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ------------------------------ 1ms Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ------------------------------ 1ms Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ------------------------------ 1ms Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces system playlist update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Ignoring MPD Status Update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces system playlist update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Ignoring MPD Status Update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces system playlist update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Ignoring MPD Status Update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces system playlist update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Ignoring MPD Status Update Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ------------------------------ 1ms Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand play took 1 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ------------------------------ 1ms Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ------------------------------ 0ms Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ------------------------------ 0ms Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ------------------------------ 0ms Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces state update: player Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::getState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces state update: player Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::getState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces state update: player Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::getState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces state update: player Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::getState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces state update: player Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::getState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jun 07 23:25:28 volumiopi5 volumio[1245]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces state update: player Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand status took 10 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::getState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand status took 10 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand status took 9 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand status took 9 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand status took 9 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces state update: player Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::getState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces state update: player Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::getState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces state update: player Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::getState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Jun 07 23:25:28 volumiopi5 volumio[1245]: ---------------------------- MPD announces state update: player Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::getState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand status took 4 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand status took 3 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand status took 2 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand status took 2 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand status took 2 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseState Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","artist":null,"album":null,"uri":"USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","trackType":"wav"} Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: CURRENT POSITION 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState stateService play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: STATE SERVICE {"status":"play","position":0,"seek":458,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","artist":null,"album":null,"uri":"USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","trackType":"wav"} Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: CURRENT POSITION 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState stateService play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState currentStatus play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Received an update from plugin. extracting info from payload Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: STATE SERVICE {"status":"play","position":0,"seek":458,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","artist":null,"album":null,"uri":"USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","trackType":"wav"} Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: CURRENT POSITION 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState stateService play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState currentStatus play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Received an update from plugin. extracting info from payload Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: STATE SERVICE {"status":"play","position":0,"seek":458,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","artist":null,"album":null,"uri":"USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","trackType":"wav"} Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: CURRENT POSITION 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState stateService play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState currentStatus play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Received an update from plugin. extracting info from payload Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: STATE SERVICE {"status":"play","position":0,"seek":458,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","artist":null,"album":null,"uri":"USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","trackType":"wav"} Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: CURRENT POSITION 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState stateService play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState currentStatus play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Received an update from plugin. extracting info from payload Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ------------------------------ 39ms Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ------------------------------ 60ms Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ------------------------------ 59ms Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ------------------------------ 59ms Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ------------------------------ 59ms Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand playlistinfo took 45 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand playlistinfo took 43 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand playlistinfo took 43 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand playlistinfo took 43 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: info: sendMpdCommand playlistinfo took 42 milliseconds Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1214,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","artist":null,"album":null,"uri":"USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","trackType":"wav"} Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: CURRENT POSITION 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState stateService play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState currentStatus play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Received an update from plugin. extracting info from payload Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1214,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","artist":null,"album":null,"uri":"USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","trackType":"wav"} Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: CURRENT POSITION 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState stateService play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState currentStatus play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Received an update from plugin. extracting info from payload Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1214,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","artist":null,"album":null,"uri":"USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","trackType":"wav"} Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: CURRENT POSITION 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState stateService play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState currentStatus play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Received an update from plugin. extracting info from payload Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1214,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","artist":null,"album":null,"uri":"USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","trackType":"wav"} Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: CURRENT POSITION 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState stateService play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState currentStatus play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Received an update from plugin. extracting info from payload Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: ControllerMpd::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1214,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","artist":null,"album":null,"uri":"USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","trackType":"wav"} Jun 07 23:25:28 volumiopi5 volumio[1245]: verbose: CURRENT POSITION 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState stateService play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState currentStatus play Jun 07 23:25:28 volumiopi5 volumio[1245]: info: Received an update from plugin. extracting info from payload Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:28 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:28 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:29 volumiopi5 volumio[1245]: info: ------------------------------ 86ms Jun 07 23:25:29 volumiopi5 volumio[1245]: info: ------------------------------ 84ms Jun 07 23:25:29 volumiopi5 volumio[1245]: info: ------------------------------ 84ms Jun 07 23:25:29 volumiopi5 volumio[1245]: info: ------------------------------ 83ms Jun 07 23:25:29 volumiopi5 volumio[1245]: info: ------------------------------ 84ms Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is playing Jun 07 23:25:29 volumiopi5 volumio[1245]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is playing Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is playing Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is playing Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is playing Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is playing Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is playing Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is playing Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is playing Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is playing Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is playing Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is playing Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is playing Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is playing Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is playing Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is playing Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is playing Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is playing Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:29 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:31 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:31 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:31 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:31 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:31 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:31 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:31 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:31 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:31 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:31 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:31 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:31 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:31 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:31 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:31 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:31 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:31 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:31 volumiopi5 volumio[1245]: info: FusionDsp - Clipping Monitor started Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPause Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::pause Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::stPlaybackTimer Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::servicePause Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePause Jun 07 23:25:33 volumiopi5 volumio[1245]: info: ControllerMpd::pause Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand pause Jun 07 23:25:33 volumiopi5 volumio[1245]: info: sendMpdCommand pause took 1 milliseconds Jun 07 23:25:33 volumiopi5 volumio[1245]: info: Jun 07 23:25:33 volumiopi5 volumio[1245]: ---------------------------- MPD announces state update: player Jun 07 23:25:33 volumiopi5 volumio[1245]: info: ControllerMpd::getState Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jun 07 23:25:33 volumiopi5 volumio[1245]: info: Jun 07 23:25:33 volumiopi5 volumio[1245]: ---------------------------- MPD announces state update: player Jun 07 23:25:33 volumiopi5 volumio[1245]: info: ControllerMpd::getState Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jun 07 23:25:33 volumiopi5 volumio[1245]: info: Jun 07 23:25:33 volumiopi5 volumio[1245]: ---------------------------- MPD announces state update: player Jun 07 23:25:33 volumiopi5 volumio[1245]: info: ControllerMpd::getState Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jun 07 23:25:33 volumiopi5 volumio[1245]: info: Jun 07 23:25:33 volumiopi5 volumio[1245]: ---------------------------- MPD announces state update: player Jun 07 23:25:33 volumiopi5 volumio[1245]: info: ControllerMpd::getState Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jun 07 23:25:33 volumiopi5 volumio[1245]: info: Jun 07 23:25:33 volumiopi5 volumio[1245]: ---------------------------- MPD announces state update: player Jun 07 23:25:33 volumiopi5 volumio[1245]: info: ControllerMpd::getState Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jun 07 23:25:33 volumiopi5 volumio[1245]: info: sendMpdCommand status took 1 milliseconds Jun 07 23:25:33 volumiopi5 volumio[1245]: info: sendMpdCommand status took 1 milliseconds Jun 07 23:25:33 volumiopi5 volumio[1245]: info: sendMpdCommand status took 1 milliseconds Jun 07 23:25:33 volumiopi5 volumio[1245]: info: sendMpdCommand status took 1 milliseconds Jun 07 23:25:33 volumiopi5 volumio[1245]: info: sendMpdCommand status took 1 milliseconds Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseState Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseState Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseState Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseState Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseState Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 07 23:25:33 volumiopi5 volumio[1245]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 07 23:25:33 volumiopi5 volumio[1245]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 07 23:25:33 volumiopi5 volumio[1245]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 07 23:25:33 volumiopi5 volumio[1245]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 07 23:25:33 volumiopi5 volumio[1245]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jun 07 23:25:33 volumiopi5 volumio[1245]: info: ControllerMpd::pushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9078,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","artist":null,"album":null,"uri":"USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","trackType":"wav"} Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: CURRENT POSITION 0 Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState stateService pause Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState currentStatus pause Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:33 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::stPlaybackTimer Jun 07 23:25:33 volumiopi5 volumio[1245]: info: ControllerMpd::pushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9078,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","artist":null,"album":null,"uri":"USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","trackType":"wav"} Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: CURRENT POSITION 0 Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState stateService pause Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState currentStatus pause Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:33 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::stPlaybackTimer Jun 07 23:25:33 volumiopi5 volumio[1245]: info: ControllerMpd::pushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9078,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","artist":null,"album":null,"uri":"USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","trackType":"wav"} Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: CURRENT POSITION 0 Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState stateService pause Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState currentStatus pause Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:33 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::stPlaybackTimer Jun 07 23:25:33 volumiopi5 volumio[1245]: info: ControllerMpd::pushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9078,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","artist":null,"album":null,"uri":"USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","trackType":"wav"} Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: CURRENT POSITION 0 Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState stateService pause Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState currentStatus pause Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:33 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::stPlaybackTimer Jun 07 23:25:33 volumiopi5 volumio[1245]: info: ControllerMpd::pushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9078,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","artist":null,"album":null,"uri":"USB/Music/Song/Andrea Bocelli - Romanza/01-Andrea Bocelli-Por ti volaré (Con te partirò).wav","trackType":"wav"} Jun 07 23:25:33 volumiopi5 volumio[1245]: verbose: CURRENT POSITION 0 Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState stateService pause Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::syncState currentStatus pause Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:33 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:33 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:33 volumiopi5 volumio[1245]: info: CoreStateMachine::stPlaybackTimer Jun 07 23:25:33 volumiopi5 volumio[1245]: info: ------------------------------ 21ms Jun 07 23:25:33 volumiopi5 volumio[1245]: info: ------------------------------ 20ms Jun 07 23:25:33 volumiopi5 volumio[1245]: info: ------------------------------ 20ms Jun 07 23:25:33 volumiopi5 volumio[1245]: info: ------------------------------ 20ms Jun 07 23:25:33 volumiopi5 volumio[1245]: info: ------------------------------ 20ms Jun 07 23:25:33 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:25:33 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:25:33 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:25:33 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:25:33 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:25:33 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:25:33 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:25:33 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:25:33 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:25:33 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:25:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 23:25:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 07 23:25:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:25:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 23:25:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 07 23:25:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 07 23:25:58 volumiopi5 volumio[1245]: info: Preparing to save Alsa Options, stopping services first Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPause Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreStateMachine::pause Jun 07 23:25:58 volumiopi5 volumio[1245]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"MPD-8"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 23:25:58 volumiopi5 volumio[1245]: info: Device 5 does not have any Mixer Control Available Jun 07 23:25:58 volumiopi5 vtcs[26602]: [2025-06-07 23:25:58.633] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jun 07 23:25:58 volumiopi5 vtcs[26602]: [2025-06-07 23:25:58.634] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 07 23:25:58 volumiopi5 sudo[26855]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:25:58 volumiopi5 sudo[26855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 07 23:25:58 volumiopi5 volumio[1245]: info: Updating Volume Controller Parameters: Device: 5 Name: MPD-8 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 07 23:25:58 volumiopi5 volumio[1245]: info: Disabling external Volume Control Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:25:58 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:25:58 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:25:58 volumiopi5 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 07 23:25:58 volumiopi5 volumio[1245]: info: Preparing to generate the ALSA configuration file Jun 07 23:25:58 volumiopi5 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jun 07 23:25:58 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:25:58 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:25:58 volumiopi5 systemd[1]: vtcs.service: Deactivated successfully. Jun 07 23:25:58 volumiopi5 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jun 07 23:25:58 volumiopi5 volumio[1245]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 07 23:25:58 volumiopi5 volumio[1245]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 07 23:25:58 volumiopi5 sudo[26863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:25:58 volumiopi5 sudo[26863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:58 volumiopi5 volumio[1245]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jun 07 23:25:58 volumiopi5 volumio[1245]: info: Reading ALSA contributions from plugins. Jun 07 23:25:58 volumiopi5 dbus-daemon[859]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.43' (uid=0 pid=26860 comm="timedatectl show --property=NTPSynchronized --valu") Jun 07 23:25:58 volumiopi5 sudo[26855]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 07 23:25:58 volumiopi5 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 23:25:58 volumiopi5 sudo[26863]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 07 23:25:58 volumiopi5 volumio[1245]: info: Asound.conf file unchanged, so no further update is needed Jun 07 23:25:58 volumiopi5 volumio[1245]: info: Output device has changed, restarting MPD Jun 07 23:25:58 volumiopi5 volumio[1245]: info: Output device has changed, restarting Shairport Sync Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:25:58 volumiopi5 sudo[26875]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 23:25:58 volumiopi5 sudo[26875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:58 volumiopi5 sudo[26877]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 23:25:58 volumiopi5 sudo[26875]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:58 volumiopi5 sudo[26877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:58 volumiopi5 volumio[1245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 23:25:58 volumiopi5 volumio[1245]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:58 volumiopi5 systemd[1]: musicservicesshield.service: Deactivated successfully. Jun 07 23:25:58 volumiopi5 systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Jun 07 23:25:58 volumiopi5 systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Jun 07 23:25:58 volumiopi5 volumio[1245]: info: MPD Permissions set Jun 07 23:25:58 volumiopi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:58 volumiopi5 sudo[26888]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:25:58 volumiopi5 sudo[26888]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:58 volumiopi5 sudo[26889]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:25:58 volumiopi5 sudo[26889]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:25:58 volumiopi5 systemd[1]: mpd.service: Deactivated successfully. Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:58 volumiopi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 07 23:25:58 volumiopi5 systemd[1]: mpd.socket: Deactivated successfully. Jun 07 23:25:58 volumiopi5 volumio[1245]: info: Starting Shairport Sync Jun 07 23:25:58 volumiopi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 07 23:25:58 volumiopi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 07 23:25:58 volumiopi5 dbus-daemon[859]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 07 23:25:58 volumiopi5 volumio[1245]: info: Preparing to generate the ALSA configuration file Jun 07 23:25:58 volumiopi5 setdatetime-helper.sh[26846]: Time is not synchronized. Attempting to sync... Jun 07 23:25:58 volumiopi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 07 23:25:58 volumiopi5 sudo[26898]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 23:25:58 volumiopi5 sudo[26898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:58 volumiopi5 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 07 23:25:58 volumiopi5 volumio[1245]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 07 23:25:58 volumiopi5 volumio[1245]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 07 23:25:58 volumiopi5 volumio[1245]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jun 07 23:25:58 volumiopi5 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 07 23:25:58 volumiopi5 volumio[1245]: info: Reading ALSA contributions from plugins. Jun 07 23:25:58 volumiopi5 volumio[1245]: info: Asound.conf file written Jun 07 23:25:58 volumiopi5 sudo[26909]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 07 23:25:58 volumiopi5 sudo[26909]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:58 volumiopi5 sudo[26909]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:58 volumiopi5 volumio[1245]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 07 23:25:58 volumiopi5 volumio[1245]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 07 23:25:58 volumiopi5 volumio[1245]: info: Output device has changed, restarting MPD Jun 07 23:25:58 volumiopi5 volumio[1245]: info: Output device has changed, restarting Shairport Sync Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:25:58 volumiopi5 sudo[26915]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 23:25:58 volumiopi5 sudo[26915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:58 volumiopi5 sudo[26917]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 23:25:58 volumiopi5 sudo[26915]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:58 volumiopi5 sudo[26917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:58 volumiopi5 sudo[26889]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:58 volumiopi5 volumio[1245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 23:25:58 volumiopi5 volumio[1245]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 07 23:25:58 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:58 volumiopi5 sudo[26888]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:58 volumiopi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 07 23:25:58 volumiopi5 sudo[26905]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 07 23:25:58 volumiopi5 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 07 23:25:58 volumiopi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 07 23:25:58 volumiopi5 systemd[1]: shairport-sync.service: Consumed 1.521s CPU time. Jun 07 23:25:58 volumiopi5 sudo[26905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 07 23:25:58 volumiopi5 sudo[26905]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:58 volumiopi5 volumio[1245]: info: MPD Permissions set Jun 07 23:25:58 volumiopi5 sudo[26931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:25:58 volumiopi5 sudo[26931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:59 volumiopi5 sudo[26933]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:25:59 volumiopi5 sudo[26933]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:59 volumiopi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 07 23:25:59 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:59 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:59 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:59 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:59 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:59 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:59 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:59 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:59 volumiopi5 sudo[26898]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:59 volumiopi5 systemd[1]: mpd.service: Deactivated successfully. Jun 07 23:25:59 volumiopi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 07 23:25:59 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:25:59 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:25:59 volumiopi5 sudo[26954]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sat, 07 Jun 2025 14:25:59 GMT#015' Jun 07 23:25:59 volumiopi5 sudo[26954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 07 23:25:59 volumiopi5 volumio[1245]: info: Shairport-Sync Started Jun 07 23:25:59 volumiopi5 volumio[1245]: info: Starting Shairport Sync Jun 07 23:25:59 volumiopi5 setdatetime-helper.sh[26957]: Sat Jun 7 23:25:59 JST 2025 Jun 07 23:25:59 volumiopi5 sudo[26931]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:59 volumiopi5 systemd-journald[426]: Time jumped backwards, rotating. Jun 07 23:25:59 volumiopi5 sudo[26954]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:59 volumiopi5 setdatetime-helper.sh[26846]: Time synchronized successfully. Jun 07 23:25:59 volumiopi5 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 07 23:25:59 volumiopi5 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 07 23:25:59 volumiopi5 sudo[26933]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:59 volumiopi5 systemd[1]: mpd.socket: Deactivated successfully. Jun 07 23:25:59 volumiopi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 07 23:25:59 volumiopi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 07 23:25:59 volumiopi5 sudo[26961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 23:25:59 volumiopi5 sudo[26961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:25:59 volumiopi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 07 23:25:59 volumiopi5 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 07 23:25:59 volumiopi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 07 23:25:59 volumiopi5 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 07 23:25:59 volumiopi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 07 23:25:59 volumiopi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 07 23:25:59 volumiopi5 sudo[26961]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:59 volumiopi5 volumio[1245]: info: Shairport-Sync Started Jun 07 23:25:59 volumiopi5 sudo[26965]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 07 23:25:59 volumiopi5 sudo[26965]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 07 23:25:59 volumiopi5 sudo[26965]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:59 volumiopi5 mpd[26981]: 2025-06-07T23:25:59 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 07 23:25:59 volumiopi5 systemd[1]: Started mpd.service - Music Player Daemon. Jun 07 23:25:59 volumiopi5 sudo[26877]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:59 volumiopi5 sudo[26917]: pam_unix(sudo:session): session closed for user root Jun 07 23:25:59 volumiopi5 volumio[1245]: error: updateQueue error: null Jun 07 23:25:59 volumiopi5 volumio[1245]: error: updateQueue error: null Jun 07 23:25:59 volumiopi5 systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Jun 07 23:26:00 volumiopi5 bash[26999]: cset: --> shielding system active with Jun 07 23:26:00 volumiopi5 bash[26999]: cset: "system" cpuset of CPUSPEC(2-3) with 158 tasks running Jun 07 23:26:00 volumiopi5 bash[26999]: cset: "user" cpuset of CPUSPEC(0-1) with 3 tasks running Jun 07 23:26:00 volumiopi5 systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Jun 07 23:26:01 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:26:01 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:26:01 volumiopi5 volumio[1245]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:26:01 volumiopi5 volumio[1245]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:26:01 volumiopi5 sudo[27015]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 07 23:26:01 volumiopi5 sudo[27015]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:01 volumiopi5 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jun 07 23:26:01 volumiopi5 sudo[27015]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:01 volumiopi5 volumio[1245]: info: Executing endpoint tc_getconfig Jun 07 23:26:01 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 07 23:26:01 volumiopi5 vtcs[27018]: OpenSSL version mismatch. Built against 101010ef, you have 30000100 Jun 07 23:26:01 volumiopi5 vtcs[27018]: STARTING TidalConnect services, version: 1.5.0.45 Jun 07 23:26:01 volumiopi5 vtcs[27018]: STARTED TidalConnect services. Jun 07 23:26:01 volumiopi5 volumio[1245]: info: Executing endpoint tc_connect Jun 07 23:26:01 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 07 23:26:01 volumiopi5 volumio[1245]: info: Connecting to TidalConnect Jun 07 23:26:01 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:26:01 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:26:01 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:01 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:26:01 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:26:01 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:26:01 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:01 volumiopi5 volumio[1245]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jun 07 23:26:01 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:26:01 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:26:01 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:01 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:26:01 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:26:01 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:26:01 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:01 volumiopi5 volumio[1245]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jun 07 23:26:01 volumiopi5 vtcs[27018]: [2025-06-07 23:26:01.894] [tisoc] [error] [WebsocketServerTls.cpp:90] [WebsocketServerTls onTlsInit Error] use_certificate_chain: no start line Jun 07 23:26:01 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:26:01 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:26:01 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:26:01 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:26:01 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:26:01 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:26:01 volumiopi5 volumio[1245]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:26:01 volumiopi5 volumio[1245]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:26:01 volumiopi5 sudo[27043]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 07 23:26:01 volumiopi5 sudo[27043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:01 volumiopi5 sudo[27043]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:02 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:26:02 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:26:02 volumiopi5 volumio[1245]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:26:02 volumiopi5 volumio[1245]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:26:02 volumiopi5 sudo[27055]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 07 23:26:02 volumiopi5 sudo[27055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:02 volumiopi5 sudo[27055]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:02 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 07 23:26:02 volumiopi5 volumio[1245]: info: CoreStateMachine::getcurrentVolume Jun 07 23:26:02 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioRetrievevolume Jun 07 23:26:02 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:26:02 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:02 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:26:02 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:26:02 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:26:02 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:26:02 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:26:04 volumiopi5 volumio[1245]: info: TidalConnect service started! Jun 07 23:26:04 volumiopi5 volumio[1245]: info: TidalConnect service started! Jun 07 23:26:05 volumiopi5 volumio[1245]: info: TidalConnect service started! Jun 07 23:26:11 volumiopi5 kernel: usb 3-2: USB disconnect, device number 2 Jun 07 23:26:11 volumiopi5 kernel: usb 3-2: new high-speed USB device number 3 using xhci-hcd Jun 07 23:26:11 volumiopi5 kernel: usb 3-2: New USB device found, idVendor=23ba, idProduct=0110, bcdDevice= 1.02 Jun 07 23:26:11 volumiopi5 kernel: usb 3-2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Jun 07 23:26:11 volumiopi5 kernel: usb 3-2: Product: MPD-8 Jun 07 23:26:11 volumiopi5 kernel: usb 3-2: Manufacturer: AKDesign Jun 07 23:26:11 volumiopi5 kernel: usb 3-2: SerialNumber: 00100000 Jun 07 23:26:11 volumiopi5 kernel: usb 3-2: 1:1: cannot set freq 384000 (v2/v3): err -71 Jun 07 23:26:11 volumiopi5 kernel: usb 3-2: parse_audio_format_rates_v2v3(): unable to retrieve number of sample rates (clock 41) Jun 07 23:26:12 volumiopi5 kernel: usb 3-2: parse_audio_format_rates_v2v3(): unable to retrieve number of sample rates (clock 41) Jun 07 23:26:12 volumiopi5 kernel: usb 3-2: parse_audio_format_rates_v2v3(): unable to retrieve number of sample rates (clock 41) Jun 07 23:26:12 volumiopi5 kernel: usb 3-2: USB disconnect, device number 3 Jun 07 23:26:12 volumiopi5 volumio[1245]: info: Jun 07 23:26:12 volumiopi5 volumio[1245]: ---------------------------- USB Audio Device Detached Jun 07 23:26:12 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jun 07 23:26:12 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 23:26:12 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jun 07 23:26:12 volumiopi5 volumio[1245]: info: Executing endpoint scanAudioInputs Jun 07 23:26:12 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: smart_inputs , scanAudioInputs Jun 07 23:26:12 volumiopi5 volumio[1245]: info: Scanning Audio Inputs Jun 07 23:26:12 volumiopi5 volumio[1245]: info: Checking against Known Cards name Jun 07 23:26:12 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMPD-8 Jun 07 23:26:12 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 23:26:12 volumiopi5 (udev-worker)[27076]: controlC5: /usr/lib/udev/rules.d/78-sound-card.rules:5 Failed to write ATTR{/sys/devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.0/sound/card5/controlC5/../uevent}, ignoring: No such file or directory Jun 07 23:26:12 volumiopi5 volumio[1245]: info: Jun 07 23:26:12 volumiopi5 volumio[1245]: ---------------------------- USB Audio Device Attached Jun 07 23:26:12 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 07 23:26:12 volumiopi5 volumio[1245]: error: API:pushError: TypeError: Cannot read properties of null (reading 'name') Jun 07 23:26:12 volumiopi5 volumio[1245]: info: Jun 07 23:26:12 volumiopi5 volumio[1245]: ---------------------------- USB Audio Device Detached Jun 07 23:26:12 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jun 07 23:26:12 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 23:26:12 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jun 07 23:26:13 volumiopi5 kernel: usb 3-2: new high-speed USB device number 4 using xhci-hcd Jun 07 23:26:13 volumiopi5 kernel: usb 3-2: New USB device found, idVendor=23ba, idProduct=0110, bcdDevice= 1.02 Jun 07 23:26:13 volumiopi5 kernel: usb 3-2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Jun 07 23:26:13 volumiopi5 kernel: usb 3-2: Product: MPD-8 Jun 07 23:26:13 volumiopi5 kernel: usb 3-2: Manufacturer: AKDesign Jun 07 23:26:13 volumiopi5 kernel: usb 3-2: SerialNumber: 00100000 Jun 07 23:26:14 volumiopi5 volumio[1245]: info: Executing endpoint scanAudioInputs Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: smart_inputs , scanAudioInputs Jun 07 23:26:14 volumiopi5 volumio[1245]: info: Scanning Audio Inputs Jun 07 23:26:14 volumiopi5 volumio[1245]: info: Checking against Known Cards name Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 07 23:26:14 volumiopi5 volumio[1245]: info: [1749306374470] CoreMusicLibrary::Adding element MPD-8 Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 07 23:26:14 volumiopi5 volumio[1245]: Cannot find translation for source MPD-8 Jun 07 23:26:14 volumiopi5 volumio[1245]: info: Checking against Known Cards name Jun 07 23:26:14 volumiopi5 volumio[1245]: info: Jun 07 23:26:14 volumiopi5 volumio[1245]: ---------------------------- USB Audio Device Attached Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::Close All Modals sent Jun 07 23:26:14 volumiopi5 volumio[1245]: info: Preparing to save Alsa Options, stopping services first Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPause Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreStateMachine::pause Jun 07 23:26:14 volumiopi5 volumio[1245]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"MPD-8","alsacard":"MPD8"},"i2s":false} Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 23:26:14 volumiopi5 volumio[1245]: info: Device 5 does not have any Mixer Control Available Jun 07 23:26:14 volumiopi5 vtcs[27018]: [2025-06-07 23:26:14.521] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jun 07 23:26:14 volumiopi5 vtcs[27018]: [2025-06-07 23:26:14.522] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 07 23:26:14 volumiopi5 sudo[27121]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:26:14 volumiopi5 sudo[27121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 07 23:26:14 volumiopi5 volumio[1245]: info: Updating Volume Controller Parameters: Device: 5 Name: MPD-8 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 07 23:26:14 volumiopi5 volumio[1245]: info: Disabling external Volume Control Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:26:14 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:26:14 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:26:14 volumiopi5 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jun 07 23:26:14 volumiopi5 systemd[1]: vtcs.service: Deactivated successfully. Jun 07 23:26:14 volumiopi5 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jun 07 23:26:14 volumiopi5 volumio[1245]: info: Preparing to generate the ALSA configuration file Jun 07 23:26:14 volumiopi5 sudo[27121]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:14 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:26:14 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:26:14 volumiopi5 sudo[27127]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:26:14 volumiopi5 sudo[27127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:14 volumiopi5 volumio[1245]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 07 23:26:14 volumiopi5 volumio[1245]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 07 23:26:14 volumiopi5 volumio[1245]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jun 07 23:26:14 volumiopi5 volumio[1245]: info: Reading ALSA contributions from plugins. Jun 07 23:26:14 volumiopi5 sudo[27127]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 07 23:26:14 volumiopi5 volumio[1245]: info: Asound.conf file unchanged, so no further update is needed Jun 07 23:26:14 volumiopi5 volumio[1245]: info: Output device has changed, restarting MPD Jun 07 23:26:14 volumiopi5 volumio[1245]: info: Output device has changed, restarting Shairport Sync Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:26:14 volumiopi5 sudo[27139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 23:26:14 volumiopi5 sudo[27139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:14 volumiopi5 sudo[27140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 23:26:14 volumiopi5 sudo[27140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:14 volumiopi5 sudo[27139]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:14 volumiopi5 volumio[1245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 23:26:14 volumiopi5 volumio[1245]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:14 volumiopi5 systemd[1]: musicservicesshield.service: Deactivated successfully. Jun 07 23:26:14 volumiopi5 systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Jun 07 23:26:14 volumiopi5 systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Jun 07 23:26:14 volumiopi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 07 23:26:14 volumiopi5 volumio[1245]: info: MPD Permissions set Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:14 volumiopi5 sudo[27150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:26:14 volumiopi5 sudo[27150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:14 volumiopi5 systemd[1]: mpd.service: Deactivated successfully. Jun 07 23:26:14 volumiopi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 07 23:26:14 volumiopi5 systemd[1]: mpd.socket: Deactivated successfully. Jun 07 23:26:14 volumiopi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 07 23:26:14 volumiopi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 07 23:26:14 volumiopi5 sudo[27152]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:26:14 volumiopi5 sudo[27152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:26:14 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:14 volumiopi5 volumio[1245]: info: Starting Shairport Sync Jun 07 23:26:14 volumiopi5 sudo[27162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 23:26:14 volumiopi5 sudo[27162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:14 volumiopi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 07 23:26:14 volumiopi5 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 07 23:26:14 volumiopi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 07 23:26:14 volumiopi5 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 07 23:26:14 volumiopi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 07 23:26:14 volumiopi5 systemd[1]: shairport-sync.service: Consumed 1.517s CPU time. Jun 07 23:26:14 volumiopi5 sudo[27150]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:14 volumiopi5 sudo[27164]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 07 23:26:14 volumiopi5 sudo[27164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 07 23:26:14 volumiopi5 sudo[27164]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:14 volumiopi5 sudo[27152]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:14 volumiopi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 07 23:26:14 volumiopi5 sudo[27162]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:14 volumiopi5 volumio[1245]: info: Shairport-Sync Started Jun 07 23:26:15 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jun 07 23:26:15 volumiopi5 mpd[27168]: 2025-06-07T23:26:15 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 07 23:26:15 volumiopi5 systemd[1]: Started mpd.service - Music Player Daemon. Jun 07 23:26:15 volumiopi5 sudo[27140]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:15 volumiopi5 volumio[1245]: error: updateQueue error: null Jun 07 23:26:15 volumiopi5 systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Jun 07 23:26:15 volumiopi5 bash[27200]: cset: --> shielding system active with Jun 07 23:26:15 volumiopi5 bash[27200]: cset: "system" cpuset of CPUSPEC(2-3) with 161 tasks running Jun 07 23:26:15 volumiopi5 bash[27200]: cset: "user" cpuset of CPUSPEC(0-1) with 3 tasks running Jun 07 23:26:15 volumiopi5 systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Jun 07 23:26:17 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:26:17 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:26:17 volumiopi5 volumio[1245]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:26:17 volumiopi5 volumio[1245]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:26:17 volumiopi5 sudo[27218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 07 23:26:17 volumiopi5 sudo[27218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:17 volumiopi5 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jun 07 23:26:17 volumiopi5 sudo[27218]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:17 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:26:17 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:26:17 volumiopi5 volumio[1245]: info: Executing endpoint tc_getconfig Jun 07 23:26:17 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 07 23:26:17 volumiopi5 volumio[1245]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:26:17 volumiopi5 volumio[1245]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:26:17 volumiopi5 vtcs[27233]: OpenSSL version mismatch. Built against 101010ef, you have 30000100 Jun 07 23:26:17 volumiopi5 vtcs[27233]: STARTING TidalConnect services, version: 1.5.0.45 Jun 07 23:26:17 volumiopi5 sudo[27244]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 07 23:26:17 volumiopi5 sudo[27244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:17 volumiopi5 vtcs[27233]: STARTED TidalConnect services. Jun 07 23:26:17 volumiopi5 volumio[1245]: info: Executing endpoint tc_connect Jun 07 23:26:17 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 07 23:26:17 volumiopi5 volumio[1245]: info: Connecting to TidalConnect Jun 07 23:26:17 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:26:17 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:26:17 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:17 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:26:17 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:26:17 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:26:17 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:17 volumiopi5 volumio[1245]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jun 07 23:26:17 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:26:17 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:26:17 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:17 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:26:17 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:26:17 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:26:17 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:17 volumiopi5 volumio[1245]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jun 07 23:26:17 volumiopi5 sudo[27244]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:17 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:26:17 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:26:17 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:26:17 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:26:17 volumiopi5 vtcs[27233]: [2025-06-07 23:26:17.865] [tisoc] [error] [WebsocketServerTls.cpp:90] [WebsocketServerTls onTlsInit Error] use_certificate_chain: no start line Jun 07 23:26:18 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 07 23:26:18 volumiopi5 volumio[1245]: info: CoreStateMachine::getcurrentVolume Jun 07 23:26:18 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioRetrievevolume Jun 07 23:26:18 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:26:18 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:18 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:26:18 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:26:18 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:26:18 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:26:18 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:26:20 volumiopi5 volumio[1245]: info: TidalConnect service started! Jun 07 23:26:20 volumiopi5 volumio[1245]: info: TidalConnect service started! Jun 07 23:26:25 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPlay Jun 07 23:26:25 volumiopi5 volumio[1245]: info: CoreStateMachine::play index undefined Jun 07 23:26:25 volumiopi5 volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 07 23:26:25 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:25 volumiopi5 volumio[1245]: info: CoreStateMachine::startPlaybackTimer Jun 07 23:26:25 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:25 volumiopi5 volumio[1245]: info: ControllerMpd::resume Jun 07 23:26:25 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand play Jun 07 23:26:25 volumiopi5 volumio[1245]: info: sendMpdCommand play took 0 milliseconds Jun 07 23:26:28 volumiopi5 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 07 23:26:30 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPlay Jun 07 23:26:30 volumiopi5 volumio[1245]: info: CoreStateMachine::play index undefined Jun 07 23:26:30 volumiopi5 volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 07 23:26:30 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:30 volumiopi5 volumio[1245]: info: CoreStateMachine::startPlaybackTimer Jun 07 23:26:30 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:30 volumiopi5 volumio[1245]: info: ControllerMpd::resume Jun 07 23:26:30 volumiopi5 volumio[1245]: verbose: ControllerMpd::sendMpdCommand play Jun 07 23:26:30 volumiopi5 volumio[1245]: info: sendMpdCommand play took 0 milliseconds Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 23:26:33 volumiopi5 volumio[1245]: info: Retrieving Cloud Streaming UI Jun 07 23:26:33 volumiopi5 volumio[1245]: info: Getting Tidal Cloud Configuration Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 23:26:33 volumiopi5 volumio[1245]: info: Getting Qobuz Cloud Configuration Jun 07 23:26:33 volumiopi5 volumio[1245]: info: Asking plugin for UI Config Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 23:26:33 volumiopi5 volumio[1245]: info: Getting Spotify Cloud Configuration Jun 07 23:26:33 volumiopi5 volumio[1245]: info: Asking plugin for UI Config Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 23:26:33 volumiopi5 volumio[1245]: info: Saving Spotify Acccount Jun 07 23:26:33 volumiopi5 volumio[1245]: info: Got it Jun 07 23:26:33 volumiopi5 volumio[1245]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 07 23:26:33 volumiopi5 volumio[1245]: info: Got Tidal Cloud Configuration Jun 07 23:26:33 volumiopi5 volumio[1245]: info: Got it Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioGetBrowseSources Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioGetBrowseSources Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioGetBrowseSources Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 07 23:26:33 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 07 23:26:36 volumiopi5 volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 23:26:36 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 07 23:26:36 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:26:36 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 23:26:36 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:36 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:36 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:36 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:36 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:36 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:36 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:36 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 07 23:26:36 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 07 23:26:40 volumiopi5 volumio[1245]: info: Preparing to save Alsa Options, stopping services first Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPause Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreStateMachine::pause Jun 07 23:26:40 volumiopi5 volumio[1245]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"MPD-8"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 23:26:40 volumiopi5 volumio[1245]: info: Device 5 does not have any Mixer Control Available Jun 07 23:26:40 volumiopi5 vtcs[27233]: [2025-06-07 23:26:40.453] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jun 07 23:26:40 volumiopi5 vtcs[27233]: [2025-06-07 23:26:40.453] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jun 07 23:26:40 volumiopi5 sudo[27310]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:26:40 volumiopi5 sudo[27310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 07 23:26:40 volumiopi5 volumio[1245]: info: Updating Volume Controller Parameters: Device: 5 Name: MPD-8 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 07 23:26:40 volumiopi5 volumio[1245]: info: Disabling external Volume Control Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:26:40 volumiopi5 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jun 07 23:26:40 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:26:40 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:26:40 volumiopi5 systemd[1]: vtcs.service: Killing process 27247 (vtcs) with signal SIGKILL. Jun 07 23:26:40 volumiopi5 systemd[1]: vtcs.service: Killing process 27248 (vtcs) with signal SIGKILL. Jun 07 23:26:40 volumiopi5 systemd[1]: vtcs.service: Killing process 27249 (vtcs) with signal SIGKILL. Jun 07 23:26:40 volumiopi5 systemd[1]: vtcs.service: Deactivated successfully. Jun 07 23:26:40 volumiopi5 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jun 07 23:26:40 volumiopi5 volumio[1245]: info: Preparing to generate the ALSA configuration file Jun 07 23:26:40 volumiopi5 sudo[27310]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:40 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:26:40 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:26:40 volumiopi5 volumio[1245]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 07 23:26:40 volumiopi5 volumio[1245]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 07 23:26:40 volumiopi5 volumio[1245]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jun 07 23:26:40 volumiopi5 volumio[1245]: info: Reading ALSA contributions from plugins. Jun 07 23:26:40 volumiopi5 sudo[27317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:26:40 volumiopi5 sudo[27317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 07 23:26:40 volumiopi5 sudo[27317]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 07 23:26:40 volumiopi5 volumio[1245]: info: Asound.conf file unchanged, so no further update is needed Jun 07 23:26:40 volumiopi5 volumio[1245]: info: Output device has changed, restarting MPD Jun 07 23:26:40 volumiopi5 volumio[1245]: info: Output device has changed, restarting Shairport Sync Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:26:40 volumiopi5 sudo[27327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 07 23:26:40 volumiopi5 sudo[27327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:40 volumiopi5 sudo[27329]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 07 23:26:40 volumiopi5 sudo[27327]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:40 volumiopi5 sudo[27329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:40 volumiopi5 volumio[1245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 07 23:26:40 volumiopi5 volumio[1245]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:40 volumiopi5 systemd[1]: musicservicesshield.service: Deactivated successfully. Jun 07 23:26:40 volumiopi5 systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Jun 07 23:26:40 volumiopi5 systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Jun 07 23:26:40 volumiopi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 07 23:26:40 volumiopi5 sudo[27339]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:26:40 volumiopi5 volumio[1245]: info: MPD Permissions set Jun 07 23:26:40 volumiopi5 sudo[27339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:40 volumiopi5 sudo[27341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 07 23:26:40 volumiopi5 sudo[27341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:40 volumiopi5 systemd[1]: mpd.service: Deactivated successfully. Jun 07 23:26:40 volumiopi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 07 23:26:40 volumiopi5 systemd[1]: mpd.socket: Deactivated successfully. Jun 07 23:26:40 volumiopi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 07 23:26:40 volumiopi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 07 23:26:40 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 07 23:26:40 volumiopi5 volumio[1245]: info: Starting Shairport Sync Jun 07 23:26:40 volumiopi5 sudo[27351]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 07 23:26:40 volumiopi5 sudo[27351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:40 volumiopi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 07 23:26:40 volumiopi5 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 07 23:26:40 volumiopi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 07 23:26:40 volumiopi5 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 07 23:26:40 volumiopi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 07 23:26:40 volumiopi5 systemd[1]: shairport-sync.service: Consumed 1.534s CPU time. Jun 07 23:26:40 volumiopi5 sudo[27341]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:40 volumiopi5 sudo[27339]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:40 volumiopi5 sudo[27353]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 07 23:26:40 volumiopi5 sudo[27353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 07 23:26:40 volumiopi5 sudo[27353]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:40 volumiopi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 07 23:26:40 volumiopi5 sudo[27351]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:40 volumiopi5 volumio[1245]: info: Shairport-Sync Started Jun 07 23:26:41 volumiopi5 mpd[27358]: 2025-06-07T23:26:41 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 07 23:26:41 volumiopi5 systemd[1]: Started mpd.service - Music Player Daemon. Jun 07 23:26:41 volumiopi5 sudo[27329]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:41 volumiopi5 volumio[1245]: error: updateQueue error: null Jun 07 23:26:41 volumiopi5 systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Jun 07 23:26:41 volumiopi5 bash[27389]: cset: --> shielding system active with Jun 07 23:26:41 volumiopi5 bash[27389]: cset: "system" cpuset of CPUSPEC(2-3) with 153 tasks running Jun 07 23:26:41 volumiopi5 bash[27389]: cset: "user" cpuset of CPUSPEC(0-1) with 3 tasks running Jun 07 23:26:41 volumiopi5 systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Jun 07 23:26:43 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:26:43 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:26:43 volumiopi5 volumio[1245]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:26:43 volumiopi5 volumio[1245]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:26:43 volumiopi5 sudo[27405]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 07 23:26:43 volumiopi5 sudo[27405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 07 23:26:43 volumiopi5 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jun 07 23:26:43 volumiopi5 sudo[27405]: pam_unix(sudo:session): session closed for user root Jun 07 23:26:43 volumiopi5 volumio[1245]: info: Executing endpoint tc_getconfig Jun 07 23:26:43 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 07 23:26:43 volumiopi5 vtcs[27408]: OpenSSL version mismatch. Built against 101010ef, you have 30000100 Jun 07 23:26:43 volumiopi5 vtcs[27408]: STARTING TidalConnect services, version: 1.5.0.45 Jun 07 23:26:43 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:26:43 volumiopi5 volumio[1245]: info: TidalConnect service stoped! Jun 07 23:26:43 volumiopi5 vtcs[27408]: STARTED TidalConnect services. Jun 07 23:26:43 volumiopi5 volumio[1245]: info: Executing endpoint tc_connect Jun 07 23:26:43 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 07 23:26:43 volumiopi5 volumio[1245]: info: Connecting to TidalConnect Jun 07 23:26:43 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:26:43 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:26:43 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:43 volumiopi5 volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 07 23:26:43 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:26:43 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:26:43 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:26:43 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:43 volumiopi5 volumio[1245]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jun 07 23:26:43 volumiopi5 volumio[1245]: info: CoreCommandRouter::servicePushState Jun 07 23:26:43 volumiopi5 volumio[1245]: info: CoreStateMachine::pushState Jun 07 23:26:43 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:43 volumiopi5 volumio[1245]: info: CoreCommandRouter::volumioPushState Jun 07 23:26:43 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output update for this device Jun 07 23:26:43 volumiopi5 volumio[1245]: info: MRS: Pushing multiroomSync output Jun 07 23:26:43 volumiopi5 volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 07 23:26:43 volumiopi5 volumio[1245]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jun 07 23:26:43 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:26:43 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:26:43 volumiopi5 volumio[1245]: info: FusionDsp - Volumio is not playing Jun 07 23:26:43 volumiopi5 volumio[1245]: info: FusionDsp - Clipped samples monitor stopped Jun 07 23:26:43 volumiopi5 vtcs[27408]: [2025-06-07 23:26:43.781] [tisoc] [error] [WebsocketServerTls.cpp:90] [WebsocketServerTls onTlsInit Error] use_certificate_chain: no start line Jun 07 23:26:43 volumiopi5 volumio[1245]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:26:43 volumiopi5 volumio[1245]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 07 23:26:43 volumiopi5 volumio[1245]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 23:26:43 volumiopi5 volumio[1245]: Error: ENOENT: no such file or directory, unlink '/tmp/qk6rRFLpen9C4vCMruPFvIK1C1a5j5hMx2Ygf6ue4vHtUq5pWHzIT1fkqIVPQbniTlAJ0dfW81t8QLpSCaGGs22fEmmP0351zm5i' Jun 07 23:26:43 volumiopi5 volumio[1245]: at Object.unlinkSync (node:fs:1899:3) Jun 07 23:26:43 volumiopi5 volumio[1245]: at ControllerTidalconnect.deleteCertificate (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:11238) Jun 07 23:26:43 volumiopi5 volumio[1245]: at TidalConnectClient. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:2774) Jun 07 23:26:43 volumiopi5 volumio[1245]: at TidalConnectClient.emit (node:events:514:28) Jun 07 23:26:43 volumiopi5 volumio[1245]: at TidalConnectClient.parseData (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:44661) Jun 07 23:26:43 volumiopi5 volumio[1245]: at /myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41631 Jun 07 23:26:43 volumiopi5 volumio[1245]: at Array.forEach () Jun 07 23:26:43 volumiopi5 volumio[1245]: at Socket. (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:41509) Jun 07 23:26:43 volumiopi5 volumio[1245]: at Socket.emit (node:events:514:28) Jun 07 23:26:43 volumiopi5 volumio[1245]: at addChunk (node:internal/streams/readable:343:12) Jun 07 23:26:43 volumiopi5 volumio[1245]: at readableAddChunk (node:internal/streams/readable:316:9) Jun 07 23:26:43 volumiopi5 volumio[1245]: at Readable.push (node:internal/streams/readable:253:10) Jun 07 23:26:43 volumiopi5 volumio[1245]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) { Jun 07 23:26:43 volumiopi5 volumio[1245]: errno: -2, Jun 07 23:26:43 volumiopi5 volumio[1245]: syscall: 'unlink', Jun 07 23:26:43 volumiopi5 volumio[1245]: code: 'ENOENT', Jun 07 23:26:43 volumiopi5 volumio[1245]: path: '/tmp/qk6rRFLpen9C4vCMruPFvIK1C1a5j5hMx2Ygf6ue4vHtUq5pWHzIT1fkqIVPQbniTlAJ0dfW81t8QLpSCaGGs22fEmmP0351zm5i' Jun 07 23:26:43 volumiopi5 volumio[1245]: } Jun 07 23:26:43 volumiopi5 volumio[1245]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 07 23:26:44 volumiopi5 sudo[27446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-07 23:25' Jun 07 23:26:44 volumiopi5 sudo[27446]: 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="4a70cb031e64a8e498efc3e29470650871ff7d54" 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 12:57:40 UTC 2025" VOLUMIO_VERSION="4.008" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4256517ba770cec8e56a1795de23dd16"