Oct 03 20:47:01 motivo volumio[1223]: info: CoreStateMachine::pushState Oct 03 20:47:01 motivo volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 20:47:01 motivo volumio[1223]: info: CoreCommandRouter::volumioPushState Oct 03 20:47:01 motivo volumio[1223]: info: Signalling Playback active due to playback status change Oct 03 20:47:02 motivo volumio[1223]: info: CoreStateMachine::pushState Oct 03 20:47:02 motivo volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 20:47:02 motivo volumio[1223]: info: CoreCommandRouter::volumioPushState Oct 03 20:47:02 motivo volumio[1223]: info: Signalling Playback active due to playback status change Oct 03 20:47:02 motivo volumio[1223]: info: CoreStateMachine::pushState Oct 03 20:47:02 motivo volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 20:47:02 motivo volumio[1223]: info: CoreCommandRouter::volumioPushState Oct 03 20:47:02 motivo volumio[1223]: info: Signalling Playback active due to playback status change Oct 03 20:47:03 motivo volumio[1223]: info: CoreStateMachine::pushState Oct 03 20:47:03 motivo volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 20:47:03 motivo volumio[1223]: info: CoreCommandRouter::volumioPushState Oct 03 20:47:03 motivo volumio[1223]: info: Signalling Playback active due to playback status change Oct 03 20:47:03 motivo volumio[1223]: info: CoreStateMachine::pushState Oct 03 20:47:03 motivo volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 20:47:03 motivo volumio[1223]: info: CoreCommandRouter::volumioPushState Oct 03 20:47:03 motivo volumio[1223]: info: Signalling Playback active due to playback status change Oct 03 20:47:12 motivo volumio[1223]: info: CoreCommandRouter::volumioGetState Oct 03 20:47:14 motivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 28. Oct 03 20:47:14 motivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 20:47:14 motivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 20:47:14 motivo upmpdcli[2731]: Could not open config: /tmp/upmpdcli.conf Oct 03 20:47:14 motivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 03 20:47:14 motivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 03 20:47:14 motivo volumio[1223]: info: Executing endpoint metavolumio Oct 03 20:47:14 motivo volumio[1223]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 20:47:14 motivo volumio[1223]: info: Executing endpoint metavolumio Oct 03 20:47:14 motivo volumio[1223]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 20:47:14 motivo volumio[1223]: info: Executing endpoint metavolumio Oct 03 20:47:14 motivo volumio[1223]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 20:47:16 motivo volumio[1223]: info: CoreCommandRouter::volumioPause Oct 03 20:47:16 motivo volumio[1223]: info: CoreStateMachine::pause Oct 03 20:47:16 motivo volumio[1223]: info: CoreStateMachine::stPlaybackTimer Oct 03 20:47:16 motivo volumio[1223]: info: CoreStateMachine::servicePause Oct 03 20:47:16 motivo volumio[1223]: info: CorePlayQueue::getTrack 0 Oct 03 20:47:16 motivo volumio[1223]: info: CoreCommandRouter::servicePause Oct 03 20:47:16 motivo volumio[1223]: info: [1791031636969] ControllerTidal::pause Oct 03 20:47:16 motivo volumio[1223]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 03 20:47:16 motivo volumio[1223]: info: ControllerMpd::pause Oct 03 20:47:16 motivo volumio[1223]: verbose: ControllerMpd::sendMpdCommand pause Oct 03 20:47:16 motivo volumio[1223]: info: Oct 03 20:47:16 motivo volumio[1223]: ---------------------------- MPD announces state update: player Oct 03 20:47:16 motivo volumio[1223]: info: sendMpdCommand pause took 2 milliseconds Oct 03 20:47:16 motivo volumio[1223]: info: ControllerMpd::getState Oct 03 20:47:16 motivo volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Oct 03 20:47:16 motivo volumio[1223]: info: Oct 03 20:47:16 motivo volumio[1223]: ---------------------------- MPD announces state update: player Oct 03 20:47:16 motivo volumio[1223]: info: ControllerMpd::getState Oct 03 20:47:16 motivo volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Oct 03 20:47:16 motivo volumio[1223]: info: Oct 03 20:47:16 motivo volumio[1223]: ---------------------------- MPD announces state update: player Oct 03 20:47:16 motivo volumio[1223]: info: ControllerMpd::getState Oct 03 20:47:16 motivo volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Oct 03 20:47:16 motivo volumio[1223]: info: sendMpdCommand status took 5 milliseconds Oct 03 20:47:16 motivo volumio[1223]: info: sendMpdCommand status took 4 milliseconds Oct 03 20:47:16 motivo volumio[1223]: verbose: ControllerMpd::parseState Oct 03 20:47:16 motivo volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 20:47:16 motivo volumio[1223]: verbose: ControllerMpd::parseState Oct 03 20:47:16 motivo volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 20:47:16 motivo volumio[1223]: info: sendMpdCommand status took 5 milliseconds Oct 03 20:47:16 motivo volumio[1223]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 03 20:47:16 motivo volumio[1223]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 03 20:47:16 motivo volumio[1223]: verbose: ControllerMpd::parseState Oct 03 20:47:16 motivo volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 20:47:16 motivo volumio[1223]: verbose: ControllerMpd::parseTrackInfo Oct 03 20:47:16 motivo volumio[1223]: verbose: ControllerMpd::parseTrackInfo Oct 03 20:47:16 motivo volumio[1223]: info: ControllerMpd::pushState Oct 03 20:47:16 motivo volumio[1223]: info: CoreCommandRouter::servicePushState Oct 03 20:47:16 motivo volumio[1223]: info: CorePlayQueue::getTrack 0 Oct 03 20:47:16 motivo volumio[1223]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14497,"duration":151,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2742 Kbps","isStreaming":false,"title":"0.flac?token=1791035206~YjU4MWJkMDJmMzNkNjc5YzJlN2Y4NWQ2NmY3NzFiZmRiYzQyMDQwYw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRlMmNkZGFhZDUxNmQ2NzFiNTAwN2Y0ZTVjYzExMzVmZS5tcDQ/0.flac?token=1791035206~YjU4MWJkMDJmMzNkNjc5YzJlN2Y4NWQ2NmY3NzFiZmRiYzQyMDQwYw==","trackType":"tidal"} Oct 03 20:47:16 motivo volumio[1223]: verbose: CURRENT POSITION 0 Oct 03 20:47:16 motivo volumio[1223]: info: CoreStateMachine::syncState stateService pause Oct 03 20:47:16 motivo volumio[1223]: info: CoreStateMachine::syncState currentStatus pause Oct 03 20:47:16 motivo volumio[1223]: info: CoreStateMachine::pushState Oct 03 20:47:16 motivo volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 20:47:16 motivo volumio[1223]: info: CoreCommandRouter::volumioPushState Oct 03 20:47:16 motivo volumio[1223]: info: CoreStateMachine::stPlaybackTimer Oct 03 20:47:16 motivo volumio[1223]: info: ControllerMpd::pushState Oct 03 20:47:16 motivo volumio[1223]: info: CoreCommandRouter::servicePushState Oct 03 20:47:16 motivo volumio[1223]: info: CorePlayQueue::getTrack 0 Oct 03 20:47:16 motivo volumio[1223]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14497,"duration":151,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2742 Kbps","isStreaming":false,"title":"0.flac?token=1791035206~YjU4MWJkMDJmMzNkNjc5YzJlN2Y4NWQ2NmY3NzFiZmRiYzQyMDQwYw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRlMmNkZGFhZDUxNmQ2NzFiNTAwN2Y0ZTVjYzExMzVmZS5tcDQ/0.flac?token=1791035206~YjU4MWJkMDJmMzNkNjc5YzJlN2Y4NWQ2NmY3NzFiZmRiYzQyMDQwYw==","trackType":"tidal"} Oct 03 20:47:16 motivo volumio[1223]: verbose: CURRENT POSITION 0 Oct 03 20:47:16 motivo volumio[1223]: info: CoreStateMachine::syncState stateService pause Oct 03 20:47:16 motivo volumio[1223]: info: CoreStateMachine::syncState currentStatus pause Oct 03 20:47:16 motivo volumio[1223]: info: CoreStateMachine::pushState Oct 03 20:47:16 motivo volumio[1223]: info: CoreCommandRouter::volumioPushState Oct 03 20:47:17 motivo volumio[1223]: info: CoreStateMachine::stPlaybackTimer Oct 03 20:47:17 motivo volumio[1223]: info: ------------------------------ 43ms Oct 03 20:47:17 motivo volumio[1223]: info: ------------------------------ 42ms Oct 03 20:47:17 motivo volumio[1223]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 03 20:47:17 motivo volumio[1223]: verbose: ControllerMpd::parseTrackInfo Oct 03 20:47:17 motivo volumio[1223]: info: ControllerMpd::pushState Oct 03 20:47:17 motivo volumio[1223]: info: CoreCommandRouter::servicePushState Oct 03 20:47:17 motivo volumio[1223]: info: CorePlayQueue::getTrack 0 Oct 03 20:47:17 motivo volumio[1223]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14497,"duration":151,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2742 Kbps","isStreaming":false,"title":"0.flac?token=1791035206~YjU4MWJkMDJmMzNkNjc5YzJlN2Y4NWQ2NmY3NzFiZmRiYzQyMDQwYw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRlMmNkZGFhZDUxNmQ2NzFiNTAwN2Y0ZTVjYzExMzVmZS5tcDQ/0.flac?token=1791035206~YjU4MWJkMDJmMzNkNjc5YzJlN2Y4NWQ2NmY3NzFiZmRiYzQyMDQwYw==","trackType":"tidal"} Oct 03 20:47:17 motivo volumio[1223]: verbose: CURRENT POSITION 0 Oct 03 20:47:17 motivo volumio[1223]: info: CoreStateMachine::syncState stateService pause Oct 03 20:47:17 motivo volumio[1223]: info: CoreStateMachine::syncState currentStatus pause Oct 03 20:47:17 motivo volumio[1223]: info: CoreStateMachine::pushState Oct 03 20:47:17 motivo volumio[1223]: info: CoreCommandRouter::volumioPushState Oct 03 20:47:17 motivo volumio[1223]: info: CoreStateMachine::stPlaybackTimer Oct 03 20:47:17 motivo volumio[1223]: info: ------------------------------ 63ms Oct 03 20:47:17 motivo volumio[1223]: info: MCU Signalled Playback Inactive Oct 03 20:47:27 motivo volumio[1223]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 03 20:47:27 motivo volumio[1223]: info: Retrying Login Due to Network Error Oct 03 20:47:29 motivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 29. Oct 03 20:47:29 motivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 20:47:29 motivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 20:47:29 motivo upmpdcli[2749]: Could not open config: /tmp/upmpdcli.conf Oct 03 20:47:29 motivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 03 20:47:29 motivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 03 20:47:37 motivo volumio[1223]: info: MyVolumio login type: Token Oct 03 20:47:39 motivo volumio[1223]: (node:1223) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Oct 03 20:47:44 motivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 30. Oct 03 20:47:44 motivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 20:47:44 motivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 20:47:44 motivo upmpdcli[2779]: Could not open config: /tmp/upmpdcli.conf Oct 03 20:47:44 motivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 03 20:47:44 motivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 03 20:47:59 motivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 31. Oct 03 20:47:59 motivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 20:48:00 motivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 20:48:00 motivo upmpdcli[2795]: Could not open config: /tmp/upmpdcli.conf Oct 03 20:48:00 motivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 03 20:48:00 motivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 03 20:48:07 motivo volumio[1223]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 03 20:48:07 motivo volumio[1223]: info: Retrying Login Due to Network Error Oct 03 20:48:15 motivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 32. Oct 03 20:48:15 motivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 20:48:15 motivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 20:48:15 motivo upmpdcli[2826]: Could not open config: /tmp/upmpdcli.conf Oct 03 20:48:15 motivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 03 20:48:15 motivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 03 20:48:17 motivo volumio[1223]: info: MyVolumio login type: Token Oct 03 20:48:19 motivo volumio[1223]: (node:1223) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Oct 03 20:48:30 motivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 33. Oct 03 20:48:30 motivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 20:48:30 motivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 20:48:30 motivo upmpdcli[2841]: Could not open config: /tmp/upmpdcli.conf Oct 03 20:48:30 motivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 03 20:48:30 motivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 03 20:48:45 motivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 34. Oct 03 20:48:45 motivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 20:48:45 motivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 03 20:48:45 motivo upmpdcli[2871]: Could not open config: /tmp/upmpdcli.conf Oct 03 20:48:45 motivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 03 20:48:45 motivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 03 20:48:47 motivo volumio[1223]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 03 20:48:47 motivo volumio[1223]: info: Retrying Login Due to Network Error Oct 03 20:48:57 motivo volumio[1223]: info: MyVolumio login type: Token Oct 03 20:48:58 motivo volumio[1223]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 20:48:58 motivo volumio[1223]: Error: connect ETIMEDOUT 59.24.3.173:443 Oct 03 20:48:58 motivo volumio[1223]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 03 20:48:58 motivo volumio[1223]: errno: -110, Oct 03 20:48:58 motivo volumio[1223]: code: 'ETIMEDOUT', Oct 03 20:48:58 motivo volumio[1223]: syscall: 'connect', Oct 03 20:48:58 motivo volumio[1223]: address: '59.24.3.173', Oct 03 20:48:58 motivo volumio[1223]: port: 443 Oct 03 20:48:58 motivo volumio[1223]: } Oct 03 20:48:58 motivo volumio[1223]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 20:48:58 motivo sudo[2901]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-03 20:47' Oct 03 20:48:58 motivo sudo[2901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="9ccd1247f8cab3c5d64c23a96d243f6bfa34d032" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="e94c5b27ab2607d3918cd6a4973c240c1b2ce37e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 16 00:11:09 UTC 2026" VOLUMIO_VERSION="4.156" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="67ebfb15187d4de64266b21b13da5443"