-- Logs begin at Fri 2025-10-24 15:12:16 MSK, end at Sat 2025-11-01 00:22:48 MSK. -- Nov 01 00:21:00 smsl-n100-repacked sudo[4674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 01 00:21:00 smsl-n100-repacked sudo[4674]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:00 smsl-n100-repacked sudo[4677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 01 00:21:00 smsl-n100-repacked sudo[4677]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:00 smsl-n100-repacked sudo[4674]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:00 smsl-n100-repacked sudo[4677]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:00 smsl-n100-repacked volumio[964]: xcb_connection_has_error() returned true Nov 01 00:21:00 smsl-n100-repacked volumio[964]: info: Output device has changed, restarting MPD Nov 01 00:21:00 smsl-n100-repacked sudo[4683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 00:21:00 smsl-n100-repacked volumio[964]: info: Output device has changed, restarting Shairport Sync Nov 01 00:21:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 00:21:00 smsl-n100-repacked sudo[4683]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:00 smsl-n100-repacked sudo[4683]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:00 smsl-n100-repacked sudo[4685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 00:21:00 smsl-n100-repacked sudo[4685]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:00 smsl-n100-repacked systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 01 00:21:00 smsl-n100-repacked systemd[1]: mpd.service: Succeeded. Nov 01 00:21:00 smsl-n100-repacked systemd[1]: Stopped Music Player Daemon. Nov 01 00:21:00 smsl-n100-repacked systemd[1]: Starting Music Player Daemon... Nov 01 00:21:00 smsl-n100-repacked volumio[964]: xcb_connection_has_error() returned true Nov 01 00:21:00 smsl-n100-repacked volumio[964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 01 00:21:00 smsl-n100-repacked volumio[964]: xcb_connection_has_error() returned true Nov 01 00:21:00 smsl-n100-repacked volumio[964]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 01 00:21:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:00 smsl-n100-repacked volumio[964]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Nov 01 00:21:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:00 smsl-n100-repacked sudo[4696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 00:21:00 smsl-n100-repacked sudo[4696]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:00 smsl-n100-repacked sudo[4691]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 00:21:00 smsl-n100-repacked sudo[4691]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:00 smsl-n100-repacked systemd[1]: Stopping Volumio Tidal Connect Service... Nov 01 00:21:00 smsl-n100-repacked sudo[4691]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:00 smsl-n100-repacked systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 01 00:21:00 smsl-n100-repacked systemd[1]: vtcs.service: Succeeded. Nov 01 00:21:00 smsl-n100-repacked systemd[1]: Stopped Volumio Tidal Connect Service. Nov 01 00:21:00 smsl-n100-repacked sudo[4696]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:01 smsl-n100-repacked volumio[964]: xcb_connection_has_error() returned true Nov 01 00:21:01 smsl-n100-repacked volumio[964]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Nov 01 00:21:02 smsl-n100-repacked volumio[964]: xcb_connection_has_error() returned true Nov 01 00:21:02 smsl-n100-repacked volumio[964]: Found hardware: "USB-Audio" "USB Mixer" "USB0c76:1607" "" "" Nov 01 00:21:02 smsl-n100-repacked volumio[964]: Hardware is initialized using a generic method Nov 01 00:21:02 smsl-n100-repacked volumio[964]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Nov 01 00:21:02 smsl-n100-repacked volumio[964]: info: Output device has changed, restarting MPD Nov 01 00:21:02 smsl-n100-repacked sudo[4720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 00:21:02 smsl-n100-repacked sudo[4720]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:02 smsl-n100-repacked sudo[4723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 00:21:02 smsl-n100-repacked sudo[4723]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:02 smsl-n100-repacked sudo[4720]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:02 smsl-n100-repacked volumio[964]: info: Output device has changed, restarting Shairport Sync Nov 01 00:21:02 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:02 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 00:21:02 smsl-n100-repacked sudo[4723]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:02 smsl-n100-repacked sudo[4727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 00:21:02 smsl-n100-repacked sudo[4727]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:02 smsl-n100-repacked systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 01 00:21:02 smsl-n100-repacked systemd[1]: mpd.service: Succeeded. Nov 01 00:21:02 smsl-n100-repacked systemd[1]: Stopped Music Player Daemon. Nov 01 00:21:02 smsl-n100-repacked systemd[1]: Starting Music Player Daemon... Nov 01 00:21:02 smsl-n100-repacked volumio[964]: xcb_connection_has_error() returned true Nov 01 00:21:02 smsl-n100-repacked volumio[964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 01 00:21:02 smsl-n100-repacked volumio[964]: xcb_connection_has_error() returned true Nov 01 00:21:02 smsl-n100-repacked volumio[964]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 01 00:21:02 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:02 smsl-n100-repacked volumio[964]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Nov 01 00:21:02 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:02 smsl-n100-repacked sudo[4736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 00:21:02 smsl-n100-repacked sudo[4736]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:02 smsl-n100-repacked sudo[4732]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 00:21:02 smsl-n100-repacked sudo[4732]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:02 smsl-n100-repacked sudo[4732]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:02 smsl-n100-repacked sudo[4736]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:02 smsl-n100-repacked volumio[964]: xcb_connection_has_error() returned true Nov 01 00:21:02 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 01 00:21:02 smsl-n100-repacked volumio[964]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 01 00:21:02 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 01 00:21:02 smsl-n100-repacked volumio[964]: info: Disabling external Volume Control Nov 01 00:21:02 smsl-n100-repacked sudo[4752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 00:21:02 smsl-n100-repacked sudo[4752]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:02 smsl-n100-repacked sudo[4755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 00:21:02 smsl-n100-repacked sudo[4755]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:02 smsl-n100-repacked sudo[4752]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:03 smsl-n100-repacked sudo[4755]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:03 smsl-n100-repacked sudo[4759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 00:21:03 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 00:21:03 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 00:21:03 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 00:21:03 smsl-n100-repacked sudo[4759]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:03 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 00:21:03 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 00:21:03 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 00:21:03 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 00:21:03 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 00:21:03 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: RAAT: Requesting Headphone Status Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: RAAT: Requesting Headphone Status Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Nov 01 00:21:03 smsl-n100-repacked sudo[4759]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: MPD Permissions set Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: MPD Permissions set Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: TidalConnect service started! Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: TidalConnect service started! Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:03 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined Nov 01 00:21:03 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 01 00:21:03 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:03 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined Nov 01 00:21:03 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 01 00:21:03 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 01 00:21:03 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:03 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:03 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 01 00:21:03 smsl-n100-repacked volumio[964]: SPOTIFY: SPOTIFY VOLUME 100 Nov 01 00:21:03 smsl-n100-repacked volumio[964]: SPOTIFY: VOLUMIO VOLUME 100 Nov 01 00:21:03 smsl-n100-repacked volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: Executing endpoint tc_getconfig Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume0 Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:03 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume0 Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: TidalConnect service stoped! Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Nov 01 00:21:04 smsl-n100-repacked volumio[964]: xcb_connection_has_error() returned true Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Nov 01 00:21:04 smsl-n100-repacked sudo[4792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Nov 01 00:21:04 smsl-n100-repacked sudo[4792]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:04 smsl-n100-repacked sudo[4792]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:04 smsl-n100-repacked sudo[4796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Nov 01 00:21:04 smsl-n100-repacked sudo[4796]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:04 smsl-n100-repacked volumio[964]: xcb_connection_has_error() returned true Nov 01 00:21:04 smsl-n100-repacked systemd[1]: Stopping RAAT DAEMON... Nov 01 00:21:04 smsl-n100-repacked systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Nov 01 00:21:04 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Nov 01 00:21:04 smsl-n100-repacked systemd[1]: raat-daemon.service: Succeeded. Nov 01 00:21:04 smsl-n100-repacked systemd[1]: Stopped RAAT DAEMON. Nov 01 00:21:04 smsl-n100-repacked systemd[1]: Started RAAT DAEMON. Nov 01 00:21:04 smsl-n100-repacked sudo[4799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Nov 01 00:21:04 smsl-n100-repacked sudo[4796]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:04 smsl-n100-repacked sudo[4799]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:04 smsl-n100-repacked sudo[4799]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:05 smsl-n100-repacked volumio[964]: xcb_connection_has_error() returned true Nov 01 00:21:05 smsl-n100-repacked sudo[4808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Nov 01 00:21:05 smsl-n100-repacked sudo[4808]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:05 smsl-n100-repacked systemd[1]: Stopping RAAT DAEMON... Nov 01 00:21:05 smsl-n100-repacked systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Nov 01 00:21:05 smsl-n100-repacked systemd[1]: raat-daemon.service: Succeeded. Nov 01 00:21:05 smsl-n100-repacked systemd[1]: Stopped RAAT DAEMON. Nov 01 00:21:05 smsl-n100-repacked systemd[1]: Started RAAT DAEMON. Nov 01 00:21:05 smsl-n100-repacked sudo[4808]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:05 smsl-n100-repacked sudo[4811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Nov 01 00:21:05 smsl-n100-repacked sudo[4811]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:05 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Nov 01 00:21:05 smsl-n100-repacked sudo[4811]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:05 smsl-n100-repacked sudo[4818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Nov 01 00:21:05 smsl-n100-repacked sudo[4818]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:05 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioGetState Nov 01 00:21:05 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:05 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 00:21:05 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 00:21:05 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 00:21:05 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 00:21:05 smsl-n100-repacked systemd[1]: Stopping RAAT DAEMON... Nov 01 00:21:05 smsl-n100-repacked sudo[4820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 00:21:05 smsl-n100-repacked sudo[4820]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:05 smsl-n100-repacked systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Nov 01 00:21:05 smsl-n100-repacked systemd[1]: raat-daemon.service: Succeeded. Nov 01 00:21:05 smsl-n100-repacked systemd[1]: Stopped RAAT DAEMON. Nov 01 00:21:05 smsl-n100-repacked volumio[964]: info: Raat Daemon started successfully Nov 01 00:21:05 smsl-n100-repacked volumio[964]: info: Raat Daemon started successfully Nov 01 00:21:05 smsl-n100-repacked volumio[964]: info: TidalConnect service stoped! Nov 01 00:21:05 smsl-n100-repacked systemd[1]: Started RAAT DAEMON. Nov 01 00:21:05 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 00:21:05 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 00:21:05 smsl-n100-repacked volumio[964]: info: Starting Shairport Sync Nov 01 00:21:05 smsl-n100-repacked sudo[4818]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:05 smsl-n100-repacked systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 01 00:21:05 smsl-n100-repacked systemd[1]: mpd.service: Succeeded. Nov 01 00:21:05 smsl-n100-repacked systemd[1]: Stopped Music Player Daemon. Nov 01 00:21:05 smsl-n100-repacked volumio[964]: info: Starting Shairport Sync Nov 01 00:21:05 smsl-n100-repacked systemd[1]: Starting Music Player Daemon... Nov 01 00:21:05 smsl-n100-repacked sudo[4829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 01 00:21:05 smsl-n100-repacked sudo[4829]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:05 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 00:21:05 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 00:21:05 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 00:21:05 smsl-n100-repacked systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 01 00:21:05 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 00:21:05 smsl-n100-repacked sudo[4833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 01 00:21:05 smsl-n100-repacked sudo[4833]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:05 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:05 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:05 smsl-n100-repacked systemd[1]: shairport-sync.service: Succeeded. Nov 01 00:21:05 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 00:21:05 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 00:21:05 smsl-n100-repacked systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 01 00:21:05 smsl-n100-repacked volumio[964]: info: TidalConnect service stoped! Nov 01 00:21:05 smsl-n100-repacked volumio[964]: info: Raat Daemon started successfully Nov 01 00:21:05 smsl-n100-repacked volumio[964]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 00:21:05 smsl-n100-repacked sudo[4830]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 00:21:05 smsl-n100-repacked sudo[4830]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:05 smsl-n100-repacked systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 01 00:21:05 smsl-n100-repacked sudo[4830]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:05 smsl-n100-repacked sudo[4833]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:05 smsl-n100-repacked sudo[4829]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:05 smsl-n100-repacked volumio[964]: info: Shairport-Sync Started Nov 01 00:21:05 smsl-n100-repacked volumio[964]: info: Shairport-Sync Started Nov 01 00:21:05 smsl-n100-repacked volumio[964]: info: TidalConnect service stoped! Nov 01 00:21:06 smsl-n100-repacked volumio[964]: info: TidalConnect service stoped! Nov 01 00:21:06 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPlay Nov 01 00:21:06 smsl-n100-repacked volumio[964]: info: CoreStateMachine::play index undefined Nov 01 00:21:06 smsl-n100-repacked volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 00:21:06 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:06 smsl-n100-repacked volumio[964]: info: CoreStateMachine::startPlaybackTimer Nov 01 00:21:06 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:06 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioGetVisibleSources Nov 01 00:21:06 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 00:21:06 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 01 00:21:06 smsl-n100-repacked volumio[964]: info: [1761945666210] ControllerCalmRadio::clearAddPlayTrack Nov 01 00:21:06 smsl-n100-repacked volumio[964]: { Nov 01 00:21:06 smsl-n100-repacked volumio[964]: uri: 'calmradio://16/143', Nov 01 00:21:06 smsl-n100-repacked volumio[964]: service: 'calmradio', Nov 01 00:21:06 smsl-n100-repacked volumio[964]: trackType: 'CalmRadio', Nov 01 00:21:06 smsl-n100-repacked volumio[964]: radioType: 'web', Nov 01 00:21:06 smsl-n100-repacked volumio[964]: name: 'HAYDN', Nov 01 00:21:06 smsl-n100-repacked volumio[964]: title: 'HAYDN', Nov 01 00:21:06 smsl-n100-repacked volumio[964]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/000/143/2ab14d7ecceff662737fb6145c7db38a08d461aa60d4ba58301043912646.png', Nov 01 00:21:06 smsl-n100-repacked volumio[964]: type: 'webradio', Nov 01 00:21:06 smsl-n100-repacked volumio[964]: samplerate: '', Nov 01 00:21:06 smsl-n100-repacked volumio[964]: bitdepth: 0, Nov 01 00:21:06 smsl-n100-repacked volumio[964]: channels: 0 Nov 01 00:21:06 smsl-n100-repacked volumio[964]: } Nov 01 00:21:06 smsl-n100-repacked volumio[964]: info: Calm Radio getStreamUrl for Cat 16 Chan 143 Nov 01 00:21:06 smsl-n100-repacked volumio[964]: STREAMURL: https://streams.calmradio.com/api/291/128/stream Nov 01 00:21:06 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 00:21:06 smsl-n100-repacked volumio[964]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 01 00:21:06 smsl-n100-repacked volumio[964]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 01 00:21:06 smsl-n100-repacked sudo[4855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 01 00:21:06 smsl-n100-repacked sudo[4855]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:06 smsl-n100-repacked volumio[964]: info: TidalConnect service stoped! Nov 01 00:21:06 smsl-n100-repacked systemd[1]: Started Volumio Tidal Connect Service. Nov 01 00:21:06 smsl-n100-repacked sudo[4855]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:06 smsl-n100-repacked volumio[964]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 01 00:21:06 smsl-n100-repacked volumio[964]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 01 00:21:07 smsl-n100-repacked sudo[4869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 01 00:21:07 smsl-n100-repacked sudo[4869]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: Executing endpoint restartRAATSocket Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Nov 01 00:21:07 smsl-n100-repacked sudo[4869]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:07 smsl-n100-repacked sudo[4902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 01 00:21:07 smsl-n100-repacked sudo[4902]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: Executing endpoint metavolumio Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 00:21:07 smsl-n100-repacked volumio[964]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Nov 01 00:21:07 smsl-n100-repacked sudo[4902]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: Executing endpoint tc_getconfig Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 01 00:21:07 smsl-n100-repacked vtcs[4858]: STARTING TidalConnect services, version: 1.5.1-beta1 Nov 01 00:21:07 smsl-n100-repacked vtcs[4858]: STARTED TidalConnect services. Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: Executing endpoint tc_connect Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: Connecting to TidalConnect Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received tidalconnect Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received tidalconnect Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: RAAT: Requesting Headphone Status Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: RAAT: Requesting Headphone Status Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:07 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined Nov 01 00:21:07 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 01 00:21:07 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:07 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:08 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:08 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:08 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined Nov 01 00:21:08 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 01 00:21:08 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined Nov 01 00:21:08 smsl-n100-repacked volumio[964]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 01 00:21:08 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:08 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:08 smsl-n100-repacked volumio[964]: info: RAAT: Requesting Headphone Status Nov 01 00:21:08 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Nov 01 00:21:08 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:08 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:08 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:08 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:08 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:08 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:08 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined Nov 01 00:21:08 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 01 00:21:08 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined Nov 01 00:21:08 smsl-n100-repacked volumio[964]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 01 00:21:08 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:09 smsl-n100-repacked volumio[964]: info: TidalConnect service started! Nov 01 00:21:09 smsl-n100-repacked mpd[4839]: Nov 01 00:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 01 00:21:09 smsl-n100-repacked systemd[1]: Started Music Player Daemon. Nov 01 00:21:09 smsl-n100-repacked sudo[4550]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:09 smsl-n100-repacked sudo[4727]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:09 smsl-n100-repacked sudo[4820]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:09 smsl-n100-repacked sudo[4685]: pam_unix(sudo:session): session closed for user root Nov 01 00:21:10 smsl-n100-repacked volumio[964]: error: MPD error: The expression evaluated to a falsy value: Nov 01 00:21:10 smsl-n100-repacked volumio[964]: assert.ok(self.idling) Nov 01 00:21:10 smsl-n100-repacked volumio[964]: error: The expression evaluated to a falsy value: Nov 01 00:21:10 smsl-n100-repacked volumio[964]: assert.ok(self.idling) Nov 01 00:21:10 smsl-n100-repacked volumio[964]: error: MPD error: The expression evaluated to a falsy value: Nov 01 00:21:10 smsl-n100-repacked volumio[964]: assert.ok(self.idling) Nov 01 00:21:10 smsl-n100-repacked volumio[964]: error: The expression evaluated to a falsy value: Nov 01 00:21:10 smsl-n100-repacked volumio[964]: assert.ok(self.idling) Nov 01 00:21:10 smsl-n100-repacked volumio[964]: error: MPD error: The expression evaluated to a falsy value: Nov 01 00:21:10 smsl-n100-repacked volumio[964]: assert.ok(self.idling) Nov 01 00:21:10 smsl-n100-repacked volumio[964]: error: The expression evaluated to a falsy value: Nov 01 00:21:10 smsl-n100-repacked volumio[964]: assert.ok(self.idling) Nov 01 00:21:10 smsl-n100-repacked volumio[964]: error: updateQueue error: null Nov 01 00:21:10 smsl-n100-repacked volumio[964]: info: Executing endpoint metavolumio Nov 01 00:21:10 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 00:21:10 smsl-n100-repacked volumio[964]: info: TidalConnect service started! Nov 01 00:21:10 smsl-n100-repacked volumio[964]: info: TidalConnect service started! Nov 01 00:21:12 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 00:21:12 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 00:21:12 smsl-n100-repacked volumio[964]: info: Discovery: Getting this device information Nov 01 00:21:12 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioGetState Nov 01 00:21:12 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:12 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 00:21:19 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPlay Nov 01 00:21:19 smsl-n100-repacked volumio[964]: info: CoreStateMachine::play index undefined Nov 01 00:21:19 smsl-n100-repacked volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 00:21:19 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:19 smsl-n100-repacked volumio[964]: info: CoreStateMachine::startPlaybackTimer Nov 01 00:21:19 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:19 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioGetVisibleSources Nov 01 00:21:19 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 00:21:19 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 01 00:21:19 smsl-n100-repacked volumio[964]: info: [1761945679916] ControllerCalmRadio::clearAddPlayTrack Nov 01 00:21:19 smsl-n100-repacked volumio[964]: { Nov 01 00:21:19 smsl-n100-repacked volumio[964]: uri: 'calmradio://16/143', Nov 01 00:21:19 smsl-n100-repacked volumio[964]: service: 'calmradio', Nov 01 00:21:19 smsl-n100-repacked volumio[964]: trackType: 'CalmRadio', Nov 01 00:21:19 smsl-n100-repacked volumio[964]: radioType: 'web', Nov 01 00:21:19 smsl-n100-repacked volumio[964]: name: 'HAYDN', Nov 01 00:21:19 smsl-n100-repacked volumio[964]: title: 'HAYDN', Nov 01 00:21:19 smsl-n100-repacked volumio[964]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/000/143/2ab14d7ecceff662737fb6145c7db38a08d461aa60d4ba58301043912646.png', Nov 01 00:21:19 smsl-n100-repacked volumio[964]: type: 'webradio', Nov 01 00:21:19 smsl-n100-repacked volumio[964]: samplerate: '', Nov 01 00:21:19 smsl-n100-repacked volumio[964]: bitdepth: 0, Nov 01 00:21:19 smsl-n100-repacked volumio[964]: channels: 0 Nov 01 00:21:19 smsl-n100-repacked volumio[964]: } Nov 01 00:21:19 smsl-n100-repacked volumio[964]: info: Calm Radio getStreamUrl for Cat 16 Chan 143 Nov 01 00:21:20 smsl-n100-repacked volumio[964]: STREAMURL: https://streams.calmradio.com/api/291/128/stream Nov 01 00:21:20 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: sendMpdCommand stop took 3 milliseconds Nov 01 00:21:20 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand clear Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:20 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: sendMpdCommand clear took 4 milliseconds Nov 01 00:21:20 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/291/128/stream" Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:20 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:20 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:20 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: error: updateQueue error: null Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:20 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: ------------------------------ 22ms Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: sendMpdCommand addid "https://streams.calmradio.com/api/291/128/stream" took 19 milliseconds Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: ------------------------------ 18ms Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: ------------------------------ 16ms Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: ------------------------------ 12ms Nov 01 00:21:20 smsl-n100-repacked volumio[964]: verbose: MPD COMMAND [object Object] Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:20 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:20 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:20 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:20 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: ------------------------------ 26ms Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: ------------------------------ 15ms Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: ------------------------------ 13ms Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: ------------------------------ 10ms Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 01 00:21:20 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand play Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:20 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:20 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:20 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: ------------------------------ 27ms Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: sendMpdCommand play took 19 milliseconds Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: ------------------------------ 18ms Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: ------------------------------ 14ms Nov 01 00:21:20 smsl-n100-repacked volumio[964]: info: ------------------------------ 15ms Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPlay Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreStateMachine::play index undefined Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreStateMachine::startPlaybackTimer Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioGetVisibleSources Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: [1761945682009] ControllerCalmRadio::clearAddPlayTrack Nov 01 00:21:22 smsl-n100-repacked volumio[964]: { Nov 01 00:21:22 smsl-n100-repacked volumio[964]: uri: 'calmradio://16/143', Nov 01 00:21:22 smsl-n100-repacked volumio[964]: service: 'calmradio', Nov 01 00:21:22 smsl-n100-repacked volumio[964]: trackType: 'CalmRadio', Nov 01 00:21:22 smsl-n100-repacked volumio[964]: radioType: 'web', Nov 01 00:21:22 smsl-n100-repacked volumio[964]: name: 'HAYDN', Nov 01 00:21:22 smsl-n100-repacked volumio[964]: title: 'HAYDN', Nov 01 00:21:22 smsl-n100-repacked volumio[964]: albumart: 'https://arts.calmradio.com/fapi/000/000/000/000/000/143/2ab14d7ecceff662737fb6145c7db38a08d461aa60d4ba58301043912646.png', Nov 01 00:21:22 smsl-n100-repacked volumio[964]: type: 'webradio', Nov 01 00:21:22 smsl-n100-repacked volumio[964]: samplerate: '', Nov 01 00:21:22 smsl-n100-repacked volumio[964]: bitdepth: 0, Nov 01 00:21:22 smsl-n100-repacked volumio[964]: channels: 0 Nov 01 00:21:22 smsl-n100-repacked volumio[964]: } Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Calm Radio getStreamUrl for Cat 16 Chan 143 Nov 01 00:21:22 smsl-n100-repacked volumio[964]: STREAMURL: https://streams.calmradio.com/api/291/128/stream Nov 01 00:21:22 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: sendMpdCommand stop took 312 milliseconds Nov 01 00:21:22 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand clear Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:22 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:22 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: sendMpdCommand clear took 17 milliseconds Nov 01 00:21:22 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand addid "https://streams.calmradio.com/api/291/128/stream" Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:22 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:22 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:22 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:22 smsl-n100-repacked volumio[964]: error: updateQueue error: null Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:22 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 30 milliseconds Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ------------------------------ 46ms Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: sendMpdCommand addid "https://streams.calmradio.com/api/291/128/stream" took 29 milliseconds Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 25 milliseconds Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 20 milliseconds Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 15 milliseconds Nov 01 00:21:22 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Nov 01 00:21:22 smsl-n100-repacked volumio[964]: verbose: MPD COMMAND [object Object] Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current calmradio Received mpd Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ------------------------------ 248ms Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ------------------------------ 228ms Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ------------------------------ 224ms Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ------------------------------ 219ms Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:22 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:22 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:22 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:22 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ------------------------------ 239ms Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ------------------------------ 27ms Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ------------------------------ 24ms Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ------------------------------ 14ms Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 01 00:21:22 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand play Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:22 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:22 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:22 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:22 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:22 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:22 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:22 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ------------------------------ 87ms Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: sendMpdCommand play took 77 milliseconds Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ------------------------------ 75ms Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ------------------------------ 70ms Nov 01 00:21:22 smsl-n100-repacked volumio[964]: info: ------------------------------ 66ms Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:24 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:24 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:24 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:24 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 31 milliseconds Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:24 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 34 milliseconds Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 29 milliseconds Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 25 milliseconds Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HAYDN","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/291/128/stream","trackType":""} Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: CURRENT POSITION 0 Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState stateService play Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState currentStatus stop Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ------------------------------ 68ms Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:24 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:24 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:24 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 38 milliseconds Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 31 milliseconds Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 32 milliseconds Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 31 milliseconds Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 18 milliseconds Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 14 milliseconds Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 11 milliseconds Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HAYDN","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/291/128/stream","trackType":""} Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: CURRENT POSITION 0 Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState stateService play Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState currentStatus play Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: Received an update from plugin. extracting info from payload Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HAYDN","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/291/128/stream","trackType":""} Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: CURRENT POSITION 0 Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState stateService play Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState currentStatus play Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: Received an update from plugin. extracting info from payload Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HAYDN","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/291/128/stream","trackType":""} Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: CURRENT POSITION 0 Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState stateService play Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState currentStatus play Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: Received an update from plugin. extracting info from payload Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ------------------------------ 333ms Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ------------------------------ 331ms Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ------------------------------ 328ms Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:24 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:24 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 260 milliseconds Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 260 milliseconds Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 259 milliseconds Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 260 milliseconds Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HAYDN","artist":null,"album":null,"uri":"https://streams.calmradio.com/api/291/128/stream","trackType":""} Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: CURRENT POSITION 0 Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState stateService play Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState currentStatus play Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: Received an update from plugin. extracting info from payload Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Franz Joseph Haydn (Composer), Kodaly Quartet (Artist) - Haydn: Complete String Quartets CD6 - Haydn: String Quartet No. 12 in C major, Op. 9/1, H. 3/19: 3. Adagio","artist":"CALMRADIO.COM - Haydn","album":null,"uri":"https://streams.calmradio.com/api/291/128/stream","trackType":""} Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: CURRENT POSITION 0 Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState stateService play Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState currentStatus play Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: Received an update from plugin. extracting info from payload Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Franz Joseph Haydn (Composer), Kodaly Quartet (Artist) - Haydn: Complete String Quartets CD6 - Haydn: String Quartet No. 12 in C major, Op. 9/1, H. 3/19: 3. Adagio","artist":"CALMRADIO.COM - Haydn","album":null,"uri":"https://streams.calmradio.com/api/291/128/stream","trackType":""} Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: CURRENT POSITION 0 Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState stateService play Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState currentStatus play Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: Received an update from plugin. extracting info from payload Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Franz Joseph Haydn (Composer), Kodaly Quartet (Artist) - Haydn: Complete String Quartets CD6 - Haydn: String Quartet No. 12 in C major, Op. 9/1, H. 3/19: 3. Adagio","artist":"CALMRADIO.COM - Haydn","album":null,"uri":"https://streams.calmradio.com/api/291/128/stream","trackType":""} Nov 01 00:21:24 smsl-n100-repacked volumio[964]: verbose: CURRENT POSITION 0 Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState stateService play Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState currentStatus play Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: Received an update from plugin. extracting info from payload Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:24 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ------------------------------ 695ms Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ------------------------------ 671ms Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ------------------------------ 668ms Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ------------------------------ 666ms Nov 01 00:21:25 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:25 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:25 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:25 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:25 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:25 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:25 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:25 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:25 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:25 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:25 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:25 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:25 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:25 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:25 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:25 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:25 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:25 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:25 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:25 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ------------------------------ 669ms Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 663 milliseconds Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ------------------------------ 59ms Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 53 milliseconds Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ------------------------------ 51ms Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 45 milliseconds Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ------------------------------ 44ms Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 40 milliseconds Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 12 milliseconds Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 11 milliseconds Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1356,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Franz Joseph Haydn (Composer), Kodaly Quartet (Artist) - Haydn: Complete String Quartets CD6 - Haydn: String Quartet No. 12 in C major, Op. 9/1, H. 3/19: 3. Adagio","artist":"CALMRADIO.COM - Haydn","album":null,"uri":"https://streams.calmradio.com/api/291/128/stream","trackType":""} Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: CURRENT POSITION 0 Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState stateService play Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState currentStatus play Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: Received an update from plugin. extracting info from payload Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1985,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Franz Joseph Haydn (Composer), Kodaly Quartet (Artist) - Haydn: Complete String Quartets CD6 - Haydn: String Quartet No. 12 in C major, Op. 9/1, H. 3/19: 3. Adagio","artist":"CALMRADIO.COM - Haydn","album":null,"uri":"https://streams.calmradio.com/api/291/128/stream","trackType":""} Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: CURRENT POSITION 0 Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState stateService play Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState currentStatus play Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: Received an update from plugin. extracting info from payload Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1985,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Franz Joseph Haydn (Composer), Kodaly Quartet (Artist) - Haydn: Complete String Quartets CD6 - Haydn: String Quartet No. 12 in C major, Op. 9/1, H. 3/19: 3. Adagio","artist":"CALMRADIO.COM - Haydn","album":null,"uri":"https://streams.calmradio.com/api/291/128/stream","trackType":""} Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: CURRENT POSITION 0 Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState stateService play Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState currentStatus play Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: Received an update from plugin. extracting info from payload Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1985,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Franz Joseph Haydn (Composer), Kodaly Quartet (Artist) - Haydn: Complete String Quartets CD6 - Haydn: String Quartet No. 12 in C major, Op. 9/1, H. 3/19: 3. Adagio","artist":"CALMRADIO.COM - Haydn","album":null,"uri":"https://streams.calmradio.com/api/291/128/stream","trackType":""} Nov 01 00:21:25 smsl-n100-repacked volumio[964]: verbose: CURRENT POSITION 0 Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState stateService play Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState currentStatus play Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: Received an update from plugin. extracting info from payload Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ------------------------------ 1082ms Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ------------------------------ 482ms Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ------------------------------ 476ms Nov 01 00:21:25 smsl-n100-repacked volumio[964]: info: ------------------------------ 471ms Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:26 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:26 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:26 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:26 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:26 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined Nov 01 00:21:26 smsl-n100-repacked volumio[964]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: error Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: error Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: error Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: error Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: error Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: error Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: error Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: error Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: error Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: error Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: error Nov 01 00:21:26 smsl-n100-repacked volumio[964]: error: error Nov 01 00:21:27 smsl-n100-repacked volumio[964]: error: error Nov 01 00:21:27 smsl-n100-repacked volumio[964]: error: error Nov 01 00:21:27 smsl-n100-repacked volumio[964]: info: Executing endpoint metavolumio Nov 01 00:21:27 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 00:21:27 smsl-n100-repacked volumio[964]: info: Executing endpoint metavolumio Nov 01 00:21:27 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 00:21:27 smsl-n100-repacked volumio[964]: error: error Nov 01 00:21:27 smsl-n100-repacked volumio[964]: error: error Nov 01 00:21:27 smsl-n100-repacked volumio[964]: error: error Nov 01 00:21:27 smsl-n100-repacked volumio[964]: error: error Nov 01 00:21:31 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume51 Nov 01 00:21:31 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:31 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:31 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:31 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:31 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:31 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Nov 01 00:21:31 smsl-n100-repacked volumio[964]: SPOTIFY: SPOTIFY VOLUME 100 Nov 01 00:21:31 smsl-n100-repacked volumio[964]: SPOTIFY: VOLUMIO VOLUME 51 Nov 01 00:21:31 smsl-n100-repacked volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 01 00:21:31 smsl-n100-repacked volumio[964]: info: Setting Spotify Volume from Volumio: 51 Nov 01 00:21:31 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:32 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume52 Nov 01 00:21:32 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:32 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:32 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:32 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:32 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:32 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Nov 01 00:21:32 smsl-n100-repacked volumio[964]: SPOTIFY: SPOTIFY VOLUME 51 Nov 01 00:21:32 smsl-n100-repacked volumio[964]: SPOTIFY: VOLUMIO VOLUME 52 Nov 01 00:21:32 smsl-n100-repacked volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 00:21:32 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:32 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume53 Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:33 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Nov 01 00:21:33 smsl-n100-repacked volumio[964]: SPOTIFY: SPOTIFY VOLUME 51 Nov 01 00:21:33 smsl-n100-repacked volumio[964]: SPOTIFY: VOLUMIO VOLUME 53 Nov 01 00:21:33 smsl-n100-repacked volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: Setting Spotify Volume from Volumio: 53 Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume54 Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:33 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Nov 01 00:21:33 smsl-n100-repacked volumio[964]: SPOTIFY: SPOTIFY VOLUME 53 Nov 01 00:21:33 smsl-n100-repacked volumio[964]: SPOTIFY: VOLUMIO VOLUME 54 Nov 01 00:21:33 smsl-n100-repacked volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume55 Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:33 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Nov 01 00:21:33 smsl-n100-repacked volumio[964]: SPOTIFY: SPOTIFY VOLUME 53 Nov 01 00:21:33 smsl-n100-repacked volumio[964]: SPOTIFY: VOLUMIO VOLUME 55 Nov 01 00:21:33 smsl-n100-repacked volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: Setting Spotify Volume from Volumio: 55 Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume56 Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume57 Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume58 Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:33 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:34 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Nov 01 00:21:34 smsl-n100-repacked volumio[964]: SPOTIFY: SPOTIFY VOLUME 55 Nov 01 00:21:34 smsl-n100-repacked volumio[964]: SPOTIFY: VOLUMIO VOLUME 56 Nov 01 00:21:34 smsl-n100-repacked volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 00:21:34 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Nov 01 00:21:34 smsl-n100-repacked volumio[964]: SPOTIFY: SPOTIFY VOLUME 55 Nov 01 00:21:34 smsl-n100-repacked volumio[964]: SPOTIFY: VOLUMIO VOLUME 58 Nov 01 00:21:34 smsl-n100-repacked volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: Setting Spotify Volume from Volumio: 58 Nov 01 00:21:34 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume57 Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume58 Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume59 Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume60 Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume61 Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume59 Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume60 Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume61 Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume62 Nov 01 00:21:34 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume63 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume64 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume65 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume66 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume67 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume68 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: SPOTIFY VOLUME 58 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: VOLUMIO VOLUME 60 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: Setting Spotify Volume from Volumio: 60 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: SPOTIFY VOLUME 60 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: VOLUMIO VOLUME 68 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: Setting Spotify Volume from Volumio: 68 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:35 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:37 smsl-n100-repacked volumio[964]: SPOTIFY: SETTING SPOTIFY VOLUME 68 Nov 01 00:21:37 smsl-n100-repacked volumio[964]: info: Sending Spotify command with payload to local API: /player/volume Nov 01 00:21:42 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 00:21:42 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 00:21:42 smsl-n100-repacked volumio[964]: info: Discovery: Getting this device information Nov 01 00:21:42 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioGetState Nov 01 00:21:42 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 00:21:47 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioGetState Nov 01 00:21:49 smsl-n100-repacked volumio[964]: info: Executing endpoint metavolumio Nov 01 00:21:49 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 00:21:49 smsl-n100-repacked volumio[964]: info: Executing endpoint metavolumio Nov 01 00:21:49 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 00:21:56 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Nov 01 00:21:56 smsl-n100-repacked volumio[964]: info: [1761945716469] [80s80s] handleBrowseUri curUri: 80s80s Nov 01 00:21:56 smsl-n100-repacked volumio[964]: info: Preload queue cleared Nov 01 00:21:57 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Nov 01 00:21:57 smsl-n100-repacked volumio[964]: info: [1761945717841] [80s80s] handleBrowseUri curUri: 80s80s/eighties Nov 01 00:21:57 smsl-n100-repacked volumio[964]: info: [1761945717843] [80s80s] getRadioContent url: eighties Nov 01 00:21:57 smsl-n100-repacked volumio[964]: info: Preload queue cleared Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: Preload queue cleared Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreStateMachine::ClearQueue Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreStateMachine::stop Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreStateMachine::stPlaybackTimer Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreStateMachine::updateTrackBlock Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrackBlock Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreStateMachine::serviceStop Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::serviceStop Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: [1761945719830] ControllerCalmRadio::stop Nov 01 00:21:59 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CorePlayQueue::clearPlayQueue Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CorePlayQueue::saveQueue Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushQueue Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreStateMachine::addQueueItems Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CorePlayQueue::addQueueItems Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: Preload queue cleared Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: Adding Item to queue: webeighties/5 Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: Exploding uri webeighties/5 in service 80s80s Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: [1761945719847] [80s80s] explodeUri: webeighties/5 Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushQueue Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CorePlayQueue::saveQueue Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreStateMachine::updateTrackBlock Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrackBlock Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPlay Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreStateMachine::play index 0 Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreStateMachine::stop Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreStateMachine::play index undefined Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreStateMachine::startPlaybackTimer Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioGetVisibleSources Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: [1761945719918] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/italohits/mp3-192/volumio Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: [1761945719920] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=283&count=2 Nov 01 00:21:59 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:59 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: sendMpdCommand stop took 146 milliseconds Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:59 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:59 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:59 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:59 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:59 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: Nov 01 00:21:59 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:21:59 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:21:59 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 19 milliseconds Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 15 milliseconds Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 12 milliseconds Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 8 milliseconds Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 16 milliseconds Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 15 milliseconds Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 15 milliseconds Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: ------------------------------ 213ms Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: ------------------------------ 210ms Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: ------------------------------ 208ms Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: ------------------------------ 205ms Nov 01 00:22:00 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:00 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined Nov 01 00:22:00 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 01 00:22:00 smsl-n100-repacked volumio[964]: error: Serial API: Failed to decode command: 100, message: undefined Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 01 00:22:00 smsl-n100-repacked volumio[964]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gfGpRshes9XiG5x9onz6iocMXC72.f7a0fd279ef1bfe9409709f3e4a5b6a9.state.mute' Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: [1761945720532] [80s80s] received new event containing 2 songs. Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume0 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: VolumeController::SetAlsaVolume0 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: sendMpdCommand stop took 248 milliseconds Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand clear Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:00 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: sendMpdCommand clear took 72 milliseconds Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand consume 1 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:00 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:00 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:00 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:22:00 smsl-n100-repacked volumio[964]: error: updateQueue error: null Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:00 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: options Nov 01 00:22:00 smsl-n100-repacked volumio[964]: error: updateQueue error: null Nov 01 00:22:00 smsl-n100-repacked volumio[964]: error: updateQueue error: null Nov 01 00:22:00 smsl-n100-repacked volumio[964]: error: updateQueue error: null Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: ------------------------------ 25ms Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: sendMpdCommand consume 1 took 21 milliseconds Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: ------------------------------ 20ms Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: ------------------------------ 18ms Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: ------------------------------ 15ms Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: [1761945720888] [80s80s] adding url: http://streams.80s80s.de/italohits/mp3-192/volumio Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/italohits/mp3-192/volumio" Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:00 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: options Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:00 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: options Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:00 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: options Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:00 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 24 milliseconds Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: sendMpdCommand add "http://streams.80s80s.de/italohits/mp3-192/volumio" took 20 milliseconds Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 15 milliseconds Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 12 milliseconds Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 9 milliseconds Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand play Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:00 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: ------------------------------ 166ms Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: ------------------------------ 220ms Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: ------------------------------ 218ms Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: ------------------------------ 215ms Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:01 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:01 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:01 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: ------------------------------ 227ms Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: sendMpdCommand play took 207 milliseconds Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: ------------------------------ 17ms Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: ------------------------------ 14ms Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: ------------------------------ 11ms Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: [1761945721139] [80s80s] Pushing the next song state: Mike Mareen - Love Spy Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:01 smsl-n100-repacked volumio[964]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music123/v4/17/7d/24/177d245d-3899-1431-6d1d-ddfd3e613744/source/600x600bb.jpg","name":"Mike Mareen - Love Spy","title":"Love Spy","artist":"80s80s Italo Disco","album":"","streaming":true,"disableUiControls":true,"duration":"253","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Nov 01 00:22:01 smsl-n100-repacked volumio[964]: verbose: CURRENT POSITION 0 Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState stateService play Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState currentStatus stop Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: [1761945721150] [80s80s] PlayNextTrack API delay: 30 Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: [1761945721191] [80s80s] Setting timer to: 253000 milliseconds. Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:01 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 1 Nov 01 00:22:12 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 00:22:12 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 00:22:12 smsl-n100-repacked volumio[964]: info: Discovery: Getting this device information Nov 01 00:22:12 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioGetState Nov 01 00:22:12 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:12 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 00:22:16 smsl-n100-repacked volumio[964]: error: error Nov 01 00:22:16 smsl-n100-repacked volumio[964]: error: error Nov 01 00:22:16 smsl-n100-repacked volumio[964]: error: error Nov 01 00:22:16 smsl-n100-repacked volumio[964]: error: error Nov 01 00:22:16 smsl-n100-repacked volumio[964]: error: error Nov 01 00:22:16 smsl-n100-repacked volumio[964]: error: error Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: Preload queue cleared Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreStateMachine::ClearQueue Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreStateMachine::stop Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreStateMachine::stPlaybackTimer Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreStateMachine::updateTrackBlock Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrackBlock Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreStateMachine::serviceStop Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::serviceStop Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: ControllerMpd::stop Nov 01 00:22:16 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CorePlayQueue::clearPlayQueue Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CorePlayQueue::saveQueue Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushQueue Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreStateMachine::addQueueItems Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CorePlayQueue::addQueueItems Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: Preload queue cleared Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: Adding Item to queue: webeighties/0 Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: Exploding uri webeighties/0 in service 80s80s Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: [1761945736887] [80s80s] explodeUri: webeighties/0 Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushQueue Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CorePlayQueue::saveQueue Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreStateMachine::updateTrackBlock Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrackBlock Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPlay Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreStateMachine::play index 0 Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreStateMachine::stop Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreStateMachine::play index undefined Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreStateMachine::startPlaybackTimer Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioGetVisibleSources Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: [1761945736959] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/web/mp3-192/volumio Nov 01 00:22:16 smsl-n100-repacked volumio[964]: info: [1761945736960] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=2 Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:17 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: sendMpdCommand stop took 177 milliseconds Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music123/v4/17/7d/24/177d245d-3899-1431-6d1d-ddfd3e613744/source/600x600bb.jpg","name":"Mike Mareen - Love Spy","title":"Love Spy","artist":"80s80s Italo Disco","album":"","streaming":true,"disableUiControls":true,"duration":"253","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: CURRENT POSITION 0 Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState stateService stop Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState currentStatus stop Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: No code Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:17 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:17 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:17 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces state update: player Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: ControllerMpd::getState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand status Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 267 milliseconds Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 132 milliseconds Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 129 milliseconds Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: sendMpdCommand status took 125 milliseconds Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 00:22:17 smsl-n100-repacked volumio[964]: error: error Nov 01 00:22:17 smsl-n100-repacked volumio[964]: error: error Nov 01 00:22:17 smsl-n100-repacked volumio[964]: error: error Nov 01 00:22:17 smsl-n100-repacked volumio[964]: error: error Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 87 milliseconds Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 86 milliseconds Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 85 milliseconds Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: sendMpdCommand playlistinfo took 84 milliseconds Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::parseTrackInfo Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: ControllerMpd::pushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreStateMachine::pushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioPushState Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output update for this device Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: MRS: Pushing multiroomSync output Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: ------------------------------ 516ms Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: ------------------------------ 380ms Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: ------------------------------ 377ms Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: ------------------------------ 373ms Nov 01 00:22:17 smsl-n100-repacked volumio[964]: error: error Nov 01 00:22:17 smsl-n100-repacked volumio[964]: error: error Nov 01 00:22:17 smsl-n100-repacked volumio[964]: error: error Nov 01 00:22:17 smsl-n100-repacked volumio[964]: error: error Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Updating RAAT Signal Path Nov 01 00:22:17 smsl-n100-repacked volumio[964]: error: error Nov 01 00:22:17 smsl-n100-repacked volumio[964]: error: error Nov 01 00:22:17 smsl-n100-repacked volumio[964]: error: error Nov 01 00:22:17 smsl-n100-repacked volumio[964]: error: error Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: [1761945737921] [80s80s] received new event containing 2 songs. Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: sendMpdCommand stop took 7 milliseconds Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand clear Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:17 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: sendMpdCommand clear took 6 milliseconds Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand consume 1 Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:17 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:17 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:17 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:22:17 smsl-n100-repacked volumio[964]: error: updateQueue error: null Nov 01 00:22:17 smsl-n100-repacked volumio[964]: error: updateQueue error: null Nov 01 00:22:17 smsl-n100-repacked volumio[964]: error: updateQueue error: null Nov 01 00:22:17 smsl-n100-repacked volumio[964]: error: updateQueue error: null Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: ------------------------------ 24ms Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: sendMpdCommand consume 1 took 19 milliseconds Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: ------------------------------ 18ms Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: ------------------------------ 15ms Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: ------------------------------ 12ms Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: [1761945737964] [80s80s] adding url: http://streams.80s80s.de/web/mp3-192/volumio Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:17 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" took 9 milliseconds Nov 01 00:22:17 smsl-n100-repacked volumio[964]: verbose: ControllerMpd::sendMpdCommand play Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:17 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:17 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Nov 01 00:22:17 smsl-n100-repacked volumio[964]: ---------------------------- MPD announces system playlist update Nov 01 00:22:17 smsl-n100-repacked volumio[964]: info: Ignoring MPD Status Update Nov 01 00:22:18 smsl-n100-repacked volumio[964]: info: ------------------------------ 30ms Nov 01 00:22:18 smsl-n100-repacked volumio[964]: info: sendMpdCommand play took 15 milliseconds Nov 01 00:22:18 smsl-n100-repacked volumio[964]: info: ------------------------------ 14ms Nov 01 00:22:18 smsl-n100-repacked volumio[964]: info: ------------------------------ 11ms Nov 01 00:22:18 smsl-n100-repacked volumio[964]: info: ------------------------------ 8ms Nov 01 00:22:18 smsl-n100-repacked volumio[964]: info: [1761945738006] [80s80s] Pushing the next song state: Johnny Hates Jazz - I Don't Want to Be a Hero Nov 01 00:22:18 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:18 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::servicePushState Nov 01 00:22:18 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:18 smsl-n100-repacked volumio[964]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music114/v4/1f/e9/f1/1fe9f141-378f-ee04-df3d-d2034539b227/source/600x600bb.jpg","name":"Johnny Hates Jazz - I Don't Want to Be a Hero","title":"I Don't Want to Be a Hero","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"196","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Nov 01 00:22:18 smsl-n100-repacked volumio[964]: verbose: CURRENT POSITION 0 Nov 01 00:22:18 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState stateService play Nov 01 00:22:18 smsl-n100-repacked volumio[964]: info: CoreStateMachine::syncState currentStatus stop Nov 01 00:22:18 smsl-n100-repacked volumio[964]: info: [1761945738013] [80s80s] PlayNextTrack API delay: 30 Nov 01 00:22:18 smsl-n100-repacked volumio[964]: info: [1761945738020] [80s80s] Setting timer to: 196000 milliseconds. Nov 01 00:22:18 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:18 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 1 Nov 01 00:22:28 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 01 00:22:28 smsl-n100-repacked volumio[964]: info: CURURI: music-library Nov 01 00:22:28 smsl-n100-repacked volumio[964]: error: Failed LSINFO: null Nov 01 00:22:28 smsl-n100-repacked volumio[964]: info: Preload queue cleared Nov 01 00:22:33 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 01 00:22:33 smsl-n100-repacked volumio[964]: info: Preload queue cleared Nov 01 00:22:37 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 01 00:22:37 smsl-n100-repacked volumio[964]: info: Preload queue cleared Nov 01 00:22:39 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 01 00:22:39 smsl-n100-repacked volumio[964]: info: Preload queue cleared Nov 01 00:22:42 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 01 00:22:42 smsl-n100-repacked nmbd[684]: [2025/11/01 00:22:42.690995, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 01 00:22:42 smsl-n100-repacked nmbd[684]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.135 for name WORKGROUP<1d>. Nov 01 00:22:42 smsl-n100-repacked nmbd[684]: This response was from IP 192.168.0.105, reporting an IP address of 192.168.0.105. Nov 01 00:22:42 smsl-n100-repacked nmbd[684]: [2025/11/01 00:22:42.691759, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 01 00:22:42 smsl-n100-repacked nmbd[684]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.0.135 for name WORKGROUP<1d>. Nov 01 00:22:42 smsl-n100-repacked nmbd[684]: This response was from IP 192.168.0.105, reporting an IP address of 192.168.0.105. Nov 01 00:22:42 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 00:22:42 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 00:22:42 smsl-n100-repacked volumio[964]: info: Discovery: Getting this device information Nov 01 00:22:42 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::volumioGetState Nov 01 00:22:42 smsl-n100-repacked volumio[964]: info: CorePlayQueue::getTrack 0 Nov 01 00:22:42 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 00:22:43 smsl-n100-repacked volumio[964]: info: Preload queue cleared Nov 01 00:22:45 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 01 00:22:45 smsl-n100-repacked volumio[964]: info: Preload queue cleared Nov 01 00:22:47 smsl-n100-repacked volumio[964]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 01 00:22:47 smsl-n100-repacked volumio[964]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 00:22:47 smsl-n100-repacked volumio[964]: TypeError: Cannot read property 'length' of undefined Nov 01 00:22:47 smsl-n100-repacked volumio[964]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Nov 01 00:22:47 smsl-n100-repacked volumio[964]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Nov 01 00:22:47 smsl-n100-repacked volumio[964]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Nov 01 00:22:47 smsl-n100-repacked volumio[964]: at Parser.emit (events.js:400:28) Nov 01 00:22:47 smsl-n100-repacked volumio[964]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Nov 01 00:22:47 smsl-n100-repacked volumio[964]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Nov 01 00:22:47 smsl-n100-repacked volumio[964]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Nov 01 00:22:47 smsl-n100-repacked volumio[964]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Nov 01 00:22:47 smsl-n100-repacked volumio[964]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Nov 01 00:22:47 smsl-n100-repacked volumio[964]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Nov 01 00:22:47 smsl-n100-repacked volumio[964]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Nov 01 00:22:47 smsl-n100-repacked volumio[964]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Nov 01 00:22:47 smsl-n100-repacked volumio[964]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Nov 01 00:22:47 smsl-n100-repacked volumio[964]: at IncomingMessage.emit (events.js:412:35) Nov 01 00:22:47 smsl-n100-repacked volumio[964]: at endReadableNT (internal/streams/readable.js:1333:12) Nov 01 00:22:47 smsl-n100-repacked volumio[964]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Nov 01 00:22:47 smsl-n100-repacked volumio[964]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 00:22:48 smsl-n100-repacked sudo[5184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 00:21 Nov 01 00:22:48 smsl-n100-repacked sudo[5184]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="95d9088e5dcb3a55aabfe7f209b9ae00946f3ec5" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="30d63a8c53d2826bd7f262e22b4e4511a4cc9d01" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 25 Aug 2025 05:09:33 PM CEST" VOLUMIO_VERSION="3.831" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="4dc74030ed7c55500148df2cfe657e4a"