-- Logs begin at Sat 2025-09-13 08:15:57 EDT, end at Sat 2025-09-13 08:34:04 EDT. -- Sep 13 08:33:01 volumio-mac volumio[1419]: info: CoreCommandRouter::volumioGetState Sep 13 08:33:11 volumio-mac volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 08:33:11 volumio-mac volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 08:33:11 volumio-mac volumio[1419]: info: Discovery: Getting this device information Sep 13 08:33:11 volumio-mac volumio[1419]: info: CoreCommandRouter::volumioGetState Sep 13 08:33:11 volumio-mac volumio[1419]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 08:33:16 volumio-mac volumio[1419]: info: CoreCommandRouter::volumioGetState Sep 13 08:33:31 volumio-mac volumio[1419]: info: CoreCommandRouter::volumioGetState Sep 13 08:33:41 volumio-mac volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 08:33:41 volumio-mac volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 08:33:41 volumio-mac volumio[1419]: info: Discovery: Getting this device information Sep 13 08:33:41 volumio-mac volumio[1419]: info: CoreCommandRouter::volumioGetState Sep 13 08:33:41 volumio-mac volumio[1419]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 08:33:46 volumio-mac volumio[1419]: info: CoreCommandRouter::volumioGetState Sep 13 08:33:48 volumio-mac volumio[1419]: info: MRS: Found cast device: Google-Cast-Group-37d932be06f4422c9696d4e7c9994f3b Sep 13 08:33:48 volumio-mac volumio[1419]: info: Adding audio output: Sep 13 08:33:48 volumio-mac volumio[1419]: info: MRS: Found cast device: Google-Cast-Group-37d932be06f4422c9696d4e7c9994f3b Sep 13 08:33:50 volumio-mac volumio[1419]: verbose: New Socket.io Connection to 192.168.50.21 from 192.168.50.93 UA: Mozilla/5.0 (Linux; Android 16; Pixel 9 Pro Build/BP3A.250905.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.160 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Sep 13 08:33:50 volumio-mac volumio[1419]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 13 08:33:50 volumio-mac volumio[1419]: info: CoreCommandRouter::volumioGetVisibleSources Sep 13 08:33:50 volumio-mac volumio[1419]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 13 08:33:50 volumio-mac volumio[1419]: info: CoreCommandRouter::volumioGetState Sep 13 08:33:50 volumio-mac volumio[1419]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 13 08:33:50 volumio-mac volumio[1419]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 13 08:33:50 volumio-mac volumio[1419]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 13 08:33:50 volumio-mac volumio[1419]: info: Received Get System Info Sep 13 08:33:50 volumio-mac volumio[1419]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 08:33:50 volumio-mac volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 08:33:50 volumio-mac volumio[1419]: info: Discovery: Getting this device information Sep 13 08:33:50 volumio-mac volumio[1419]: info: CoreCommandRouter::volumioGetState Sep 13 08:33:50 volumio-mac volumio[1419]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 08:33:50 volumio-mac volumio[1419]: info: CoreCommandRouter::volumioGetState Sep 13 08:33:50 volumio-mac volumio[1419]: info: Listing playlists Sep 13 08:33:50 volumio-mac volumio[1419]: info: CoreCommandRouter::volumioGetQueue Sep 13 08:33:50 volumio-mac volumio[1419]: info: CoreStateMachine::getQueue Sep 13 08:33:50 volumio-mac volumio[1419]: info: CorePlayQueue::getQueue Sep 13 08:33:50 volumio-mac volumio[1419]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreCommandRouter::volumioStop Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreStateMachine::stop Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreStateMachine::stPlaybackTimer Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreStateMachine::updateTrackBlock Sep 13 08:34:01 volumio-mac volumio[1419]: info: CorePlayQueue::getTrackBlock Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreStateMachine::pushState Sep 13 08:34:01 volumio-mac volumio[1419]: info: CorePlayQueue::getTrack 0 Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreCommandRouter::volumioPushState Sep 13 08:34:01 volumio-mac volumio[1419]: info: MRS: Pushing multiroomSync output update for this device Sep 13 08:34:01 volumio-mac volumio[1419]: info: MRS: Pushing multiroomSync output Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreStateMachine::serviceStop Sep 13 08:34:01 volumio-mac volumio[1419]: info: CorePlayQueue::getTrack 0 Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreCommandRouter::serviceStop Sep 13 08:34:01 volumio-mac volumio[1419]: info: [1757766841030] ControllerWebradio::stop Sep 13 08:34:01 volumio-mac volumio[1419]: verbose: ControllerMpd::sendMpdCommand stop Sep 13 08:34:01 volumio-mac volumio[1419]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 08:34:01 volumio-mac volumio[1419]: info: Sep 13 08:34:01 volumio-mac volumio[1419]: ---------------------------- MPD announces state update: player Sep 13 08:34:01 volumio-mac volumio[1419]: info: sendMpdCommand stop took 14 milliseconds Sep 13 08:34:01 volumio-mac volumio[1419]: info: ControllerMpd::getState Sep 13 08:34:01 volumio-mac volumio[1419]: verbose: ControllerMpd::sendMpdCommand status Sep 13 08:34:01 volumio-mac volumio[1419]: info: sendMpdCommand status took 1 milliseconds Sep 13 08:34:01 volumio-mac volumio[1419]: verbose: ControllerMpd::parseState Sep 13 08:34:01 volumio-mac volumio[1419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 08:34:01 volumio-mac volumio[1419]: info: Sep 13 08:34:01 volumio-mac volumio[1419]: ---------------------------- MPD announces state update: player Sep 13 08:34:01 volumio-mac volumio[1419]: info: ControllerMpd::getState Sep 13 08:34:01 volumio-mac volumio[1419]: verbose: ControllerMpd::sendMpdCommand status Sep 13 08:34:01 volumio-mac volumio[1419]: info: Sep 13 08:34:01 volumio-mac volumio[1419]: ---------------------------- MPD announces state update: player Sep 13 08:34:01 volumio-mac volumio[1419]: info: ControllerMpd::getState Sep 13 08:34:01 volumio-mac volumio[1419]: verbose: ControllerMpd::sendMpdCommand status Sep 13 08:34:01 volumio-mac volumio[1419]: info: Sep 13 08:34:01 volumio-mac volumio[1419]: ---------------------------- MPD announces state update: player Sep 13 08:34:01 volumio-mac volumio[1419]: info: ControllerMpd::getState Sep 13 08:34:01 volumio-mac volumio[1419]: verbose: ControllerMpd::sendMpdCommand status Sep 13 08:34:01 volumio-mac volumio[1419]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 13 08:34:01 volumio-mac volumio[1419]: info: sendMpdCommand status took 3 milliseconds Sep 13 08:34:01 volumio-mac volumio[1419]: info: sendMpdCommand status took 2 milliseconds Sep 13 08:34:01 volumio-mac volumio[1419]: info: sendMpdCommand status took 2 milliseconds Sep 13 08:34:01 volumio-mac volumio[1419]: verbose: ControllerMpd::parseTrackInfo Sep 13 08:34:01 volumio-mac volumio[1419]: verbose: ControllerMpd::parseState Sep 13 08:34:01 volumio-mac volumio[1419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 08:34:01 volumio-mac volumio[1419]: verbose: ControllerMpd::parseState Sep 13 08:34:01 volumio-mac volumio[1419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 08:34:01 volumio-mac volumio[1419]: verbose: ControllerMpd::parseState Sep 13 08:34:01 volumio-mac volumio[1419]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 13 08:34:01 volumio-mac volumio[1419]: info: ControllerMpd::pushState Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreCommandRouter::servicePushState Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreStateMachine::pushState Sep 13 08:34:01 volumio-mac volumio[1419]: info: CorePlayQueue::getTrack 0 Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreCommandRouter::volumioPushState Sep 13 08:34:01 volumio-mac volumio[1419]: info: MRS: Pushing multiroomSync output update for this device Sep 13 08:34:01 volumio-mac volumio[1419]: info: MRS: Pushing multiroomSync output Sep 13 08:34:01 volumio-mac volumio[1419]: info: CorePlayQueue::getTrack 0 Sep 13 08:34:01 volumio-mac volumio[1419]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 13 08:34:01 volumio-mac volumio[1419]: info: ------------------------------ 13ms Sep 13 08:34:01 volumio-mac volumio[1419]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 13 08:34:01 volumio-mac volumio[1419]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 13 08:34:01 volumio-mac volumio[1419]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 13 08:34:01 volumio-mac volumio[1419]: verbose: ControllerMpd::parseTrackInfo Sep 13 08:34:01 volumio-mac volumio[1419]: verbose: ControllerMpd::parseTrackInfo Sep 13 08:34:01 volumio-mac volumio[1419]: verbose: ControllerMpd::parseTrackInfo Sep 13 08:34:01 volumio-mac volumio[1419]: info: ControllerMpd::pushState Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreCommandRouter::servicePushState Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreStateMachine::pushState Sep 13 08:34:01 volumio-mac volumio[1419]: info: CorePlayQueue::getTrack 0 Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreCommandRouter::volumioPushState Sep 13 08:34:01 volumio-mac volumio[1419]: info: MRS: Pushing multiroomSync output update for this device Sep 13 08:34:01 volumio-mac volumio[1419]: info: MRS: Pushing multiroomSync output Sep 13 08:34:01 volumio-mac volumio[1419]: info: CorePlayQueue::getTrack 0 Sep 13 08:34:01 volumio-mac volumio[1419]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 13 08:34:01 volumio-mac volumio[1419]: info: ControllerMpd::pushState Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreCommandRouter::servicePushState Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreStateMachine::pushState Sep 13 08:34:01 volumio-mac volumio[1419]: info: CorePlayQueue::getTrack 0 Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreCommandRouter::volumioPushState Sep 13 08:34:01 volumio-mac volumio[1419]: info: MRS: Pushing multiroomSync output update for this device Sep 13 08:34:01 volumio-mac volumio[1419]: info: MRS: Pushing multiroomSync output Sep 13 08:34:01 volumio-mac volumio[1419]: info: CorePlayQueue::getTrack 0 Sep 13 08:34:01 volumio-mac volumio[1419]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 13 08:34:01 volumio-mac volumio[1419]: info: ControllerMpd::pushState Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreCommandRouter::servicePushState Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreStateMachine::pushState Sep 13 08:34:01 volumio-mac volumio[1419]: info: CorePlayQueue::getTrack 0 Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreCommandRouter::volumioPushState Sep 13 08:34:01 volumio-mac volumio[1419]: info: MRS: Pushing multiroomSync output update for this device Sep 13 08:34:01 volumio-mac volumio[1419]: info: MRS: Pushing multiroomSync output Sep 13 08:34:01 volumio-mac volumio[1419]: info: CorePlayQueue::getTrack 0 Sep 13 08:34:01 volumio-mac volumio[1419]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 13 08:34:01 volumio-mac volumio[1419]: info: ------------------------------ 22ms Sep 13 08:34:01 volumio-mac volumio[1419]: info: ------------------------------ 22ms Sep 13 08:34:01 volumio-mac volumio[1419]: info: ------------------------------ 22ms Sep 13 08:34:01 volumio-mac volumio[1419]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 08:34:01 volumio-mac volumio[1419]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 08:34:01 volumio-mac volumio[1419]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 08:34:01 volumio-mac volumio[1419]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 13 08:34:01 volumio-mac volumio[1419]: info: CoreCommandRouter::volumioGetState Sep 13 08:34:01 volumio-mac volumio[1419]: info: CorePlayQueue::getTrack 0 Sep 13 08:34:03 volumio-mac volumio[1419]: info: Executing endpoint metavolumio Sep 13 08:34:03 volumio-mac volumio[1419]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 13 08:34:03 volumio-mac volumio[1419]: error: Failed request for metavolumio API Sep 13 08:34:04 volumio-mac volumio[1419]: info: MRS: STARTING volumioStreaming Sep 13 08:34:04 volumio-mac sudo[5673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream714.ts /tmp/hls/stream715.ts /tmp/hls/stream716.ts /tmp/hls/stream717.ts /tmp/hls/stream718.ts Sep 13 08:34:04 volumio-mac sudo[5673]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 08:34:04 volumio-mac sudo[5673]: pam_unix(sudo:session): session closed for user root Sep 13 08:34:04 volumio-mac volumio[1419]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 13 08:34:04 volumio-mac volumio[1419]: TypeError: Cannot read property 'send' of null Sep 13 08:34:04 volumio-mac volumio[1419]: at Client.send (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:128:11) Sep 13 08:34:04 volumio-mac volumio[1419]: at Channel.send (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:34:12) Sep 13 08:34:04 volumio-mac volumio[1419]: at fn.Controller.send (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:28:16) Sep 13 08:34:04 volumio-mac volumio[1419]: at fn.RequestResponseController.request (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:34:8) Sep 13 08:34:04 volumio-mac volumio[1419]: at fn.MediaController.sessionRequest (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/media.js:91:8) Sep 13 08:34:04 volumio-mac volumio[1419]: at fn.MediaController.stop (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/media.js:107:8) Sep 13 08:34:04 volumio-mac volumio[1419]: at DefaultMediaReceiver.stop (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/default-media-receiver.js:42:19) Sep 13 08:34:04 volumio-mac volumio[1419]: at Timeout._onTimeout (/myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:5038) Sep 13 08:34:04 volumio-mac volumio[1419]: at listOnTimeout (internal/timers.js:557:17) Sep 13 08:34:04 volumio-mac volumio[1419]: at processTimers (internal/timers.js:500:7) Sep 13 08:34:04 volumio-mac volumio[1419]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 13 08:34:04 volumio-mac sudo[5676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Sep 13 08:34:04 volumio-mac sudo[5676]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 08:34:04 volumio-mac systemd[1]: Stopping VolumioStreamingService... Sep 13 08:34:04 volumio-mac systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Sep 13 08:34:04 volumio-mac systemd[1]: volumioStreaming.service: Succeeded. Sep 13 08:34:04 volumio-mac systemd[1]: Stopped VolumioStreamingService. Sep 13 08:34:04 volumio-mac systemd[1]: Started VolumioStreamingService. Sep 13 08:34:04 volumio-mac sudo[5676]: pam_unix(sudo:session): session closed for user root Sep 13 08:34:04 volumio-mac sudo[5687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 13 08:34:04 volumio-mac sudo[5687]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 08:34:04 volumio-mac sudo[5687]: pam_unix(sudo:session): session closed for user root Sep 13 08:34:04 volumio-mac sudo[5692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-13 08:33 Sep 13 08:34:04 volumio-mac sudo[5692]: 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="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Aug 2025 06:37:19 PM CEST" VOLUMIO_VERSION="3.839" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="3616d4b5f30ddd7d34bf1bdb3bca86e3"