Feb 17 18:08:03 volumio volumio[1351]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 17 18:08:03 volumio volumio[1351]: info: Preparing to save Alsa Options, stopping services first Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::volumioStop Feb 17 18:08:03 volumio volumio[1351]: info: CoreStateMachine::stop Feb 17 18:08:03 volumio volumio[1351]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:08:03 volumio volumio[1351]: info: CoreStateMachine::stPlaybackTimer Feb 17 18:08:03 volumio volumio[1351]: info: CoreStateMachine::updateTrackBlock Feb 17 18:08:03 volumio volumio[1351]: info: CorePlayQueue::getTrackBlock Feb 17 18:08:03 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:03 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:03 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:03 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:03 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:03 volumio volumio[1351]: info: CoreStateMachine::serviceStop Feb 17 18:08:03 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::serviceStop Feb 17 18:08:03 volumio volumio[1351]: info: [1771348083076] ControllerWebradio::stop Feb 17 18:08:03 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 18:08:03 volumio volumio[1351]: info: Saving Audio Output to: {"output_device":{"value":"0,0","label":"Analog Out"}} Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 17 18:08:03 volumio volumio[1351]: info: Found match in Cards Database: setting mixer for card Analog Out Feb 17 18:08:03 volumio volumio[1351]: info: QobuzConnect: setDeactiveState invoked Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:03 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:03 volumio vtcs[4984]: [2026-02-17 18:08:03.091] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer Feb 17 18:08:03 volumio vtcs[4984]: [2026-02-17 18:08:03.091] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Feb 17 18:08:03 volumio vtcs[4984]: [2026-02-17 18:08:03.091] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Feb 17 18:08:03 volumio sudo[5144]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 17 18:08:03 volumio sudo[5144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio volumio[1351]: info: Setting mixer Master for card Analog Out Feb 17 18:08:03 volumio volumio[1351]: info: QobuzConnect: setDeactiveState invoked Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:03 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:03 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 17 18:08:03 volumio systemd[1]: vtcs.service: Killing process 4989 (vtcs) with signal SIGKILL. Feb 17 18:08:03 volumio sudo[5150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 17 18:08:03 volumio systemd[1]: vtcs.service: Deactivated successfully. Feb 17 18:08:03 volumio sudo[5150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 17 18:08:03 volumio volumio[1351]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 17 18:08:03 volumio volumio[1351]: info: Disabling external Volume Control Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 18:08:03 volumio sudo[5144]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio sudo[5150]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio volumio[1351]: info: Preparing to generate the ALSA configuration file Feb 17 18:08:03 volumio sudo[5159]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 17 18:08:03 volumio sudo[5159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio sudo[5161]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 17 18:08:03 volumio sudo[5161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 18:08:03 volumio sudo[5161]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio sudo[5170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 17 18:08:03 volumio sudo[5159]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio sudo[5170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 17 18:08:03 volumio volumio[1351]: info: Reading ALSA contributions from plugins. Feb 17 18:08:03 volumio volumio[1351]: info: Feb 17 18:08:03 volumio volumio[1351]: ---------------------------- MPD announces state update: player Feb 17 18:08:03 volumio volumio[1351]: info: sendMpdCommand stop took 92 milliseconds Feb 17 18:08:03 volumio volumio[1351]: info: ControllerMpd::getState Feb 17 18:08:03 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:08:03 volumio volumio[1351]: info: Feb 17 18:08:03 volumio volumio[1351]: ---------------------------- MPD announces state update: player Feb 17 18:08:03 volumio volumio[1351]: info: ControllerMpd::getState Feb 17 18:08:03 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:08:03 volumio sudo[5170]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio sudo[5178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 17 18:08:03 volumio sudo[5178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio volumio[1351]: info: sendMpdCommand status took 6 milliseconds Feb 17 18:08:03 volumio volumio[1351]: info: sendMpdCommand status took 6 milliseconds Feb 17 18:08:03 volumio volumio[1351]: verbose: ControllerMpd::parseState Feb 17 18:08:03 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:08:03 volumio volumio[1351]: verbose: ControllerMpd::parseState Feb 17 18:08:03 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:08:03 volumio volumio[1351]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 17 18:08:03 volumio volumio[1351]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 17 18:08:03 volumio volumio[1351]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:08:03 volumio volumio[1351]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:08:03 volumio volumio[1351]: info: ControllerMpd::pushState Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::servicePushState Feb 17 18:08:03 volumio sudo[5179]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 17 18:08:03 volumio sudo[5179]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:03 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:03 volumio sudo[5178]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:03 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:03 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:03 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:03 volumio volumio[1351]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 17 18:08:03 volumio volumio[1351]: info: ControllerMpd::pushState Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::servicePushState Feb 17 18:08:03 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:03 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:03 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:03 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:03 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:03 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:03 volumio volumio[1351]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 17 18:08:03 volumio sudo[5183]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 17 18:08:03 volumio sudo[5183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 17 18:08:03 volumio qobuz-connect[4954]: 20260217 18:08:03.188 [4954.4954] INFO SampleApp: Stopping Local configuration server Feb 17 18:08:03 volumio volumio[1351]: info: ------------------------------ 24ms Feb 17 18:08:03 volumio volumio[1351]: info: ------------------------------ 22ms Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 17 18:08:03 volumio volumio[1351]: info: VolumeController:: Volume=15 Mute =false Feb 17 18:08:03 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:03 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:03 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:03 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:03 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:03 volumio volumio[1351]: info: Asound.conf file written Feb 17 18:08:03 volumio sudo[5193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 17 18:08:03 volumio sudo[5193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio sudo[5193]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio volumio[1351]: No state is present for card PCH Feb 17 18:08:03 volumio volumio[1351]: Found hardware: "HDA-Intel" "Cirrus Logic Generic" "HDA:10134206,106b5c00,00100302" "0x8086" "0x7270" Feb 17 18:08:03 volumio volumio[1351]: Hardware is initialized using a generic method Feb 17 18:08:03 volumio volumio[1351]: No state is present for card PCH Feb 17 18:08:03 volumio volumio[1351]: No state is present for card NVidia Feb 17 18:08:03 volumio volumio[1351]: Found hardware: "HDA-Intel" "Nvidia GPU 42 HDMI/DP" "HDA:10de0042,106b010b,00100100" "0x106b" "0x010b" Feb 17 18:08:03 volumio volumio[1351]: Hardware is initialized using a generic method Feb 17 18:08:03 volumio volumio[1351]: No state is present for card NVidia Feb 17 18:08:03 volumio volumio[1351]: info: Output device has changed, restarting MPD Feb 17 18:08:03 volumio volumio[1351]: info: Output device has changed, restarting Shairport Sync Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 18:08:03 volumio sudo[5200]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 18:08:03 volumio sudo[5200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio sudo[5201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 18:08:03 volumio sudo[5201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio sudo[5200]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio volumio[1351]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 18:08:03 volumio volumio[1351]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 17 18:08:03 volumio volumio[1351]: info: QobuzConnect: setDeactiveState invoked Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:03 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:03 volumio volumio[1351]: info: Output device has changed, restarting MPD Feb 17 18:08:03 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 17 18:08:03 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 18:08:03 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 17 18:08:03 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 18:08:03 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 18:08:03 volumio sudo[5211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 17 18:08:03 volumio sudo[5211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio sudo[5213]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 17 18:08:03 volumio sudo[5213]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio sudo[5213]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio volumio[1351]: info: Output device has changed, restarting Shairport Sync Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 18:08:03 volumio sudo[5218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 17 18:08:03 volumio sudo[5218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 18:08:03 volumio volumio[1351]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 17 18:08:03 volumio volumio[1351]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 18:08:03 volumio volumio[1351]: info: QobuzConnect: setDeactiveState invoked Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:03 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:03 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 17 18:08:03 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 17 18:08:03 volumio sudo[5228]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 17 18:08:03 volumio sudo[5228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio sudo[5211]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 17 18:08:03 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 17 18:08:03 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 17 18:08:03 volumio sudo[5230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 17 18:08:03 volumio sudo[5234]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 17 18:08:03 volumio sudo[5234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio sudo[5230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 17 18:08:03 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 17 18:08:03 volumio sudo[5228]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 18:08:03 volumio sudo[5242]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 17 18:08:03 volumio sudo[5242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 18:08:03 volumio sudo[5246]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 17 18:08:03 volumio sudo[5242]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio sudo[5246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio sudo[5234]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio volumio[1351]: info: MPD Permissions set Feb 17 18:08:03 volumio volumio[1351]: info: MPD Permissions set Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio sudo[5230]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio sudo[5255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 17 18:08:03 volumio sudo[5255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio sudo[5246]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 17 18:08:03 volumio sudo[5255]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio sudo[5263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 17 18:08:03 volumio sudo[5263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 17 18:08:03 volumio sudo[5273]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 17 18:08:03 volumio sudo[5273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 17 18:08:03 volumio volumio[1351]: info: Starting Shairport Sync Feb 17 18:08:03 volumio volumio[1351]: info: Starting Shairport Sync Feb 17 18:08:03 volumio sudo[5279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 18:08:03 volumio sudo[5279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio sudo[5281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 18:08:03 volumio sudo[5281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:03 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 17 18:08:03 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 17 18:08:03 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 18:08:03 volumio systemd[1]: shairport-sync.service: Consumed 1.657s CPU time. Feb 17 18:08:03 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 18:08:03 volumio sudo[5279]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio sudo[5281]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio volumio[1351]: info: Shairport-Sync Started Feb 17 18:08:03 volumio volumio[1351]: info: Shairport-Sync Started Feb 17 18:08:03 volumio volumio[1351]: (node:1351) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 17 18:08:03 volumio volumio[1351]: (Use `node --trace-warnings ...` to show where the warning was created) Feb 17 18:08:03 volumio volumio[1351]: (node:1351) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 17 18:08:03 volumio volumio[1351]: (node:1351) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 17 18:08:03 volumio volumio[1351]: (node:1351) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 17 18:08:03 volumio volumio[1351]: (node:1351) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 17 18:08:03 volumio mpd[5252]: 2026-02-17T18:08:03 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 17 18:08:03 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 17 18:08:03 volumio sudo[5201]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio sudo[5218]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:03 volumio volumio[1351]: error: MPD error: The expression evaluated to a falsy value: Feb 17 18:08:03 volumio volumio[1351]: assert.ok(self.idling) Feb 17 18:08:03 volumio volumio[1351]: error: The expression evaluated to a falsy value: Feb 17 18:08:03 volumio volumio[1351]: assert.ok(self.idling) Feb 17 18:08:03 volumio volumio[1351]: error: updateQueue error: null Feb 17 18:08:04 volumio qobuz-connect[4954]: 20260217 18:08:04.361 [4954.4954] INFO SampleApp: shat down connection on UNIX socket Feb 17 18:08:04 volumio volumio[1351]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 17 18:08:04 volumio volumio[1351]: info: QobuzConnect: setDeactiveState invoked Feb 17 18:08:04 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:04 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:04 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 17 18:08:04 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 17 18:08:04 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 17 18:08:04 volumio sudo[5183]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:04 volumio sudo[5263]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:04 volumio sudo[5179]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:04 volumio sudo[5273]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:04 volumio volumio[1351]: info: Executing endpoint qc_getconfig Feb 17 18:08:04 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 17 18:08:04 volumio qobuz-connect[5317]: 20260217 18:08:04.396 [5317.5317] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 17 18:08:04 volumio volumio[1351]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 17 18:08:04 volumio volumio[1351]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 17 18:08:04 volumio qobuz-connect[5317]: 20260217 18:08:04.398 [5317.5317] INFO VolumeManager: [0x55d8c7927030]: Setting new playback volume: 75 Feb 17 18:08:04 volumio qobuz-connect[5317]: 20260217 18:08:04.398 [5317.5317] INFO VolumeManager: [0x55d8c7927030]: Setting new mute state: 0 Feb 17 18:08:04 volumio qobuz-connect[5317]: 20260217 18:08:04.398 [5317.5317] INFO QobuzConnect: [0x55d8c7928250]: Client initialized! Feb 17 18:08:04 volumio qobuz-connect[5317]: 20260217 18:08:04.398 [5317.5317] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Feb 17 18:08:04 volumio qobuz-connect[5317]: 20260217 18:08:04.402 [5317.5317] INFO LocalConfigManager: [0x55d8c7926320]: Starting Local Configuration server Feb 17 18:08:04 volumio qobuz-connect[5317]: 20260217 18:08:04.402 [5317.5317] INFO SampleApp: Starting Local configuration server Feb 17 18:08:04 volumio qobuz-connect[5317]: 20260217 18:08:04.402 [5317.5317] INFO SampleApp: Connected to UNIX socket client 0x55d8c78fcbb0 Feb 17 18:08:04 volumio qobuz-connect[5317]: 20260217 18:08:04.547 [5317.5317] INFO SampleApp: Playback volume changed: 75 Feb 17 18:08:04 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:04 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:06 volumio volumio[1351]: info: MRS: STARTING volumioStreaming Feb 17 18:08:06 volumio sudo[5326]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream21.ts /tmp/hls/stream22.ts /tmp/hls/stream23.ts /tmp/hls/stream24.ts Feb 17 18:08:06 volumio sudo[5326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:06 volumio sudo[5326]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:06 volumio sudo[5328]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Feb 17 18:08:06 volumio sudo[5328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:06 volumio systemd[1]: Stopping volumioStreaming.service - VolumioStreamingService... Feb 17 18:08:06 volumio systemd[1]: volumioStreaming.service: Killing process 5068 (ffmpeg) with signal SIGKILL. Feb 17 18:08:06 volumio systemd[1]: volumioStreaming.service: Killing process 5071 (ffmpeg) with signal SIGKILL. Feb 17 18:08:06 volumio systemd[1]: volumioStreaming.service: Killing process 5072 (ffmpeg) with signal SIGKILL. Feb 17 18:08:06 volumio systemd[1]: volumioStreaming.service: Killing process 5073 (ffmpeg) with signal SIGKILL. Feb 17 18:08:06 volumio systemd[1]: volumioStreaming.service: Deactivated successfully. Feb 17 18:08:06 volumio systemd[1]: Stopped volumioStreaming.service - VolumioStreamingService. Feb 17 18:08:06 volumio systemd[1]: volumioStreaming.service: Consumed 1.359s CPU time. Feb 17 18:08:06 volumio systemd[1]: Started volumioStreaming.service - VolumioStreamingService. Feb 17 18:08:06 volumio sudo[5328]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:06 volumio volumio[1351]: info: MRS: volumioStreaming STARTED Feb 17 18:08:06 volumio volumio[1351]: info: TidalConnect service stoped! Feb 17 18:08:06 volumio sudo[5332]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 17 18:08:06 volumio sudo[5332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:06 volumio volumio[1351]: info: TidalConnect service stoped! Feb 17 18:08:06 volumio sudo[5332]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:06 volumio volumio[1351]: info: TidalConnect service stoped! Feb 17 18:08:06 volumio volumio[1351]: info: TidalConnect service stoped! Feb 17 18:08:06 volumio volumio[1351]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 17 18:08:06 volumio volumio[1351]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 17 18:08:06 volumio sudo[5348]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 17 18:08:06 volumio sudo[5348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:06 volumio volumio[1351]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 17 18:08:06 volumio volumio[1351]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 17 18:08:06 volumio sudo[5355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 17 18:08:06 volumio sudo[5355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:06 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 17 18:08:06 volumio sudo[5348]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:06 volumio sudo[5355]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:06 volumio volumio[1351]: info: Executing endpoint tc_getconfig Feb 17 18:08:06 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 17 18:08:06 volumio vtcs[5359]: STARTING TidalConnect services, version: 1.6.1 Feb 17 18:08:06 volumio vtcs[5359]: STARTED TidalConnect services. Feb 17 18:08:06 volumio volumio[1351]: info: Executing endpoint tc_connect Feb 17 18:08:06 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 17 18:08:06 volumio volumio[1351]: info: Connecting to TidalConnect Feb 17 18:08:06 volumio volumio[1351]: info: CoreCommandRouter::servicePushState Feb 17 18:08:06 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:06 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:06 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:08:06 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:06 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:06 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:06 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:06 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:06 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:06 volumio volumio[1351]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Feb 17 18:08:06 volumio volumio[1351]: info: CoreCommandRouter::servicePushState Feb 17 18:08:06 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:06 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:06 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:06 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:06 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:06 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:06 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:06 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:06 volumio volumio[1351]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Feb 17 18:08:06 volumio volumio[1351]: info: TidalConnect service stoped! Feb 17 18:08:06 volumio volumio[1351]: info: TidalConnect service stoped! Feb 17 18:08:06 volumio volumio[1351]: info: TidalConnect service stoped! Feb 17 18:08:06 volumio volumio[1351]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 17 18:08:06 volumio volumio[1351]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 17 18:08:06 volumio volumio[1351]: info: TidalConnect service stoped! Feb 17 18:08:06 volumio sudo[5384]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 17 18:08:06 volumio sudo[5384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:06 volumio volumio[1351]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 17 18:08:06 volumio volumio[1351]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 17 18:08:06 volumio sudo[5384]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:06 volumio sudo[5396]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 17 18:08:06 volumio sudo[5396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:08:06 volumio sudo[5396]: pam_unix(sudo:session): session closed for user root Feb 17 18:08:07 volumio volumio[1351]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 17 18:08:07 volumio volumio[1351]: info: CoreStateMachine::getcurrentVolume Feb 17 18:08:07 volumio volumio[1351]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 18:08:07 volumio volumio[1351]: info: VolumeController:: Volume=41 Mute =false Feb 17 18:08:07 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:07 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:07 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:08:07 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:07 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:07 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:07 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:07 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:07 volumio volumio[1351]: info: CoreStateMachine::updateTrackBlock Feb 17 18:08:07 volumio volumio[1351]: info: CorePlayQueue::getTrackBlock Feb 17 18:08:07 volumio volumio[1351]: info: CoreCommandRouter::volumioRetrievevolume Feb 17 18:08:07 volumio volumio[1351]: info: VolumeController:: Volume=41 Mute =false Feb 17 18:08:07 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:07 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:07 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:07 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:07 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:07 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:07 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:08 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:08 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:08 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Feb 17 18:08:08 volumio volumio[1351]: info: CoreCommandRouter::volumioPlay Feb 17 18:08:08 volumio volumio[1351]: info: CoreStateMachine::play index undefined Feb 17 18:08:08 volumio volumio[1351]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:08:08 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:08 volumio volumio[1351]: info: CoreStateMachine::startPlaybackTimer Feb 17 18:08:08 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:08 volumio volumio[1351]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 18:08:08 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 18:08:08 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 17 18:08:08 volumio volumio[1351]: info: [1771348088968] ControllerWebradio::clearAddPlayTrack Feb 17 18:08:08 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 18:08:08 volumio volumio[1351]: info: sendMpdCommand stop took 0 milliseconds Feb 17 18:08:08 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand clear Feb 17 18:08:08 volumio volumio[1351]: info: Feb 17 18:08:08 volumio volumio[1351]: ---------------------------- MPD announces system playlist update Feb 17 18:08:08 volumio volumio[1351]: info: Ignoring MPD Status Update Feb 17 18:08:08 volumio volumio[1351]: info: sendMpdCommand clear took 0 milliseconds Feb 17 18:08:08 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radioparadise.com/rock-flacm" Feb 17 18:08:08 volumio volumio[1351]: info: Feb 17 18:08:08 volumio volumio[1351]: ---------------------------- MPD announces system playlist update Feb 17 18:08:08 volumio volumio[1351]: info: Ignoring MPD Status Update Feb 17 18:08:08 volumio volumio[1351]: error: updateQueue error: null Feb 17 18:08:08 volumio volumio[1351]: info: ------------------------------ 1ms Feb 17 18:08:09 volumio volumio[1351]: error: updateQueue error: null Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/rock-flacm" Feb 17 18:08:09 volumio volumio[1351]: info: ------------------------------ 64ms Feb 17 18:08:09 volumio volumio[1351]: info: Feb 17 18:08:09 volumio volumio[1351]: ---------------------------- MPD announces system playlist update Feb 17 18:08:09 volumio volumio[1351]: info: Ignoring MPD Status Update Feb 17 18:08:09 volumio volumio[1351]: info: sendMpdCommand add "http://stream.radioparadise.com/rock-flacm" took 1 milliseconds Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand play Feb 17 18:08:09 volumio volumio[1351]: info: Feb 17 18:08:09 volumio volumio[1351]: ---------------------------- MPD announces system playlist update Feb 17 18:08:09 volumio volumio[1351]: info: Ignoring MPD Status Update Feb 17 18:08:09 volumio volumio[1351]: info: ------------------------------ 1ms Feb 17 18:08:09 volumio volumio[1351]: info: sendMpdCommand play took 0 milliseconds Feb 17 18:08:09 volumio volumio[1351]: info: ------------------------------ 0ms Feb 17 18:08:09 volumio volumio[1351]: info: Feb 17 18:08:09 volumio volumio[1351]: ---------------------------- MPD announces state update: player Feb 17 18:08:09 volumio volumio[1351]: info: ControllerMpd::getState Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:08:09 volumio volumio[1351]: info: Feb 17 18:08:09 volumio volumio[1351]: ---------------------------- MPD announces state update: player Feb 17 18:08:09 volumio volumio[1351]: info: ControllerMpd::getState Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:08:09 volumio volumio[1351]: info: Feb 17 18:08:09 volumio volumio[1351]: ---------------------------- MPD announces state update: player Feb 17 18:08:09 volumio volumio[1351]: info: sendMpdCommand status took 7 milliseconds Feb 17 18:08:09 volumio volumio[1351]: info: sendMpdCommand status took 7 milliseconds Feb 17 18:08:09 volumio volumio[1351]: info: ControllerMpd::getState Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::parseState Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::parseState Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:08:09 volumio volumio[1351]: info: Feb 17 18:08:09 volumio volumio[1351]: ---------------------------- MPD announces state update: player Feb 17 18:08:09 volumio volumio[1351]: info: ControllerMpd::getState Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:08:09 volumio volumio[1351]: info: sendMpdCommand status took 1 milliseconds Feb 17 18:08:09 volumio volumio[1351]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 17 18:08:09 volumio volumio[1351]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::parseState Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:08:09 volumio volumio[1351]: info: ControllerMpd::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::servicePushState Feb 17 18:08:09 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:09 volumio volumio[1351]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Feb 17 18:08:09 volumio volumio[1351]: verbose: CURRENT POSITION 0 Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::syncState stateService play Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:08:09 volumio volumio[1351]: info: ControllerMpd::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::servicePushState Feb 17 18:08:09 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:09 volumio volumio[1351]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Feb 17 18:08:09 volumio volumio[1351]: verbose: CURRENT POSITION 0 Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::syncState stateService play Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:08:09 volumio volumio[1351]: info: Received an update from plugin. extracting info from payload Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:09 volumio volumio[1351]: info: ------------------------------ 12ms Feb 17 18:08:09 volumio volumio[1351]: info: ------------------------------ 19ms Feb 17 18:08:09 volumio volumio[1351]: info: sendMpdCommand status took 12 milliseconds Feb 17 18:08:09 volumio volumio[1351]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::parseState Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:08:09 volumio volumio[1351]: info: ControllerMpd::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::servicePushState Feb 17 18:08:09 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:09 volumio volumio[1351]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Feb 17 18:08:09 volumio volumio[1351]: verbose: CURRENT POSITION 0 Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::syncState stateService play Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:08:09 volumio volumio[1351]: info: Received an update from plugin. extracting info from payload Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:09 volumio volumio[1351]: info: ------------------------------ 21ms Feb 17 18:08:09 volumio volumio[1351]: info: Feb 17 18:08:09 volumio volumio[1351]: ---------------------------- MPD announces system playlist update Feb 17 18:08:09 volumio volumio[1351]: info: Ignoring MPD Status Update Feb 17 18:08:09 volumio volumio[1351]: info: Feb 17 18:08:09 volumio volumio[1351]: ---------------------------- MPD announces state update: player Feb 17 18:08:09 volumio volumio[1351]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 17 18:08:09 volumio volumio[1351]: info: ControllerMpd::getState Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:08:09 volumio volumio[1351]: info: ControllerMpd::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::servicePushState Feb 17 18:08:09 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:09 volumio volumio[1351]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Feb 17 18:08:09 volumio volumio[1351]: verbose: CURRENT POSITION 0 Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::syncState stateService play Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:08:09 volumio volumio[1351]: info: Received an update from plugin. extracting info from payload Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:09 volumio volumio[1351]: info: ------------------------------ 34ms Feb 17 18:08:09 volumio volumio[1351]: info: Feb 17 18:08:09 volumio volumio[1351]: ---------------------------- MPD announces system playlist update Feb 17 18:08:09 volumio volumio[1351]: info: Ignoring MPD Status Update Feb 17 18:08:09 volumio volumio[1351]: info: Feb 17 18:08:09 volumio volumio[1351]: ---------------------------- MPD announces state update: player Feb 17 18:08:09 volumio volumio[1351]: info: ControllerMpd::getState Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:08:09 volumio volumio[1351]: info: ------------------------------ 7ms Feb 17 18:08:09 volumio volumio[1351]: info: sendMpdCommand status took 7 milliseconds Feb 17 18:08:09 volumio volumio[1351]: info: ------------------------------ 1ms Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::parseState Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:08:09 volumio volumio[1351]: info: sendMpdCommand status took 4 milliseconds Feb 17 18:08:09 volumio volumio[1351]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::parseState Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:08:09 volumio volumio[1351]: info: ControllerMpd::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::servicePushState Feb 17 18:08:09 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:09 volumio volumio[1351]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1209,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":"Radio Paradise: Rock Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Feb 17 18:08:09 volumio volumio[1351]: verbose: CURRENT POSITION 0 Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::syncState stateService play Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:08:09 volumio volumio[1351]: info: Received an update from plugin. extracting info from payload Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:09 volumio volumio[1351]: info: ------------------------------ 18ms Feb 17 18:08:09 volumio volumio[1351]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:08:09 volumio volumio[1351]: info: ControllerMpd::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::servicePushState Feb 17 18:08:09 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:09 volumio volumio[1351]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1209,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rock-flacm","artist":"Radio Paradise: Rock Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Feb 17 18:08:09 volumio volumio[1351]: verbose: CURRENT POSITION 0 Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::syncState stateService play Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:08:09 volumio volumio[1351]: info: Received an update from plugin. extracting info from payload Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:09 volumio volumio[1351]: info: ------------------------------ 18ms Feb 17 18:08:09 volumio volumio[1351]: info: TidalConnect service started! Feb 17 18:08:09 volumio volumio[1351]: info: TidalConnect service started! Feb 17 18:08:09 volumio volumio[1351]: info: TidalConnect service started! Feb 17 18:08:09 volumio volumio[1351]: info: TidalConnect service started! Feb 17 18:08:09 volumio volumio[1351]: info: Feb 17 18:08:09 volumio volumio[1351]: ---------------------------- MPD announces system playlist update Feb 17 18:08:09 volumio volumio[1351]: info: Ignoring MPD Status Update Feb 17 18:08:09 volumio volumio[1351]: info: Feb 17 18:08:09 volumio volumio[1351]: ---------------------------- MPD announces state update: player Feb 17 18:08:09 volumio volumio[1351]: info: ControllerMpd::getState Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:08:09 volumio volumio[1351]: info: Feb 17 18:08:09 volumio volumio[1351]: ---------------------------- MPD announces system playlist update Feb 17 18:08:09 volumio volumio[1351]: info: Ignoring MPD Status Update Feb 17 18:08:09 volumio volumio[1351]: info: Feb 17 18:08:09 volumio volumio[1351]: ---------------------------- MPD announces state update: player Feb 17 18:08:09 volumio volumio[1351]: info: ControllerMpd::getState Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:08:09 volumio volumio[1351]: info: ------------------------------ 1ms Feb 17 18:08:09 volumio volumio[1351]: info: sendMpdCommand status took 0 milliseconds Feb 17 18:08:09 volumio volumio[1351]: info: ------------------------------ 0ms Feb 17 18:08:09 volumio volumio[1351]: info: sendMpdCommand status took 0 milliseconds Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::parseState Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::parseState Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:08:09 volumio volumio[1351]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 17 18:08:09 volumio volumio[1351]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:08:09 volumio volumio[1351]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:08:09 volumio volumio[1351]: info: ControllerMpd::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::servicePushState Feb 17 18:08:09 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:09 volumio volumio[1351]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2212,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"INXS - Devil Inside","artist":"Radio Paradise: Rock Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Feb 17 18:08:09 volumio volumio[1351]: verbose: CURRENT POSITION 0 Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::syncState stateService play Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:08:09 volumio volumio[1351]: info: Received an update from plugin. extracting info from payload Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:09 volumio volumio[1351]: info: ControllerMpd::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::servicePushState Feb 17 18:08:09 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:09 volumio volumio[1351]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2212,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"INXS - Devil Inside","artist":"Radio Paradise: Rock Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/rock-flacm","trackType":""} Feb 17 18:08:09 volumio volumio[1351]: verbose: CURRENT POSITION 0 Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::syncState stateService play Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:08:09 volumio volumio[1351]: info: Received an update from plugin. extracting info from payload Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:09 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:09 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:09 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:09 volumio volumio[1351]: info: ------------------------------ 15ms Feb 17 18:08:09 volumio volumio[1351]: info: ------------------------------ 14ms Feb 17 18:08:16 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:18 volumio volumio[1351]: info: Executing endpoint metavolumio Feb 17 18:08:18 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 17 18:08:18 volumio volumio[1351]: info: Executing endpoint metavolumio Feb 17 18:08:18 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 17 18:08:23 volumio volumio[1351]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 18:08:23 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 17 18:08:23 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 17 18:08:23 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 17 18:08:23 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 17 18:08:23 volumio volumio[1351]: info: Received Get System Version Feb 17 18:08:23 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 18:08:34 volumio volumio[1351]: info: CALLMETHOD: miscellanea appearance setVolumio3UI [object Object] Feb 17 18:08:34 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , setVolumio3UI Feb 17 18:08:34 volumio volumio[1351]: info: Setting active UI to: {"uiPrettyName":"Contemporary","uiName":"contemporary","uiPath":"/volumio/http/www3"} Feb 17 18:08:35 volumio volumio[1351]: info: CoreCommandRouter::Reload Ui Feb 17 18:08:36 volumio volumio[1351]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 17 18:08:36 volumio volumio[1351]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 17 18:08:36 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 17 18:08:36 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 17 18:08:36 volumio volumio[1351]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 17 18:08:36 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:36 volumio volumio[1351]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 18:08:36 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 18:08:36 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 17 18:08:36 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 17 18:08:36 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 17 18:08:36 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 17 18:08:36 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 17 18:08:36 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 17 18:08:36 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 17 18:08:36 volumio volumio[1351]: info: Received Get System Version Feb 17 18:08:36 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 18:08:36 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Feb 17 18:08:38 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 17 18:08:38 volumio volumio[1351]: info: Received Get System Info Feb 17 18:08:38 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 18:08:38 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 18:08:38 volumio volumio[1351]: info: Discovery: Getting this device information Feb 17 18:08:38 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:38 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 18:08:39 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 17 18:08:39 volumio volumio[1351]: info: Received Get System Info Feb 17 18:08:39 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 18:08:39 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 18:08:39 volumio volumio[1351]: info: Discovery: Getting this device information Feb 17 18:08:39 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:39 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 18:08:40 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 17 18:08:40 volumio volumio[1351]: info: CURURI: music-library Feb 17 18:08:40 volumio volumio[1351]: info: Preload queue cleared Feb 17 18:08:40 volumio volumio[1351]: info: CoreCommandRouter::volumioGetQueue Feb 17 18:08:40 volumio volumio[1351]: info: CoreStateMachine::getQueue Feb 17 18:08:40 volumio volumio[1351]: info: CorePlayQueue::getQueue Feb 17 18:08:40 volumio volumio[1351]: info: Listing playlists Feb 17 18:08:42 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 17 18:08:42 volumio volumio[1351]: info: CURURI: music-library/NAS Feb 17 18:08:42 volumio volumio[1351]: info: Preload queue cleared Feb 17 18:08:43 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 17 18:08:43 volumio volumio[1351]: info: CURURI: music-library/NAS/Qnap Feb 17 18:08:43 volumio volumio[1351]: info: Preload queue cleared Feb 17 18:08:46 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 17 18:08:46 volumio volumio[1351]: info: CURURI: music-library/NAS/Qnap/Hammerfall Feb 17 18:08:46 volumio volumio[1351]: info: Preload queue cleared Feb 17 18:08:47 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 17 18:08:47 volumio volumio[1351]: info: CURURI: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings Feb 17 18:08:47 volumio volumio[1351]: info: Preload queue cleared Feb 17 18:08:47 volumio volumio[1351]: info: Preloading song: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/01-HammerFall-Heeding_The_Call.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Preloading song: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/02-HammerFall-Legacy_Of_Kings.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Preloading song: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/03-HammerFall-Let_The_Hammer_Fall.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Preloading song: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/04-HammerFall-Dreamland.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Preloading song: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/05-HammerFall-Remember_Yesterday.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Preloading song: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/06-HammerFall-At_The_End_Of_The_Rainbow.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Preloading song: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/07-HammerFall-Back_To_Back.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Preloading song: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/08-HammerFall-Stronger_Than_All.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Preloading song: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/09-HammerFall-Warriors_Of_Faith.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Preloading song: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/10-HammerFall-The_Fallen_One.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Exploding uri music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/01-HammerFall-Heeding_The_Call.mp3 in service mpd Feb 17 18:08:47 volumio volumio[1351]: info: ALBUMART /albumart?cacheid=250&web=HammerFall/Legacy%20Of%20Kings/extralarge&path=%2Fmnt%2FNAS%2FQnap%2FHammerfall%2FLegacy%20Of%20Kings%2F01-HammerFall-Heeding_The_Call.mp3&metadata=false Feb 17 18:08:47 volumio volumio[1351]: info: URI /mnt/NAS/Qnap/Hammerfall/Legacy Of Kings/01-HammerFall-Heeding_The_Call.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Exploding uri music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/02-HammerFall-Legacy_Of_Kings.mp3 in service mpd Feb 17 18:08:47 volumio volumio[1351]: info: ALBUMART /albumart?cacheid=250&web=HammerFall/Legacy%20Of%20Kings/extralarge&path=%2Fmnt%2FNAS%2FQnap%2FHammerfall%2FLegacy%20Of%20Kings%2F02-HammerFall-Legacy_Of_Kings.mp3&metadata=false Feb 17 18:08:47 volumio volumio[1351]: info: URI /mnt/NAS/Qnap/Hammerfall/Legacy Of Kings/02-HammerFall-Legacy_Of_Kings.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Exploding uri music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/03-HammerFall-Let_The_Hammer_Fall.mp3 in service mpd Feb 17 18:08:47 volumio volumio[1351]: info: ALBUMART /albumart?cacheid=250&web=HammerFall/Legacy%20Of%20Kings/extralarge&path=%2Fmnt%2FNAS%2FQnap%2FHammerfall%2FLegacy%20Of%20Kings%2F03-HammerFall-Let_The_Hammer_Fall.mp3&metadata=false Feb 17 18:08:47 volumio volumio[1351]: info: URI /mnt/NAS/Qnap/Hammerfall/Legacy Of Kings/03-HammerFall-Let_The_Hammer_Fall.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Exploding uri music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/04-HammerFall-Dreamland.mp3 in service mpd Feb 17 18:08:47 volumio volumio[1351]: info: ALBUMART /albumart?cacheid=250&web=HammerFall/Legacy%20Of%20Kings/extralarge&path=%2Fmnt%2FNAS%2FQnap%2FHammerfall%2FLegacy%20Of%20Kings%2F04-HammerFall-Dreamland.mp3&metadata=false Feb 17 18:08:47 volumio volumio[1351]: info: URI /mnt/NAS/Qnap/Hammerfall/Legacy Of Kings/04-HammerFall-Dreamland.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Exploding uri music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/05-HammerFall-Remember_Yesterday.mp3 in service mpd Feb 17 18:08:47 volumio volumio[1351]: info: ALBUMART /albumart?cacheid=250&web=HammerFall/Legacy%20Of%20Kings/extralarge&path=%2Fmnt%2FNAS%2FQnap%2FHammerfall%2FLegacy%20Of%20Kings%2F05-HammerFall-Remember_Yesterday.mp3&metadata=false Feb 17 18:08:47 volumio volumio[1351]: info: URI /mnt/NAS/Qnap/Hammerfall/Legacy Of Kings/05-HammerFall-Remember_Yesterday.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Exploding uri music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/06-HammerFall-At_The_End_Of_The_Rainbow.mp3 in service mpd Feb 17 18:08:47 volumio volumio[1351]: info: ALBUMART /albumart?cacheid=250&web=HammerFall/Legacy%20Of%20Kings/extralarge&path=%2Fmnt%2FNAS%2FQnap%2FHammerfall%2FLegacy%20Of%20Kings%2F06-HammerFall-At_The_End_Of_The_Rainbow.mp3&metadata=false Feb 17 18:08:47 volumio volumio[1351]: info: URI /mnt/NAS/Qnap/Hammerfall/Legacy Of Kings/06-HammerFall-At_The_End_Of_The_Rainbow.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Exploding uri music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/07-HammerFall-Back_To_Back.mp3 in service mpd Feb 17 18:08:47 volumio volumio[1351]: info: ALBUMART /albumart?cacheid=250&web=HammerFall/Legacy%20Of%20Kings/extralarge&path=%2Fmnt%2FNAS%2FQnap%2FHammerfall%2FLegacy%20Of%20Kings%2F07-HammerFall-Back_To_Back.mp3&metadata=false Feb 17 18:08:47 volumio volumio[1351]: info: URI /mnt/NAS/Qnap/Hammerfall/Legacy Of Kings/07-HammerFall-Back_To_Back.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Exploding uri music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/08-HammerFall-Stronger_Than_All.mp3 in service mpd Feb 17 18:08:47 volumio volumio[1351]: info: ALBUMART /albumart?cacheid=250&web=HammerFall/Legacy%20Of%20Kings/extralarge&path=%2Fmnt%2FNAS%2FQnap%2FHammerfall%2FLegacy%20Of%20Kings%2F08-HammerFall-Stronger_Than_All.mp3&metadata=false Feb 17 18:08:47 volumio volumio[1351]: info: URI /mnt/NAS/Qnap/Hammerfall/Legacy Of Kings/08-HammerFall-Stronger_Than_All.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Exploding uri music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/09-HammerFall-Warriors_Of_Faith.mp3 in service mpd Feb 17 18:08:47 volumio volumio[1351]: info: ALBUMART /albumart?cacheid=250&web=HammerFall/Legacy%20Of%20Kings/extralarge&path=%2Fmnt%2FNAS%2FQnap%2FHammerfall%2FLegacy%20Of%20Kings%2F09-HammerFall-Warriors_Of_Faith.mp3&metadata=false Feb 17 18:08:47 volumio volumio[1351]: info: URI /mnt/NAS/Qnap/Hammerfall/Legacy Of Kings/09-HammerFall-Warriors_Of_Faith.mp3 Feb 17 18:08:47 volumio volumio[1351]: info: Exploding uri music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/10-HammerFall-The_Fallen_One.mp3 in service mpd Feb 17 18:08:47 volumio volumio[1351]: info: ALBUMART /albumart?cacheid=250&web=HammerFall/Legacy%20Of%20Kings/extralarge&path=%2Fmnt%2FNAS%2FQnap%2FHammerfall%2FLegacy%20Of%20Kings%2F10-HammerFall-The_Fallen_One.mp3&metadata=false Feb 17 18:08:47 volumio volumio[1351]: info: URI /mnt/NAS/Qnap/Hammerfall/Legacy Of Kings/10-HammerFall-The_Fallen_One.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Preload queue cleared Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::ClearQueue Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::stop Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::stPlaybackTimer Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::updateTrackBlock Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrackBlock Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:48 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:48 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::serviceStop Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::serviceStop Feb 17 18:08:48 volumio volumio[1351]: info: [1771348128171] ControllerWebradio::stop Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::clearPlayQueue Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::saveQueue Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::addQueueItems Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::addQueueItems Feb 17 18:08:48 volumio volumio[1351]: info: Preload queue cleared Feb 17 18:08:48 volumio volumio[1351]: info: Adding Item to queue: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/01-HammerFall-Heeding_The_Call.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Using cached record of: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/01-HammerFall-Heeding_The_Call.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::saveQueue Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::updateTrackBlock Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrackBlock Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioPlay Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::play index 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::addQueueItems Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::addQueueItems Feb 17 18:08:48 volumio volumio[1351]: info: Preload queue cleared Feb 17 18:08:48 volumio volumio[1351]: info: Adding Item to queue: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/02-HammerFall-Legacy_Of_Kings.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Using cached record of: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/02-HammerFall-Legacy_Of_Kings.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Adding Item to queue: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/03-HammerFall-Let_The_Hammer_Fall.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Using cached record of: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/03-HammerFall-Let_The_Hammer_Fall.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Adding Item to queue: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/04-HammerFall-Dreamland.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Using cached record of: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/04-HammerFall-Dreamland.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Adding Item to queue: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/05-HammerFall-Remember_Yesterday.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Using cached record of: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/05-HammerFall-Remember_Yesterday.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Adding Item to queue: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/06-HammerFall-At_The_End_Of_The_Rainbow.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Using cached record of: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/06-HammerFall-At_The_End_Of_The_Rainbow.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Adding Item to queue: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/07-HammerFall-Back_To_Back.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Using cached record of: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/07-HammerFall-Back_To_Back.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Adding Item to queue: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/08-HammerFall-Stronger_Than_All.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Using cached record of: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/08-HammerFall-Stronger_Than_All.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Adding Item to queue: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/09-HammerFall-Warriors_Of_Faith.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Using cached record of: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/09-HammerFall-Warriors_Of_Faith.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Adding Item to queue: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/10-HammerFall-The_Fallen_One.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: Using cached record of: music-library/NAS/Qnap/Hammerfall/Legacy Of Kings/10-HammerFall-The_Fallen_One.mp3 Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::stop Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioPushQueue Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::saveQueue Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::play index undefined Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::updateTrackBlock Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrackBlock Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::startPlaybackTimer Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::clearAddPlayTracks NAS/Qnap/Hammerfall/Legacy Of Kings/01-HammerFall-Heeding_The_Call.mp3 Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 18:08:48 volumio volumio[1351]: info: Feb 17 18:08:48 volumio volumio[1351]: ---------------------------- MPD announces state update: player Feb 17 18:08:48 volumio volumio[1351]: info: sendMpdCommand stop took 17 milliseconds Feb 17 18:08:48 volumio volumio[1351]: info: ControllerMpd::getState Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:08:48 volumio volumio[1351]: info: sendMpdCommand stop took 4 milliseconds Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand clear Feb 17 18:08:48 volumio volumio[1351]: info: Feb 17 18:08:48 volumio volumio[1351]: ---------------------------- MPD announces state update: player Feb 17 18:08:48 volumio volumio[1351]: info: ControllerMpd::getState Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:08:48 volumio volumio[1351]: info: Feb 17 18:08:48 volumio volumio[1351]: ---------------------------- MPD announces system playlist update Feb 17 18:08:48 volumio volumio[1351]: info: Ignoring MPD Status Update Feb 17 18:08:48 volumio volumio[1351]: info: sendMpdCommand status took 1 milliseconds Feb 17 18:08:48 volumio volumio[1351]: info: sendMpdCommand clear took 1 milliseconds Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::parseState Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand add "NAS/Qnap/Hammerfall/Legacy Of Kings/01-HammerFall-Heeding_The_Call.mp3" Feb 17 18:08:48 volumio volumio[1351]: info: Feb 17 18:08:48 volumio volumio[1351]: ---------------------------- MPD announces system playlist update Feb 17 18:08:48 volumio volumio[1351]: info: Ignoring MPD Status Update Feb 17 18:08:48 volumio volumio[1351]: error: updateQueue error: null Feb 17 18:08:48 volumio volumio[1351]: info: Feb 17 18:08:48 volumio volumio[1351]: ---------------------------- MPD announces system playlist update Feb 17 18:08:48 volumio volumio[1351]: info: Ignoring MPD Status Update Feb 17 18:08:48 volumio volumio[1351]: info: sendMpdCommand status took 1 milliseconds Feb 17 18:08:48 volumio volumio[1351]: info: ------------------------------ 0ms Feb 17 18:08:48 volumio volumio[1351]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 17 18:08:48 volumio volumio[1351]: info: sendMpdCommand add "NAS/Qnap/Hammerfall/Legacy Of Kings/01-HammerFall-Heeding_The_Call.mp3" took 0 milliseconds Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::parseState Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand play Feb 17 18:08:48 volumio volumio[1351]: info: ControllerMpd::pushState Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::servicePushState Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:48 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:48 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 17 18:08:48 volumio volumio[1351]: verbose: CURRENT POSITION 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::syncState stateService stop Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:48 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:48 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: info: No code Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:48 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:48 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 17 18:08:48 volumio volumio[1351]: info: ------------------------------ 13ms Feb 17 18:08:48 volumio volumio[1351]: info: ------------------------------ 13ms Feb 17 18:08:48 volumio volumio[1351]: info: Feb 17 18:08:48 volumio volumio[1351]: ---------------------------- MPD announces system playlist update Feb 17 18:08:48 volumio volumio[1351]: info: Ignoring MPD Status Update Feb 17 18:08:48 volumio volumio[1351]: info: Feb 17 18:08:48 volumio volumio[1351]: ---------------------------- MPD announces state update: player Feb 17 18:08:48 volumio volumio[1351]: info: Feb 17 18:08:48 volumio volumio[1351]: ---------------------------- MPD announces state update: player Feb 17 18:08:48 volumio volumio[1351]: info: ------------------------------ 13ms Feb 17 18:08:48 volumio volumio[1351]: info: ------------------------------ 13ms Feb 17 18:08:48 volumio volumio[1351]: info: sendMpdCommand play took 12 milliseconds Feb 17 18:08:48 volumio volumio[1351]: info: ControllerMpd::getState Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:08:48 volumio volumio[1351]: info: ------------------------------ 1ms Feb 17 18:08:48 volumio volumio[1351]: info: ControllerMpd::getState Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:08:48 volumio volumio[1351]: info: Feb 17 18:08:48 volumio volumio[1351]: ---------------------------- MPD announces state update: player Feb 17 18:08:48 volumio volumio[1351]: info: ControllerMpd::getState Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand status Feb 17 18:08:48 volumio volumio[1351]: info: sendMpdCommand status took 6 milliseconds Feb 17 18:08:48 volumio volumio[1351]: info: sendMpdCommand status took 6 milliseconds Feb 17 18:08:48 volumio volumio[1351]: info: sendMpdCommand status took 6 milliseconds Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::parseState Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::parseState Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::parseState Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 18:08:48 volumio volumio[1351]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 17 18:08:48 volumio volumio[1351]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 17 18:08:48 volumio volumio[1351]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:08:48 volumio volumio[1351]: verbose: ControllerMpd::parseTrackInfo Feb 17 18:08:48 volumio volumio[1351]: info: ControllerMpd::pushState Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::servicePushState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Heeding The Call","artist":"HammerFall","album":"Legacy Of Kings","uri":"NAS/Qnap/Hammerfall/Legacy Of Kings/01-HammerFall-Heeding_The_Call.mp3","trackType":"mp3"} Feb 17 18:08:48 volumio volumio[1351]: verbose: CURRENT POSITION 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::syncState stateService play Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::syncState currentStatus stop Feb 17 18:08:48 volumio volumio[1351]: info: ControllerMpd::pushState Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::servicePushState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Heeding The Call","artist":"HammerFall","album":"Legacy Of Kings","uri":"NAS/Qnap/Hammerfall/Legacy Of Kings/01-HammerFall-Heeding_The_Call.mp3","trackType":"mp3"} Feb 17 18:08:48 volumio volumio[1351]: verbose: CURRENT POSITION 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::syncState stateService play Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:08:48 volumio volumio[1351]: info: Received an update from plugin. extracting info from payload Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:48 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:48 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:48 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:48 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: info: ControllerMpd::pushState Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::servicePushState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Heeding The Call","artist":"HammerFall","album":"Legacy Of Kings","uri":"NAS/Qnap/Hammerfall/Legacy Of Kings/01-HammerFall-Heeding_The_Call.mp3","trackType":"mp3"} Feb 17 18:08:48 volumio volumio[1351]: verbose: CURRENT POSITION 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::syncState stateService play Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::syncState currentStatus play Feb 17 18:08:48 volumio volumio[1351]: info: Received an update from plugin. extracting info from payload Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:48 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:48 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:48 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:48 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:48 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:48 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:48 volumio volumio[1351]: info: ------------------------------ 12ms Feb 17 18:08:48 volumio volumio[1351]: info: ------------------------------ 21ms Feb 17 18:08:48 volumio volumio[1351]: info: ------------------------------ 21ms Feb 17 18:08:52 volumio volumio[1351]: info: VolumeController::SetAlsaVolume55 Feb 17 18:08:52 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:52 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:52 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:08:52 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:52 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:52 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:52 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:52 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:53 volumio volumio[1351]: info: VolumeController::SetAlsaVolume30 Feb 17 18:08:53 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:08:53 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:53 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:08:53 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:08:53 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:08:53 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:08:53 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:53 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:54 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 17 18:08:54 volumio volumio[1351]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 17 18:08:54 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 17 18:08:54 volumio volumio[1351]: info: Received Get System Version Feb 17 18:08:54 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 18:08:54 volumio volumio[1351]: info: Received Get System Info Feb 17 18:08:54 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 18:08:54 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 18:08:54 volumio volumio[1351]: info: Discovery: Getting this device information Feb 17 18:08:54 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:08:54 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:08:54 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 18:09:02 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Feb 17 18:09:02 volumio volumio[1351]: info: Setting Remote Device Volume: http://192.168.1.106 Feb 17 18:09:02 volumio volumio[1351]: info: Feb 17 18:09:02 volumio volumio[1351]: ---------------------------- Client requests Volume 46 Feb 17 18:09:02 volumio volumio[1351]: info: VolumeController::SetAlsaVolume46 Feb 17 18:09:02 volumio volumio[1351]: info: CoreStateMachine::pushState Feb 17 18:09:02 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:09:02 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 18:09:02 volumio volumio[1351]: info: CoreCommandRouter::volumioPushState Feb 17 18:09:02 volumio volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Feb 17 18:09:02 volumio volumio[1351]: info: MRS: Pushing multiroomSync output Feb 17 18:09:02 volumio volumio[1351]: info: CoreCommandRouter::volumioGetState Feb 17 18:09:02 volumio volumio[1351]: info: CorePlayQueue::getTrack 0 Feb 17 18:09:02 volumio volumio[1351]: info: Done setting volume on: Feb 17 18:09:06 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Feb 17 18:09:06 volumio volumio[1351]: info: MRS: Starting cast device: QN7XF-83e1d78e1fb3e0474e4fde6c9ea6c2d7 Feb 17 18:09:06 volumio volumio[1351]: info: Enabled audio output: QN7XF-83e1d78e1fb3e0474e4fde6c9ea6c2d7 Feb 17 18:09:06 volumio volumio[1351]: info: MRS: Setting this device as Streaming Server Feb 17 18:09:06 volumio volumio[1351]: info: Feb 17 18:09:06 volumio volumio[1351]: [1771348146864] ---------------------------- MRS: Setting Streaming Server Feb 17 18:09:06 volumio volumio[1351]: info: MRS: enable multiroom server output Feb 17 18:09:06 volumio volumio[1351]: info: MRS: Set multiroom target PCM to volumioMultiRoom Feb 17 18:09:06 volumio volumio[1351]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Feb 17 18:09:06 volumio volumio[1351]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Feb 17 18:09:06 volumio volumio[1351]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Feb 17 18:09:06 volumio volumio[1351]: info: MRS: STARTING volumioStreaming Feb 17 18:09:06 volumio sudo[5605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream53.ts /tmp/hls/stream54.ts /tmp/hls/stream55.ts /tmp/hls/stream56.ts Feb 17 18:09:06 volumio sudo[5605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:09:06 volumio sudo[5605]: pam_unix(sudo:session): session closed for user root Feb 17 18:09:06 volumio sudo[5607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Feb 17 18:09:06 volumio sudo[5607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:09:06 volumio systemd[1]: Stopping volumioStreaming.service - VolumioStreamingService... Feb 17 18:09:06 volumio systemd[1]: volumioStreaming.service: Killing process 5334 (ffmpeg) with signal SIGKILL. Feb 17 18:09:06 volumio systemd[1]: volumioStreaming.service: Killing process 5413 (ffmpeg) with signal SIGKILL. Feb 17 18:09:06 volumio systemd[1]: volumioStreaming.service: Deactivated successfully. Feb 17 18:09:06 volumio systemd[1]: Stopped volumioStreaming.service - VolumioStreamingService. Feb 17 18:09:06 volumio systemd[1]: volumioStreaming.service: Consumed 2.576s CPU time. Feb 17 18:09:06 volumio systemd[1]: Started volumioStreaming.service - VolumioStreamingService. Feb 17 18:09:06 volumio sudo[5607]: pam_unix(sudo:session): session closed for user root Feb 17 18:09:06 volumio volumio[1351]: info: MRS: volumioStreaming STARTED Feb 17 18:09:06 volumio sudo[5611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 17 18:09:06 volumio sudo[5611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:09:06 volumio sudo[5611]: pam_unix(sudo:session): session closed for user root Feb 17 18:09:08 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Feb 17 18:09:08 volumio volumio[1351]: info: MRS: Setting this device as single Feb 17 18:09:08 volumio volumio[1351]: info: Feb 17 18:09:08 volumio volumio[1351]: [1771348148392] ---------------------------- Setting Multiroom Single Feb 17 18:09:08 volumio volumio[1351]: info: MRS: disable multiroom output Feb 17 18:09:08 volumio volumio[1351]: info: MRS: Set multiroom target PCM to volumioDiscard Feb 17 18:09:08 volumio volumio[1351]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Feb 17 18:09:09 volumio volumio[1351]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Feb 17 18:09:09 volumio volumio[1351]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Feb 17 18:09:09 volumio volumio[1351]: info: MRS: STOPPING volumioStreaming Feb 17 18:09:09 volumio volumio[1351]: error: Cannot set properties of undefined (setting 'enabled') Feb 17 18:09:09 volumio sudo[5624]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 17 18:09:09 volumio sudo[5624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:09:09 volumio sudo[5626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream0.ts /tmp/hls/stream1.ts Feb 17 18:09:09 volumio sudo[5626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:09:09 volumio sudo[5626]: pam_unix(sudo:session): session closed for user root Feb 17 18:09:09 volumio volumio[1351]: info: MRS: Removed streaming files Feb 17 18:09:09 volumio systemd[1]: Stopping volumioStreaming.service - VolumioStreamingService... Feb 17 18:09:09 volumio systemd[1]: volumioStreaming.service: Killing process 5613 (ffmpeg) with signal SIGKILL. Feb 17 18:09:09 volumio systemd[1]: volumioStreaming.service: Killing process 5616 (ffmpeg) with signal SIGKILL. Feb 17 18:09:09 volumio systemd[1]: volumioStreaming.service: Deactivated successfully. Feb 17 18:09:09 volumio systemd[1]: Stopped volumioStreaming.service - VolumioStreamingService. Feb 17 18:09:09 volumio sudo[5624]: pam_unix(sudo:session): session closed for user root Feb 17 18:09:09 volumio volumio[1351]: info: MRS: volumioStreaming STOPPED Feb 17 18:09:18 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Feb 17 18:09:18 volumio volumio[1351]: info: MRS: Starting browser stream Feb 17 18:09:18 volumio volumio[1351]: info: MRS: Setting this device as Streaming Server Feb 17 18:09:18 volumio volumio[1351]: info: Feb 17 18:09:18 volumio volumio[1351]: [1771348158180] ---------------------------- MRS: Setting Streaming Server Feb 17 18:09:18 volumio volumio[1351]: info: MRS: enable multiroom server output Feb 17 18:09:18 volumio volumio[1351]: info: MRS: Set multiroom target PCM to volumioMultiRoom Feb 17 18:09:18 volumio volumio[1351]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Feb 17 18:09:18 volumio volumio[1351]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Feb 17 18:09:18 volumio volumio[1351]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Feb 17 18:09:18 volumio volumio[1351]: info: MRS: STARTING volumioStreaming Feb 17 18:09:18 volumio sudo[5657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 17 18:09:18 volumio sudo[5657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:09:18 volumio sudo[5657]: pam_unix(sudo:session): session closed for user root Feb 17 18:09:18 volumio sudo[5659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Feb 17 18:09:18 volumio sudo[5659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:09:18 volumio systemd[1]: Started volumioStreaming.service - VolumioStreamingService. Feb 17 18:09:18 volumio sudo[5659]: pam_unix(sudo:session): session closed for user root Feb 17 18:09:18 volumio volumio[1351]: info: MRS: volumioStreaming STARTED Feb 17 18:09:18 volumio sudo[5663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 17 18:09:18 volumio sudo[5663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 18:09:18 volumio sudo[5663]: pam_unix(sudo:session): session closed for user root Feb 17 18:09:21 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Feb 17 18:09:21 volumio volumio[1351]: info: MRS: Starting browser stream Feb 17 18:09:37 volumio volumio[1351]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Feb 17 18:09:37 volumio volumio[1351]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Feb 17 18:09:37 volumio volumio[1351]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 18:09:37 volumio volumio[1351]: TypeError: Cannot read properties of undefined (reading 'then') Feb 17 18:09:37 volumio volumio[1351]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Feb 17 18:09:37 volumio volumio[1351]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Feb 17 18:09:37 volumio volumio[1351]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Feb 17 18:09:37 volumio volumio[1351]: at Socket.emit (node:events:514:28) Feb 17 18:09:37 volumio volumio[1351]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 17 18:09:37 volumio volumio[1351]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Feb 17 18:09:37 volumio volumio[1351]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 18:09:37 volumio sudo[5744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 18:08' Feb 17 18:09:37 volumio sudo[5744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"