-- Logs begin at Sun 2024-10-06 05:17:16 CEST, end at Tue 2024-10-08 15:02:33 CEST. -- Oct 08 15:01:09 volumiomac volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 08 15:01:09 volumiomac volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 08 15:01:09 volumiomac volumio[1184]: info: Discovery: Getting this device information Oct 08 15:01:09 volumiomac volumio[1184]: info: CoreCommandRouter::volumioGetState Oct 08 15:01:09 volumiomac volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 08 15:01:39 volumiomac volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 08 15:01:39 volumiomac volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 08 15:01:39 volumiomac volumio[1184]: info: Discovery: Getting this device information Oct 08 15:01:39 volumiomac volumio[1184]: info: CoreCommandRouter::volumioGetState Oct 08 15:01:39 volumiomac volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 08 15:01:48 volumiomac vtcs[2002]: [2024-10-08 15:01:48.536] [tisoc] [warning] [PlaybackControllerImpl.cpp:913] Not send NotifyMediaChanged(media_info is null) Oct 08 15:02:09 volumiomac volumio[1184]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 08 15:02:09 volumiomac volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 08 15:02:09 volumiomac volumio[1184]: info: Discovery: Getting this device information Oct 08 15:02:09 volumiomac volumio[1184]: info: CoreCommandRouter::volumioGetState Oct 08 15:02:09 volumiomac volumio[1184]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 08 15:02:16 volumiomac volumio[1184]: info: PlaybackInactive Oct 08 15:02:16 volumiomac volumio[1184]: info: Device Session is_active: true Oct 08 15:02:16 volumiomac volumio[1184]: info: DeviceInactive Oct 08 15:02:16 volumiomac volumio[1184]: info: Relinquishing Volumio State Oct 08 15:02:16 volumiomac volumio[1184]: verbose: UNSET VOLATILE: Service: bluetooth Oct 08 15:02:16 volumiomac volumio[1184]: info: Bluetooth Detach Command Oct 08 15:02:16 volumiomac vtcs[2002]: [2024-10-08 15:02:16] [info] asio async_shutdown error: asio.misc:2 (End of file) Oct 08 15:02:16 volumiomac volumio[1184]: info: Bluetooth Audio successfully Disabled Oct 08 15:02:16 volumiomac volumio[1184]: info: Bluetooth Successfully Detached Oct 08 15:02:19 volumiomac volumio[1184]: info: Tunnel connection is inactive, restarting it Oct 08 15:02:19 volumiomac volumio[1184]: info: Starting Tunnel 1 Oct 08 15:02:19 volumiomac volumio[1184]: info: Starting Tunnel Connection Checker Oct 08 15:02:20 volumiomac sudo[10621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 08 15:02:20 volumiomac sudo[10621]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 08 15:02:20 volumiomac autossh[10445]: received signal to exit (15) Oct 08 15:02:20 volumiomac systemd[1]: Stopping MyVolumio SSH Tunnel... Oct 08 15:02:20 volumiomac systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Oct 08 15:02:20 volumiomac systemd[1]: sshtunnel.service: Succeeded. Oct 08 15:02:20 volumiomac systemd[1]: Stopped MyVolumio SSH Tunnel. Oct 08 15:02:20 volumiomac volumio[1184]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 08 15:02:20 volumiomac volumio[1184]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 08 15:02:20 volumiomac systemd[1]: Started MyVolumio SSH Tunnel. Oct 08 15:02:20 volumiomac sudo[10621]: pam_unix(sudo:session): session closed for user root Oct 08 15:02:20 volumiomac volumio[1184]: info: Remote SSH Started Oct 08 15:02:20 volumiomac autossh[10624]: port set to 0, monitoring disabled Oct 08 15:02:20 volumiomac autossh[10624]: starting ssh (count 1) Oct 08 15:02:20 volumiomac autossh[10624]: ssh child pid is 10627 Oct 08 15:02:20 volumiomac volumio[1184]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 08 15:02:20 volumiomac volumio[1184]: info: CoreCommandRouter::volumioGetState Oct 08 15:02:20 volumiomac volumio[1184]: info: CorePlayQueue::getTrack 0 Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::volumioGetState Oct 08 15:02:30 volumiomac volumio[1184]: info: CorePlayQueue::getTrack 0 Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::volumioGetState Oct 08 15:02:30 volumiomac volumio[1184]: info: CorePlayQueue::getTrack 0 Oct 08 15:02:30 volumiomac volumio[1184]: info: Currently active: tidal Oct 08 15:02:30 volumiomac volumio[1184]: info: Stopping currently active service Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::volumioStop Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreStateMachine::stop Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreStateMachine::stPlaybackTimer Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreStateMachine::updateTrackBlock Oct 08 15:02:30 volumiomac volumio[1184]: info: CorePlayQueue::getTrackBlock Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreStateMachine::pushState Oct 08 15:02:30 volumiomac volumio[1184]: info: CorePlayQueue::getTrack 0 Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::volumioPushState Oct 08 15:02:30 volumiomac volumio[1184]: info: MRS: Pushing multiroomSync output update for this device Oct 08 15:02:30 volumiomac volumio[1184]: info: MRS: Pushing multiroomSync output Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreStateMachine::serviceStop Oct 08 15:02:30 volumiomac volumio[1184]: info: CorePlayQueue::getTrack 0 Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::serviceStop Oct 08 15:02:30 volumiomac volumio[1184]: info: [1728392550322] ControllerTidal::stop Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 08 15:02:30 volumiomac volumio[1184]: info: ControllerMpd::stop Oct 08 15:02:30 volumiomac volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop Oct 08 15:02:30 volumiomac volumio[1184]: info: sendMpdCommand stop took 6 milliseconds Oct 08 15:02:30 volumiomac volumio[1184]: info: TidalConnect Active Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::volumioGetState Oct 08 15:02:30 volumiomac volumio[1184]: info: Currently active: mpd Oct 08 15:02:30 volumiomac volumio[1184]: info: Setting Volatile state to tidalconnect Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::servicePushState Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreStateMachine::pushState Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::volumioPushState Oct 08 15:02:30 volumiomac volumio[1184]: info: MRS: Pushing multiroomSync output update for this device Oct 08 15:02:30 volumiomac volumio[1184]: info: MRS: Pushing multiroomSync output Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::servicePushState Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreStateMachine::pushState Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::volumioPushState Oct 08 15:02:30 volumiomac volumio[1184]: info: MRS: Pushing multiroomSync output update for this device Oct 08 15:02:30 volumiomac volumio[1184]: info: MRS: Pushing multiroomSync output Oct 08 15:02:30 volumiomac vtcs[2002]: [2024-10-08 15:02:30.708] [tisoc] [warning] [PlaybackControllerImpl.cpp:510] Illegal state:1 Oct 08 15:02:30 volumiomac volumio[1184]: info: Pushing metadata Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::servicePushState Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreStateMachine::pushState Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::volumioPushState Oct 08 15:02:30 volumiomac volumio[1184]: info: MRS: Pushing multiroomSync output update for this device Oct 08 15:02:30 volumiomac volumio[1184]: info: MRS: Pushing multiroomSync output Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::servicePushState Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreStateMachine::pushState Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::volumioPushState Oct 08 15:02:30 volumiomac volumio[1184]: info: MRS: Pushing multiroomSync output update for this device Oct 08 15:02:30 volumiomac volumio[1184]: info: MRS: Pushing multiroomSync output Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::servicePushState Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreStateMachine::pushState Oct 08 15:02:30 volumiomac volumio[1184]: info: CoreCommandRouter::volumioPushState Oct 08 15:02:30 volumiomac volumio[1184]: info: MRS: Pushing multiroomSync output update for this device Oct 08 15:02:30 volumiomac volumio[1184]: info: MRS: Pushing multiroomSync output Oct 08 15:02:31 volumiomac volumio[1184]: info: Pushing metadata Oct 08 15:02:31 volumiomac volumio[1184]: info: CoreCommandRouter::servicePushState Oct 08 15:02:31 volumiomac volumio[1184]: info: CoreStateMachine::pushState Oct 08 15:02:31 volumiomac volumio[1184]: info: CoreCommandRouter::volumioPushState Oct 08 15:02:31 volumiomac volumio[1184]: info: MRS: Pushing multiroomSync output update for this device Oct 08 15:02:31 volumiomac volumio[1184]: info: MRS: Pushing multiroomSync output Oct 08 15:02:31 volumiomac volumio[1184]: info: CoreCommandRouter::servicePushState Oct 08 15:02:31 volumiomac volumio[1184]: info: CoreStateMachine::pushState Oct 08 15:02:31 volumiomac volumio[1184]: info: CoreCommandRouter::volumioPushState Oct 08 15:02:31 volumiomac volumio[1184]: info: MRS: Pushing multiroomSync output update for this device Oct 08 15:02:31 volumiomac volumio[1184]: info: MRS: Pushing multiroomSync output Oct 08 15:02:31 volumiomac volumio[1184]: info: CoreCommandRouter::servicePushState Oct 08 15:02:31 volumiomac volumio[1184]: info: CoreStateMachine::pushState Oct 08 15:02:31 volumiomac volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 15:02:31 volumiomac volumio[1184]: info: CoreCommandRouter::volumioPushState Oct 08 15:02:31 volumiomac volumio[1184]: info: MRS: Pushing multiroomSync output update for this device Oct 08 15:02:31 volumiomac volumio[1184]: info: MRS: Pushing multiroomSync output Oct 08 15:02:32 volumiomac vtcs[2002]: [2024-10-08 15:02:32.064] [tisoc] [warning] [flac_decoder.cpp:282] FLACDecoder::error_callback FLAC__STREAM_DECODER_ERROR_STATUS_LOST_SYNC Oct 08 15:02:32 volumiomac volumio[1184]: info: Executing endpoint metavolumio Oct 08 15:02:32 volumiomac volumio[1184]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 08 15:02:32 volumiomac volumio[1184]: info: Executing endpoint metavolumio Oct 08 15:02:32 volumiomac volumio[1184]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 08 15:02:32 volumiomac volumio[1184]: info: Executing endpoint metavolumio Oct 08 15:02:32 volumiomac volumio[1184]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 08 15:02:33 volumiomac volumio[1184]: info: MRS: STARTING volumioStreaming Oct 08 15:02:33 volumiomac volumio[1184]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 08 15:02:33 volumiomac volumio[1184]: TypeError: Cannot read property 'send' of null Oct 08 15:02:33 volumiomac volumio[1184]: at Client.send (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:128:11) Oct 08 15:02:33 volumiomac volumio[1184]: at Channel.send (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:34:12) Oct 08 15:02:33 volumiomac volumio[1184]: at fn.Controller.send (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:28:16) Oct 08 15:02:33 volumiomac volumio[1184]: at fn.RequestResponseController.request (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:34:8) Oct 08 15:02:33 volumiomac volumio[1184]: at fn.MediaController.sessionRequest (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/media.js:91:8) Oct 08 15:02:33 volumiomac volumio[1184]: at fn.MediaController.stop (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/media.js:107:8) Oct 08 15:02:33 volumiomac volumio[1184]: at DefaultMediaReceiver.stop (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/default-media-receiver.js:42:19) Oct 08 15:02:33 volumiomac volumio[1184]: at Timeout._onTimeout (/myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:5002) Oct 08 15:02:33 volumiomac volumio[1184]: at listOnTimeout (internal/timers.js:557:17) Oct 08 15:02:33 volumiomac volumio[1184]: at processTimers (internal/timers.js:500:7) Oct 08 15:02:33 volumiomac volumio[1184]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 08 15:02:33 volumiomac sudo[10699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Oct 08 15:02:33 volumiomac sudo[10699]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 08 15:02:33 volumiomac systemd[1]: Stopping VolumioStreamingService... Oct 08 15:02:33 volumiomac systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Oct 08 15:02:33 volumiomac systemd[1]: volumioStreaming.service: Succeeded. Oct 08 15:02:33 volumiomac systemd[1]: Stopped VolumioStreamingService. Oct 08 15:02:33 volumiomac systemd[1]: Started VolumioStreamingService. Oct 08 15:02:33 volumiomac sudo[10699]: pam_unix(sudo:session): session closed for user root Oct 08 15:02:33 volumiomac sudo[10711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-08 15:01 Oct 08 15:02:33 volumiomac sudo[10711]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"