-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Sat 2024-10-05 16:40:26 EDT. -- Oct 05 16:39:10 myvolumio volumio[837]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 16:39:10 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 05 16:39:10 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 05 16:39:10 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 05 16:39:10 myvolumio volumio[837]: info: Received Get System Version Oct 05 16:39:10 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 05 16:39:23 myvolumio volumio[837]: info: CoreCommandRouter::volumioGetState Oct 05 16:39:23 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:25 myvolumio volumio[837]: info: Executing endpoint metavolumio Oct 05 16:39:25 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 05 16:39:31 myvolumio volumio[837]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 16:39:31 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 05 16:39:31 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 16:39:31 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 16:39:31 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 16:39:31 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 16:39:31 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 16:39:31 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 16:39:31 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 16:39:31 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 16:39:31 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 16:39:31 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 05 16:39:31 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 05 16:39:31 myvolumio volumio[837]: info: CoreCommandRouter::volumioGetState Oct 05 16:39:31 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:31 myvolumio volumio[837]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 05 16:39:31 myvolumio volumio[837]: info: touch_display: Setting screensaver timeout to 600 seconds. Oct 05 16:39:39 myvolumio volumio[837]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 05 16:39:39 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 05 16:39:39 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 16:39:39 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 16:39:40 myvolumio volumio[837]: info: Setting mixer SMSL USB AUDIO for card SMSL USB AUDIO Oct 05 16:39:40 myvolumio sudo[1995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 16:39:40 myvolumio sudo[1995]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 16:39:40 myvolumio systemd[1]: Stopping Volumio Tidal Connect Service... Oct 05 16:39:40 myvolumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 05 16:39:40 myvolumio systemd[1]: vtcs.service: Succeeded. Oct 05 16:39:40 myvolumio systemd[1]: Stopped Volumio Tidal Connect Service. Oct 05 16:39:40 myvolumio sudo[1995]: pam_unix(sudo:session): session closed for user root Oct 05 16:39:40 myvolumio volumio[837]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 05 16:39:40 myvolumio volumio[837]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: SMSL USB AUDIO Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 05 16:39:40 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 05 16:39:40 myvolumio volumio[837]: info: Disabling external Volume Control Oct 05 16:39:40 myvolumio volumio[837]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 16:39:40 myvolumio volumio[837]: info: Preparing to generate the ALSA configuration file Oct 05 16:39:40 myvolumio volumio[837]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 05 16:39:40 myvolumio volumio[837]: info: Reading ALSA contributions from plugins. Oct 05 16:39:40 myvolumio volumio[837]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 16:39:40 myvolumio sudo[2002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 16:39:40 myvolumio sudo[2002]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 16:39:40 myvolumio sudo[2002]: pam_unix(sudo:session): session closed for user root Oct 05 16:39:40 myvolumio kernel: rpi_firmware_get_throttled: 8 callbacks suppressed Oct 05 16:39:40 myvolumio kernel: Under-voltage detected! (0x00050005) Oct 05 16:39:40 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 05 16:39:40 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 16:39:40 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 16:39:40 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 16:39:40 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 16:39:40 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 16:39:40 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 16:39:40 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 16:39:40 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 16:39:40 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 16:39:40 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 05 16:39:40 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 05 16:39:40 myvolumio volumio[837]: info: VolumeController:: Volume=20 Mute =false Oct 05 16:39:40 myvolumio volumio[837]: info: CoreStateMachine::pushState Oct 05 16:39:40 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:40 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 16:39:40 myvolumio volumio[837]: info: CoreCommandRouter::volumioPushState Oct 05 16:39:40 myvolumio volumio[837]: info: MRS: Pushing multiroomSync output update for this device Oct 05 16:39:40 myvolumio volumio[837]: info: MRS: Pushing multiroomSync output Oct 05 16:39:41 myvolumio volumio[837]: info: Asound.conf file written Oct 05 16:39:41 myvolumio sudo[2012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 05 16:39:41 myvolumio sudo[2012]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 16:39:41 myvolumio sudo[2012]: pam_unix(sudo:session): session closed for user root Oct 05 16:39:41 myvolumio volumio[837]: No state is present for card AUDIO Oct 05 16:39:41 myvolumio volumio[837]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:85dd" "" "" Oct 05 16:39:41 myvolumio volumio[837]: Hardware is initialized using a generic method Oct 05 16:39:41 myvolumio volumio[837]: No state is present for card AUDIO Oct 05 16:39:41 myvolumio volumio[837]: info: Output device has changed, restarting MPD Oct 05 16:39:41 myvolumio sudo[2020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 16:39:41 myvolumio sudo[2020]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 16:39:41 myvolumio volumio[837]: info: Output device has changed, restarting Shairport Sync Oct 05 16:39:41 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:41 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 16:39:41 myvolumio sudo[2020]: pam_unix(sudo:session): session closed for user root Oct 05 16:39:41 myvolumio sudo[2023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 16:39:41 myvolumio sudo[2023]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 16:39:41 myvolumio systemd[1]: Stopping Music Player Daemon... Oct 05 16:39:41 myvolumio systemd[1]: mpd.service: Succeeded. Oct 05 16:39:41 myvolumio systemd[1]: Stopped Music Player Daemon. Oct 05 16:39:41 myvolumio volumio[837]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 05 16:39:41 myvolumio volumio[837]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 05 16:39:41 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:41 myvolumio systemd[1]: Starting Music Player Daemon... Oct 05 16:39:41 myvolumio volumio[837]: info: Output device has changed, restarting MPD Oct 05 16:39:41 myvolumio sudo[2032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 16:39:41 myvolumio sudo[2032]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 16:39:42 myvolumio sudo[2035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 16:39:42 myvolumio sudo[2035]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 16:39:42 myvolumio volumio[837]: info: Output device has changed, restarting Shairport Sync Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 16:39:42 myvolumio sudo[2035]: pam_unix(sudo:session): session closed for user root Oct 05 16:39:42 myvolumio sudo[2032]: pam_unix(sudo:session): session closed for user root Oct 05 16:39:42 myvolumio sudo[2039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 16:39:42 myvolumio sudo[2039]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 16:39:42 myvolumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 05 16:39:42 myvolumio systemd[1]: mpd.service: Succeeded. Oct 05 16:39:42 myvolumio systemd[1]: Stopped Music Player Daemon. Oct 05 16:39:42 myvolumio systemd[1]: Starting Music Player Daemon... Oct 05 16:39:42 myvolumio volumio[837]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 05 16:39:42 myvolumio volumio[837]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:42 myvolumio sudo[2050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 16:39:42 myvolumio sudo[2050]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 16:39:42 myvolumio sudo[2054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 16:39:42 myvolumio sudo[2054]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 16:39:42 myvolumio sudo[2050]: pam_unix(sudo:session): session closed for user root Oct 05 16:39:42 myvolumio sudo[2054]: pam_unix(sudo:session): session closed for user root Oct 05 16:39:42 myvolumio volumio[837]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Oct 05 16:39:42 myvolumio volumio[837]: SPOTIFY: SPOTIFY VOLUME 100 Oct 05 16:39:42 myvolumio volumio[837]: SPOTIFY: VOLUMIO VOLUME 20 Oct 05 16:39:42 myvolumio volumio[837]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 05 16:39:42 myvolumio volumio[837]: info: Setting Spotify Volume from Volumio: 20 Oct 05 16:39:42 myvolumio sudo[2058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 16:39:42 myvolumio sudo[2058]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 16:39:42 myvolumio volumio[837]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 16:39:42 myvolumio volumio[837]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 16:39:42 myvolumio volumio[837]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 16:39:42 myvolumio sudo[2058]: pam_unix(sudo:session): session closed for user root Oct 05 16:39:42 myvolumio volumio[837]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 16:39:42 myvolumio volumio[837]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 16:39:42 myvolumio volumio[837]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 16:39:42 myvolumio volumio[837]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 16:39:42 myvolumio volumio[837]: info: MPD Permissions set Oct 05 16:39:42 myvolumio volumio[837]: info: MPD Permissions set Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:42 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:43 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 16:39:43 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:43 myvolumio volumio[837]: info: Starting Shairport Sync Oct 05 16:39:43 myvolumio volumio[837]: info: Starting Shairport Sync Oct 05 16:39:43 myvolumio sudo[2072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 05 16:39:43 myvolumio sudo[2072]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 16:39:43 myvolumio volumio[837]: info: TidalConnect service stoped! Oct 05 16:39:43 myvolumio sudo[2075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 05 16:39:43 myvolumio sudo[2075]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 16:39:43 myvolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 05 16:39:43 myvolumio systemd[1]: shairport-sync.service: Succeeded. Oct 05 16:39:43 myvolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 05 16:39:43 myvolumio volumio[837]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 16:39:43 myvolumio volumio[837]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 16:39:43 myvolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 05 16:39:43 myvolumio sudo[2072]: pam_unix(sudo:session): session closed for user root Oct 05 16:39:43 myvolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 05 16:39:43 myvolumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 05 16:39:43 myvolumio systemd[1]: shairport-sync.service: Succeeded. Oct 05 16:39:43 myvolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 05 16:39:43 myvolumio volumio[837]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 16:39:43 myvolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 05 16:39:43 myvolumio sudo[2075]: pam_unix(sudo:session): session closed for user root Oct 05 16:39:43 myvolumio volumio[837]: info: Shairport-Sync Started Oct 05 16:39:43 myvolumio volumio[837]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 16:39:43 myvolumio volumio[837]: info: Shairport-Sync Started Oct 05 16:39:43 myvolumio volumio[837]: info: TidalConnect service stoped! Oct 05 16:39:43 myvolumio volumio[837]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 05 16:39:43 myvolumio volumio[837]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 05 16:39:43 myvolumio sudo[2090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 05 16:39:43 myvolumio sudo[2090]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 16:39:44 myvolumio systemd[1]: Started Volumio Tidal Connect Service. Oct 05 16:39:44 myvolumio sudo[2090]: pam_unix(sudo:session): session closed for user root Oct 05 16:39:44 myvolumio volumio[837]: SPOTIFY: SETTING SPOTIFY VOLUME 20 Oct 05 16:39:44 myvolumio volumio[837]: info: Sending Spotify command with payload to local API: /player/volume Oct 05 16:39:44 myvolumio go-librespot[1364]: time="2024-10-05T16:39:44-04:00" level=debug msg="update volume to 13107/65535" Oct 05 16:39:44 myvolumio volumio[837]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 05 16:39:44 myvolumio volumio[837]: info: CoreStateMachine::getcurrentVolume Oct 05 16:39:44 myvolumio volumio[837]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 16:39:44 myvolumio go-librespot[1364]: time="2024-10-05T16:39:44-04:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 05 16:39:44 myvolumio go-librespot[1364]: time="2024-10-05T16:39:44-04:00" level=trace msg="emitting websocket event: volume" Oct 05 16:39:44 myvolumio volumio[837]: SPOTIFY: received: {"type":"volume","data":{"value":20,"max":100}} Oct 05 16:39:44 myvolumio volumio[837]: SPOTIFY: RECEIVED SPOTIFY VOLUME 20 Oct 05 16:39:44 myvolumio volumio[837]: info: Executing endpoint tc_getconfig Oct 05 16:39:44 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 05 16:39:44 myvolumio vtcs[2092]: STARTING TidalConnect services, version: 1.1.7.5 Oct 05 16:39:44 myvolumio vtcs[2092]: [2024-10-05 16:39:44.535] [tisoc] [error] [SpkconServer.cpp:173] socket bind error. fd=3 errorno=98 error=Address already in use Oct 05 16:39:44 myvolumio vtcs[2092]: [2024-10-05 16:39:44.536] [tisoc] [error] [SpkconServer.cpp:54] already connected state:1 Oct 05 16:39:44 myvolumio vtcs[2092]: [2024-10-05 16:39:44.536] [tisoc] [critical] [SpeakerCommunicatorImpl.cpp:78] [ASSERT] start(ret) Oct 05 16:39:44 myvolumio vtcs[2092]: STARTED TidalConnect services. Oct 05 16:39:44 myvolumio volumio[837]: info: Executing endpoint tc_connect Oct 05 16:39:44 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 05 16:39:44 myvolumio volumio[837]: info: Connecting to TidalConnect Oct 05 16:39:44 myvolumio volumio[837]: info: VolumeController:: Volume=20 Mute =false Oct 05 16:39:44 myvolumio volumio[837]: info: CoreStateMachine::pushState Oct 05 16:39:44 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:44 myvolumio volumio[837]: info: CoreCommandRouter::volumioPushState Oct 05 16:39:44 myvolumio volumio[837]: info: MRS: Pushing multiroomSync output update for this device Oct 05 16:39:44 myvolumio volumio[837]: info: MRS: Pushing multiroomSync output Oct 05 16:39:44 myvolumio volumio[837]: info: CoreStateMachine::updateTrackBlock Oct 05 16:39:44 myvolumio volumio[837]: info: CorePlayQueue::getTrackBlock Oct 05 16:39:44 myvolumio volumio[837]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 16:39:44 myvolumio mpd[2060]: Oct 05 16:39 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 05 16:39:44 myvolumio mpd[2060]: Oct 05 16:39 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 05 16:39:44 myvolumio mpd[2060]: Oct 05 16:39 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 05 16:39:44 myvolumio volumio[837]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Oct 05 16:39:44 myvolumio systemd[1]: Started Music Player Daemon. Oct 05 16:39:44 myvolumio sudo[2023]: pam_unix(sudo:session): session closed for user root Oct 05 16:39:44 myvolumio sudo[2039]: pam_unix(sudo:session): session closed for user root Oct 05 16:39:44 myvolumio volumio[837]: error: MPD error: The expression evaluated to a falsy value: Oct 05 16:39:44 myvolumio volumio[837]: assert.ok(self.idling) Oct 05 16:39:44 myvolumio volumio[837]: error: The expression evaluated to a falsy value: Oct 05 16:39:44 myvolumio volumio[837]: assert.ok(self.idling) Oct 05 16:39:44 myvolumio volumio[837]: info: VolumeController:: Volume=20 Mute =false Oct 05 16:39:44 myvolumio volumio[837]: info: CoreStateMachine::pushState Oct 05 16:39:44 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:44 myvolumio volumio[837]: info: CoreCommandRouter::volumioPushState Oct 05 16:39:44 myvolumio volumio[837]: info: MRS: Pushing multiroomSync output update for this device Oct 05 16:39:44 myvolumio volumio[837]: info: MRS: Pushing multiroomSync output Oct 05 16:39:45 myvolumio volumio[837]: error: updateQueue error: null Oct 05 16:39:45 myvolumio volumio[837]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Oct 05 16:39:45 myvolumio volumio[837]: info: TidalConnect service stoped! Oct 05 16:39:45 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 16:39:45 myvolumio volumio[837]: info: TidalConnect service stoped! Oct 05 16:39:45 myvolumio volumio[837]: info: TidalConnect service stoped! Oct 05 16:39:45 myvolumio volumio[837]: info: TidalConnect service stoped! Oct 05 16:39:45 myvolumio volumio[837]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 05 16:39:45 myvolumio volumio[837]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 05 16:39:46 myvolumio sudo[2119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 05 16:39:46 myvolumio sudo[2119]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 16:39:46 myvolumio volumio[837]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 05 16:39:46 myvolumio volumio[837]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 05 16:39:46 myvolumio sudo[2119]: pam_unix(sudo:session): session closed for user root Oct 05 16:39:46 myvolumio sudo[2127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 05 16:39:46 myvolumio sudo[2127]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 16:39:46 myvolumio sudo[2127]: pam_unix(sudo:session): session closed for user root Oct 05 16:39:47 myvolumio volumio[837]: info: TidalConnect service started! Oct 05 16:39:49 myvolumio volumio[837]: info: TidalConnect service started! Oct 05 16:39:49 myvolumio volumio[837]: info: TidalConnect service started! Oct 05 16:39:51 myvolumio volumio[837]: info: CoreCommandRouter::volumioGetState Oct 05 16:39:51 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:51 myvolumio volumio[837]: info: CoreCommandRouter::volumioPlay Oct 05 16:39:51 myvolumio volumio[837]: info: CoreStateMachine::play index undefined Oct 05 16:39:51 myvolumio volumio[837]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 05 16:39:51 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:51 myvolumio volumio[837]: info: CoreStateMachine::startPlaybackTimer Oct 05 16:39:51 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:51 myvolumio volumio[837]: info: CoreCommandRouter::volumioGetVisibleSources Oct 05 16:39:51 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 16:39:51 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 05 16:39:51 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand stop Oct 05 16:39:51 myvolumio volumio[837]: info: sendMpdCommand stop took 1 milliseconds Oct 05 16:39:51 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand clear Oct 05 16:39:51 myvolumio volumio[837]: info: Oct 05 16:39:51 myvolumio volumio[837]: ---------------------------- MPD announces system playlist update Oct 05 16:39:51 myvolumio volumio[837]: info: Ignoring MPD Status Update Oct 05 16:39:51 myvolumio volumio[837]: info: sendMpdCommand clear took 2 milliseconds Oct 05 16:39:51 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 05 16:39:51 myvolumio volumio[837]: info: Oct 05 16:39:51 myvolumio volumio[837]: ---------------------------- MPD announces system playlist update Oct 05 16:39:51 myvolumio volumio[837]: info: Ignoring MPD Status Update Oct 05 16:39:51 myvolumio volumio[837]: error: updateQueue error: null Oct 05 16:39:51 myvolumio volumio[837]: info: Oct 05 16:39:51 myvolumio volumio[837]: ---------------------------- MPD announces state update: options Oct 05 16:39:51 myvolumio volumio[837]: error: updateQueue error: null Oct 05 16:39:51 myvolumio volumio[837]: info: ------------------------------ 4ms Oct 05 16:39:51 myvolumio volumio[837]: info: sendMpdCommand consume 1 took 3 milliseconds Oct 05 16:39:51 myvolumio volumio[837]: info: ControllerMpd::getState Oct 05 16:39:51 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status Oct 05 16:39:51 myvolumio volumio[837]: info: ------------------------------ 5ms Oct 05 16:39:51 myvolumio volumio[837]: info: [1728160791945] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flac Oct 05 16:39:51 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" Oct 05 16:39:51 myvolumio volumio[837]: info: Oct 05 16:39:51 myvolumio volumio[837]: ---------------------------- MPD announces state update: options Oct 05 16:39:51 myvolumio volumio[837]: info: ControllerMpd::getState Oct 05 16:39:51 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status Oct 05 16:39:51 myvolumio volumio[837]: info: sendMpdCommand status took 11 milliseconds Oct 05 16:39:51 myvolumio volumio[837]: verbose: ControllerMpd::parseState Oct 05 16:39:51 myvolumio volumio[837]: info: ControllerMpd::pushState Oct 05 16:39:51 myvolumio volumio[837]: info: CoreCommandRouter::servicePushState Oct 05 16:39:51 myvolumio volumio[837]: info: CoreStateMachine::pushState Oct 05 16:39:51 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:51 myvolumio volumio[837]: info: CoreCommandRouter::volumioPushState Oct 05 16:39:51 myvolumio volumio[837]: info: MRS: Pushing multiroomSync output update for this device Oct 05 16:39:51 myvolumio volumio[837]: info: MRS: Pushing multiroomSync output Oct 05 16:39:51 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:51 myvolumio volumio[837]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 05 16:39:51 myvolumio volumio[837]: info: ------------------------------ 43ms Oct 05 16:39:51 myvolumio volumio[837]: info: Oct 05 16:39:51 myvolumio volumio[837]: ---------------------------- MPD announces system playlist update Oct 05 16:39:51 myvolumio volumio[837]: info: Ignoring MPD Status Update Oct 05 16:39:51 myvolumio volumio[837]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" took 40 milliseconds Oct 05 16:39:51 myvolumio volumio[837]: info: sendMpdCommand status took 40 milliseconds Oct 05 16:39:51 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand play Oct 05 16:39:51 myvolumio volumio[837]: verbose: ControllerMpd::parseState Oct 05 16:39:51 myvolumio volumio[837]: info: ControllerMpd::pushState Oct 05 16:39:51 myvolumio volumio[837]: info: CoreCommandRouter::servicePushState Oct 05 16:39:52 myvolumio volumio[837]: info: CoreStateMachine::pushState Oct 05 16:39:52 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:52 myvolumio volumio[837]: info: CoreCommandRouter::volumioPushState Oct 05 16:39:52 myvolumio volumio[837]: info: MRS: Pushing multiroomSync output update for this device Oct 05 16:39:52 myvolumio volumio[837]: info: MRS: Pushing multiroomSync output Oct 05 16:39:52 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:52 myvolumio volumio[837]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 05 16:39:52 myvolumio volumio[837]: info: ------------------------------ 89ms Oct 05 16:39:52 myvolumio volumio[837]: info: Oct 05 16:39:52 myvolumio volumio[837]: ---------------------------- MPD announces system playlist update Oct 05 16:39:52 myvolumio volumio[837]: info: Ignoring MPD Status Update Oct 05 16:39:52 myvolumio volumio[837]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Oct 05 16:39:52 myvolumio volumio[837]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Oct 05 16:39:52 myvolumio volumio[837]: info: ------------------------------ 134ms Oct 05 16:39:52 myvolumio volumio[837]: info: sendMpdCommand play took 122 milliseconds Oct 05 16:39:52 myvolumio volumio[837]: info: ------------------------------ 84ms Oct 05 16:39:52 myvolumio volumio[837]: info: [1728160792123] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Oct 05 16:39:52 myvolumio volumio[837]: info: [1728160792448] [RadioParadise] received new metadata: {"time":113,"artist":"London Grammar","title":"Nightcall","album":"If You Wait","year":"2013","cover":"https://img.radioparadise.com/covers/l/B00ICQDPYC.jpg","cover_med":"https://img.radioparadise.com/covers/m/B00ICQDPYC.jpg","cover_small":"https://img.radioparadise.com/covers/s/B00ICQDPYC.jpg"} Oct 05 16:39:52 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:52 myvolumio volumio[837]: info: CoreCommandRouter::servicePushState Oct 05 16:39:52 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:52 myvolumio volumio[837]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00ICQDPYC.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Nightcall","title":"Nightcall","artist":"London Grammar","album":"If You Wait","streaming":true,"disableUiControls":true,"duration":118,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Oct 05 16:39:52 myvolumio volumio[837]: verbose: CURRENT POSITION 0 Oct 05 16:39:52 myvolumio volumio[837]: info: CoreStateMachine::syncState stateService play Oct 05 16:39:52 myvolumio volumio[837]: info: CoreStateMachine::syncState currentStatus stop Oct 05 16:39:52 myvolumio volumio[837]: info: [1728160792459] [RadioParadise] setting new timer with duration of 118000 seconds. Oct 05 16:39:52 myvolumio volumio[837]: info: Oct 05 16:39:52 myvolumio volumio[837]: ---------------------------- MPD announces state update: player Oct 05 16:39:52 myvolumio volumio[837]: info: ControllerMpd::getState Oct 05 16:39:52 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status Oct 05 16:39:52 myvolumio volumio[837]: info: Oct 05 16:39:52 myvolumio volumio[837]: ---------------------------- MPD announces state update: player Oct 05 16:39:52 myvolumio volumio[837]: info: ControllerMpd::getState Oct 05 16:39:52 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status Oct 05 16:39:52 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:52 myvolumio volumio[837]: info: CorePlayQueue::getTrack 1 Oct 05 16:39:52 myvolumio volumio[837]: info: sendMpdCommand status took 248 milliseconds Oct 05 16:39:52 myvolumio volumio[837]: verbose: ControllerMpd::parseState Oct 05 16:39:52 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 16:39:52 myvolumio volumio[837]: info: Oct 05 16:39:52 myvolumio volumio[837]: ---------------------------- MPD announces state update: player Oct 05 16:39:52 myvolumio volumio[837]: info: ControllerMpd::getState Oct 05 16:39:52 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status Oct 05 16:39:52 myvolumio volumio[837]: info: sendMpdCommand status took 250 milliseconds Oct 05 16:39:52 myvolumio volumio[837]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 05 16:39:52 myvolumio volumio[837]: verbose: ControllerMpd::parseState Oct 05 16:39:52 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 16:39:52 myvolumio volumio[837]: verbose: ControllerMpd::parseTrackInfo Oct 05 16:39:52 myvolumio volumio[837]: info: ControllerMpd::pushState Oct 05 16:39:52 myvolumio volumio[837]: info: CoreCommandRouter::servicePushState Oct 05 16:39:52 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:52 myvolumio volumio[837]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 05 16:39:52 myvolumio volumio[837]: info: ------------------------------ 268ms Oct 05 16:39:52 myvolumio volumio[837]: info: Oct 05 16:39:52 myvolumio volumio[837]: ---------------------------- MPD announces state update: player Oct 05 16:39:52 myvolumio volumio[837]: info: ControllerMpd::getState Oct 05 16:39:52 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status Oct 05 16:39:52 myvolumio volumio[837]: info: sendMpdCommand status took 35 milliseconds Oct 05 16:39:52 myvolumio volumio[837]: info: sendMpdCommand playlistinfo took 32 milliseconds Oct 05 16:39:52 myvolumio volumio[837]: info: sendMpdCommand status took 4 milliseconds Oct 05 16:39:52 myvolumio volumio[837]: verbose: ControllerMpd::parseState Oct 05 16:39:52 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 16:39:52 myvolumio volumio[837]: verbose: ControllerMpd::parseTrackInfo Oct 05 16:39:52 myvolumio volumio[837]: verbose: ControllerMpd::parseState Oct 05 16:39:52 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 16:39:52 myvolumio volumio[837]: info: ControllerMpd::pushState Oct 05 16:39:52 myvolumio volumio[837]: info: CoreCommandRouter::servicePushState Oct 05 16:39:52 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:52 myvolumio volumio[837]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 05 16:39:52 myvolumio volumio[837]: info: ------------------------------ 300ms Oct 05 16:39:52 myvolumio volumio[837]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 05 16:39:52 myvolumio volumio[837]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 05 16:39:52 myvolumio volumio[837]: verbose: ControllerMpd::parseTrackInfo Oct 05 16:39:52 myvolumio volumio[837]: verbose: ControllerMpd::parseTrackInfo Oct 05 16:39:52 myvolumio volumio[837]: info: ControllerMpd::pushState Oct 05 16:39:53 myvolumio volumio[837]: info: CoreCommandRouter::servicePushState Oct 05 16:39:53 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:53 myvolumio volumio[837]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 05 16:39:53 myvolumio volumio[837]: info: ControllerMpd::pushState Oct 05 16:39:53 myvolumio volumio[837]: info: CoreCommandRouter::servicePushState Oct 05 16:39:53 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:53 myvolumio volumio[837]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 05 16:39:53 myvolumio volumio[837]: info: ------------------------------ 67ms Oct 05 16:39:53 myvolumio volumio[837]: info: ------------------------------ 53ms Oct 05 16:39:53 myvolumio volumio[837]: info: Oct 05 16:39:53 myvolumio volumio[837]: ---------------------------- MPD announces system playlist update Oct 05 16:39:53 myvolumio volumio[837]: info: Ignoring MPD Status Update Oct 05 16:39:53 myvolumio volumio[837]: info: Oct 05 16:39:53 myvolumio volumio[837]: ---------------------------- MPD announces state update: player Oct 05 16:39:53 myvolumio volumio[837]: info: ControllerMpd::getState Oct 05 16:39:53 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status Oct 05 16:39:53 myvolumio volumio[837]: info: Oct 05 16:39:53 myvolumio volumio[837]: ---------------------------- MPD announces system playlist update Oct 05 16:39:53 myvolumio volumio[837]: info: Ignoring MPD Status Update Oct 05 16:39:53 myvolumio volumio[837]: info: Oct 05 16:39:53 myvolumio volumio[837]: ---------------------------- MPD announces state update: player Oct 05 16:39:53 myvolumio volumio[837]: info: ControllerMpd::getState Oct 05 16:39:53 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand status Oct 05 16:39:53 myvolumio volumio[837]: info: ------------------------------ 15ms Oct 05 16:39:53 myvolumio volumio[837]: info: sendMpdCommand status took 12 milliseconds Oct 05 16:39:53 myvolumio volumio[837]: info: ------------------------------ 9ms Oct 05 16:39:53 myvolumio volumio[837]: info: sendMpdCommand status took 3 milliseconds Oct 05 16:39:53 myvolumio volumio[837]: verbose: ControllerMpd::parseState Oct 05 16:39:53 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 16:39:53 myvolumio volumio[837]: verbose: ControllerMpd::parseState Oct 05 16:39:53 myvolumio volumio[837]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 05 16:39:53 myvolumio volumio[837]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 05 16:39:53 myvolumio volumio[837]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 05 16:39:53 myvolumio volumio[837]: verbose: ControllerMpd::parseTrackInfo Oct 05 16:39:53 myvolumio volumio[837]: verbose: ControllerMpd::parseTrackInfo Oct 05 16:39:53 myvolumio volumio[837]: info: ControllerMpd::pushState Oct 05 16:39:53 myvolumio volumio[837]: info: CoreCommandRouter::servicePushState Oct 05 16:39:53 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:53 myvolumio volumio[837]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 05 16:39:53 myvolumio volumio[837]: info: ControllerMpd::pushState Oct 05 16:39:53 myvolumio volumio[837]: info: CoreCommandRouter::servicePushState Oct 05 16:39:53 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:39:53 myvolumio volumio[837]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 05 16:39:53 myvolumio volumio[837]: info: ------------------------------ 28ms Oct 05 16:39:53 myvolumio volumio[837]: info: ------------------------------ 19ms Oct 05 16:39:54 myvolumio volumio[837]: info: Executing endpoint metavolumio Oct 05 16:39:54 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 05 16:40:19 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 16:40:19 myvolumio volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 05 16:40:26 myvolumio volumio[837]: info: Executing Deactivate State Oct 05 16:40:26 myvolumio volumio[837]: info: CoreCommandRouter::volumioGetState Oct 05 16:40:26 myvolumio volumio[837]: info: CorePlayQueue::getTrack 0 Oct 05 16:40:26 myvolumio volumio[837]: info: Currently active service: radio_paradise Oct 05 16:40:26 myvolumio volumio[837]: info: Socket destroyed Oct 05 16:40:26 myvolumio volumio[837]: info: tidalbridge: service stopped Oct 05 16:40:26 myvolumio volumio[837]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 16:40:26 myvolumio volumio[837]: Error: This socket has been ended by the other party Oct 05 16:40:26 myvolumio volumio[837]: at Socket.writeAfterFIN [as write] (net.js:456:14) Oct 05 16:40:26 myvolumio volumio[837]: at TidalControlStream._write (/data/plugins/music_service/tidalbridge/TidalControlStream.js:206:20) Oct 05 16:40:26 myvolumio volumio[837]: at writeOrBuffer (internal/streams/writable.js:358:12) Oct 05 16:40:26 myvolumio volumio[837]: at TidalControlStream.Writable.write (internal/streams/writable.js:303:10) Oct 05 16:40:26 myvolumio volumio[837]: at TidalController._write (/data/plugins/music_service/tidalbridge/TidalController.js:478:25) Oct 05 16:40:26 myvolumio volumio[837]: at TidalController._cmdStopService (/data/plugins/music_service/tidalbridge/TidalController.js:354:10) Oct 05 16:40:26 myvolumio volumio[837]: at TidalController.stopService (/data/plugins/music_service/tidalbridge/TidalController.js:73:12) Oct 05 16:40:26 myvolumio volumio[837]: at TidalBridge.onStop (/data/plugins/music_service/tidalbridge/index.js:381:24) Oct 05 16:40:26 myvolumio volumio[837]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Oct 05 16:40:26 myvolumio volumio[837]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Oct 05 16:40:26 myvolumio volumio[837]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1530:29) Oct 05 16:40:26 myvolumio volumio[837]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1356:47) Oct 05 16:40:26 myvolumio volumio[837]: at Socket.emit (events.js:315:20) Oct 05 16:40:26 myvolumio volumio[837]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 05 16:40:26 myvolumio volumio[837]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 05 16:40:26 myvolumio volumio[837]: code: 'EPIPE' Oct 05 16:40:26 myvolumio volumio[837]: } Oct 05 16:40:26 myvolumio volumio[837]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 16:40:26 myvolumio kernel: Under-voltage detected! (0x00050005) Oct 05 16:40:26 myvolumio sudo[2146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 16:39 Oct 05 16:40:26 myvolumio sudo[2146]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cd8cf34b47ce852e70b71e6f7b6e0265f7fdb695" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="8f88153f9a18f312d56050e6613b45a852ac819a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 27 Sep 2023 11:35:49 AM CEST" VOLUMIO_VERSION="3.546" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6823c0d83061ff736b7e0dd4481bc496"