-- Logs begin at Thu 2024-08-29 01:05:51 CEST, end at Thu 2024-08-29 18:36:15 CEST. -- Aug 29 18:35:01 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 18:35:01 volumio volumio[925]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 29 18:35:01 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 29 18:35:01 volumio volumio[925]: info: Received Get System Version Aug 29 18:35:01 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 18:35:01 volumio volumio[925]: info: Received Get System Info Aug 29 18:35:01 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 18:35:01 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 18:35:01 volumio volumio[925]: info: Discovery: Getting this device information Aug 29 18:35:01 volumio volumio[925]: info: CoreCommandRouter::volumioGetState Aug 29 18:35:01 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 18:35:03 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 18:35:03 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 18:35:03 volumio volumio[925]: info: Discovery: Getting this device information Aug 29 18:35:03 volumio volumio[925]: info: CoreCommandRouter::volumioGetState Aug 29 18:35:03 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 18:35:14 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Aug 29 18:35:14 volumio volumio[925]: info: MRS: Setting this device as single Aug 29 18:35:14 volumio volumio[925]: info: Aug 29 18:35:14 volumio volumio[925]: [1724949314870] ---------------------------- Setting Multiroom Single Aug 29 18:35:14 volumio volumio[925]: info: MRS: disable multiroom output Aug 29 18:35:14 volumio volumio[925]: info: MRS: Set multiroom target PCM to volumioDiscard Aug 29 18:35:14 volumio volumio[925]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Aug 29 18:35:15 volumio volumio[925]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Aug 29 18:35:15 volumio volumio[925]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Aug 29 18:35:15 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Aug 29 18:35:16 volumio volumio[925]: info: MRS: STOPPING volumioStreaming Aug 29 18:35:16 volumio sudo[13724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 29 18:35:16 volumio sudo[13724]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 18:35:16 volumio systemd[1]: Stopping VolumioStreamingService... Aug 29 18:35:16 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Aug 29 18:35:16 volumio sudo[13727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream0.ts /tmp/hls/stream1.ts /tmp/hls/stream10.ts /tmp/hls/stream11.ts /tmp/hls/stream12.ts /tmp/hls/stream13.ts /tmp/hls/stream14.ts /tmp/hls/stream15.ts /tmp/hls/stream16.ts /tmp/hls/stream17.ts /tmp/hls/stream18.ts /tmp/hls/stream19.ts /tmp/hls/stream2.ts /tmp/hls/stream20.ts /tmp/hls/stream21.ts /tmp/hls/stream22.ts /tmp/hls/stream23.ts /tmp/hls/stream24.ts /tmp/hls/stream25.ts /tmp/hls/stream26.ts /tmp/hls/stream27.ts /tmp/hls/stream28.ts /tmp/hls/stream29.ts /tmp/hls/stream3.ts /tmp/hls/stream30.ts /tmp/hls/stream31.ts /tmp/hls/stream32.ts /tmp/hls/stream33.ts /tmp/hls/stream34.ts /tmp/hls/stream35.ts /tmp/hls/stream36.ts /tmp/hls/stream37.ts /tmp/hls/stream38.ts /tmp/hls/stream39.ts /tmp/hls/stream4.ts /tmp/hls/stream40.ts /tmp/hls/stream41.ts /tmp/hls/stream42.ts /tmp/hls/stream43.ts /tmp/hls/stream44.ts /tmp/hls/stream45.ts Aug 29 18:35:16 volumio sudo[13727]: volumio : (command continued) /tmp/hls/stream46.ts /tmp/hls/stream5.ts /tmp/hls/stream6.ts /tmp/hls/stream7.ts /tmp/hls/stream8.ts /tmp/hls/stream9.ts Aug 29 18:35:16 volumio sudo[13727]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 18:35:16 volumio systemd[1]: volumioStreaming.service: Succeeded. Aug 29 18:35:16 volumio systemd[1]: Stopped VolumioStreamingService. Aug 29 18:35:16 volumio volumio[925]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 29 18:35:16 volumio sudo[13727]: pam_unix(sudo:session): session closed for user root Aug 29 18:35:16 volumio sudo[13724]: pam_unix(sudo:session): session closed for user root Aug 29 18:35:16 volumio volumio[925]: info: MRS: Removed streaming files Aug 29 18:35:16 volumio volumio[925]: info: MRS: volumioStreaming STOPPED Aug 29 18:35:20 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Aug 29 18:35:20 volumio volumio[925]: info: MRS: Starting cast device: Chromecast-93e3f8d8afcb1f997c0c883ff6468e3f Aug 29 18:35:20 volumio volumio[925]: info: Enabled audio output: Chromecast-93e3f8d8afcb1f997c0c883ff6468e3f Aug 29 18:35:20 volumio volumio[925]: info: MRS: Setting this device as Streaming Server Aug 29 18:35:20 volumio volumio[925]: info: Aug 29 18:35:20 volumio volumio[925]: [1724949320531] ---------------------------- MRS: Setting Streaming Server Aug 29 18:35:20 volumio volumio[925]: info: MRS: enable multiroom server output Aug 29 18:35:20 volumio volumio[925]: info: MRS: Set multiroom target PCM to volumioMultiRoom Aug 29 18:35:20 volumio volumio[925]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Aug 29 18:35:20 volumio volumio[925]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Aug 29 18:35:20 volumio volumio[925]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Aug 29 18:35:20 volumio volumio[925]: info: MRS: STARTING volumioStreaming Aug 29 18:35:20 volumio sudo[13733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 29 18:35:20 volumio sudo[13733]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 18:35:20 volumio sudo[13733]: pam_unix(sudo:session): session closed for user root Aug 29 18:35:20 volumio sudo[13736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Aug 29 18:35:20 volumio sudo[13736]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 18:35:20 volumio systemd[1]: Started VolumioStreamingService. Aug 29 18:35:20 volumio sudo[13736]: pam_unix(sudo:session): session closed for user root Aug 29 18:35:20 volumio volumio[925]: info: MRS: volumioStreaming STARTED Aug 29 18:35:20 volumio sudo[13739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 29 18:35:20 volumio sudo[13739]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 18:35:20 volumio sudo[13739]: pam_unix(sudo:session): session closed for user root Aug 29 18:35:21 volumio volumio[925]: info: MRS: Connected to cast device: Chromecast-93e3f8d8afcb1f997c0c883ff6468e3f Aug 29 18:35:25 volumio volumio[925]: info: Refreshing Cached IP Addresses Aug 29 18:35:25 volumio sudo[13747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 18:35:25 volumio sudo[13747]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 18:35:25 volumio sudo[13747]: pam_unix(sudo:session): session closed for user root Aug 29 18:35:25 volumio sudo[13750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 18:35:25 volumio sudo[13750]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 18:35:25 volumio sudo[13750]: pam_unix(sudo:session): session closed for user root Aug 29 18:35:25 volumio volumio[925]: info: MRS: Cast player state: IDLE Aug 29 18:35:27 volumio volumio[925]: info: MRS: Cast player state: PLAYING Aug 29 18:35:27 volumio volumio[925]: info: MRS: cast media loaded Aug 29 18:35:27 volumio volumio[925]: info: MRS: Cast player state: BUFFERING Aug 29 18:35:28 volumio volumio[925]: info: MRS: Cast player state: BUFFERING Aug 29 18:35:28 volumio volumio[925]: info: MRS: Cast player state: PLAYING Aug 29 18:35:30 volumio volumio[925]: info: MRS: Cast player state: PLAYING Aug 29 18:35:30 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 29 18:35:30 volumio volumio[925]: info: VolumeController::SetAlsaVolume- Aug 29 18:35:30 volumio volumio[925]: info: CoreCommandRouter::volumioGetState Aug 29 18:35:30 volumio volumio[925]: info: CoreStateMachine::pushState Aug 29 18:35:30 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 18:35:30 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Aug 29 18:35:30 volumio volumio[925]: info: MRS: Pushing multiroomSync output update for this device Aug 29 18:35:30 volumio volumio[925]: info: MRS: Pushing multiroomSync output Aug 29 18:35:30 volumio volumio[925]: error: Help! Some callbacks for volumioPushState are crashing! Aug 29 18:35:30 volumio volumio[925]: error: service is not defined Aug 29 18:35:30 volumio volumio[925]: SPOTIFY: RECEIVED VOLUMIO VOLUME 43 Aug 29 18:35:31 volumio volumio[925]: info: VolumeController::SetAlsaVolume- Aug 29 18:35:31 volumio volumio[925]: info: CoreCommandRouter::volumioGetState Aug 29 18:35:31 volumio volumio[925]: info: CoreStateMachine::pushState Aug 29 18:35:31 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 18:35:31 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Aug 29 18:35:31 volumio volumio[925]: info: MRS: Pushing multiroomSync output update for this device Aug 29 18:35:31 volumio volumio[925]: info: MRS: Pushing multiroomSync output Aug 29 18:35:31 volumio volumio[925]: error: Help! Some callbacks for volumioPushState are crashing! Aug 29 18:35:31 volumio volumio[925]: error: service is not defined Aug 29 18:35:31 volumio volumio[925]: SPOTIFY: RECEIVED VOLUMIO VOLUME 42 Aug 29 18:35:31 volumio volumio[925]: SPOTIFY: SPOTIFY VOLUME 43 Aug 29 18:35:31 volumio volumio[925]: SPOTIFY: VOLUMIO VOLUME 42 Aug 29 18:35:31 volumio volumio[925]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 29 18:35:31 volumio volumio[925]: info: VolumeController::SetAlsaVolume- Aug 29 18:35:32 volumio volumio[925]: info: CoreCommandRouter::volumioGetState Aug 29 18:35:32 volumio volumio[925]: info: CoreStateMachine::pushState Aug 29 18:35:32 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 18:35:32 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Aug 29 18:35:32 volumio volumio[925]: info: MRS: Pushing multiroomSync output update for this device Aug 29 18:35:32 volumio volumio[925]: info: MRS: Pushing multiroomSync output Aug 29 18:35:32 volumio volumio[925]: error: Help! Some callbacks for volumioPushState are crashing! Aug 29 18:35:32 volumio volumio[925]: error: service is not defined Aug 29 18:35:32 volumio volumio[925]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Aug 29 18:35:32 volumio volumio[925]: SPOTIFY: SPOTIFY VOLUME 43 Aug 29 18:35:32 volumio volumio[925]: SPOTIFY: VOLUMIO VOLUME 41 Aug 29 18:35:32 volumio volumio[925]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 29 18:35:32 volumio volumio[925]: info: Setting Spotify Volume from Volumio: 41 Aug 29 18:35:32 volumio volumio[925]: info: VolumeController::SetAlsaVolume- Aug 29 18:35:32 volumio volumio[925]: info: CoreCommandRouter::volumioGetState Aug 29 18:35:32 volumio volumio[925]: info: CoreStateMachine::pushState Aug 29 18:35:32 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 18:35:32 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Aug 29 18:35:32 volumio volumio[925]: info: MRS: Pushing multiroomSync output update for this device Aug 29 18:35:32 volumio volumio[925]: info: MRS: Pushing multiroomSync output Aug 29 18:35:32 volumio volumio[925]: error: Help! Some callbacks for volumioPushState are crashing! Aug 29 18:35:32 volumio volumio[925]: error: service is not defined Aug 29 18:35:32 volumio volumio[925]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Aug 29 18:35:32 volumio volumio[925]: SPOTIFY: SPOTIFY VOLUME 41 Aug 29 18:35:32 volumio volumio[925]: SPOTIFY: VOLUMIO VOLUME 40 Aug 29 18:35:32 volumio volumio[925]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 29 18:35:32 volumio volumio[925]: info: VolumeController::SetAlsaVolume- Aug 29 18:35:32 volumio volumio[925]: info: CoreCommandRouter::volumioGetState Aug 29 18:35:32 volumio volumio[925]: info: CoreStateMachine::pushState Aug 29 18:35:32 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 18:35:32 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Aug 29 18:35:32 volumio volumio[925]: info: MRS: Pushing multiroomSync output update for this device Aug 29 18:35:32 volumio volumio[925]: info: MRS: Pushing multiroomSync output Aug 29 18:35:32 volumio volumio[925]: error: Help! Some callbacks for volumioPushState are crashing! Aug 29 18:35:32 volumio volumio[925]: error: service is not defined Aug 29 18:35:32 volumio volumio[925]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Aug 29 18:35:32 volumio volumio[925]: SPOTIFY: SPOTIFY VOLUME 41 Aug 29 18:35:32 volumio volumio[925]: SPOTIFY: VOLUMIO VOLUME 39 Aug 29 18:35:32 volumio volumio[925]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 29 18:35:32 volumio volumio[925]: info: Setting Spotify Volume from Volumio: 39 Aug 29 18:35:33 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 18:35:33 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 18:35:33 volumio volumio[925]: info: Discovery: Getting this device information Aug 29 18:35:33 volumio volumio[925]: info: CoreCommandRouter::volumioGetState Aug 29 18:35:33 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 18:35:34 volumio volumio[925]: SPOTIFY: SETTING SPOTIFY VOLUME 39 Aug 29 18:35:34 volumio volumio[925]: info: Sending Spotify command with payload to local API: /player/volume Aug 29 18:35:34 volumio go-librespot[1174]: time="2024-08-29T18:35:34+02:00" level=debug msg="update volume to 25558/65535" Aug 29 18:35:34 volumio go-librespot[1174]: time="2024-08-29T18:35:34+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 29 18:35:34 volumio go-librespot[1174]: time="2024-08-29T18:35:34+02:00" level=trace msg="emitting websocket event: volume" Aug 29 18:35:34 volumio volumio[925]: SPOTIFY: received: {"type":"volume","data":{"value":39,"max":100}} Aug 29 18:35:34 volumio volumio[925]: SPOTIFY: RECEIVED SPOTIFY VOLUME 39 Aug 29 18:35:34 volumio kernel: hwmon hwmon1: Voltage normalised Aug 29 18:35:39 volumio volumio[925]: info: VolumeController::SetAlsaVolume44 Aug 29 18:35:39 volumio volumio[925]: info: CoreCommandRouter::volumioGetState Aug 29 18:35:39 volumio volumio[925]: info: CoreStateMachine::pushState Aug 29 18:35:39 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 18:35:39 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Aug 29 18:35:39 volumio volumio[925]: info: MRS: Pushing multiroomSync output update for this device Aug 29 18:35:39 volumio volumio[925]: info: MRS: Pushing multiroomSync output Aug 29 18:35:39 volumio volumio[925]: error: Help! Some callbacks for volumioPushState are crashing! Aug 29 18:35:39 volumio volumio[925]: error: service is not defined Aug 29 18:35:39 volumio volumio[925]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Aug 29 18:35:39 volumio volumio[925]: SPOTIFY: SPOTIFY VOLUME 39 Aug 29 18:35:39 volumio volumio[925]: SPOTIFY: VOLUMIO VOLUME 44 Aug 29 18:35:39 volumio volumio[925]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 29 18:35:39 volumio volumio[925]: info: Setting Spotify Volume from Volumio: 44 Aug 29 18:35:40 volumio volumio[925]: info: VolumeController::SetAlsaVolume18 Aug 29 18:35:40 volumio volumio[925]: info: CoreCommandRouter::volumioGetState Aug 29 18:35:40 volumio volumio[925]: info: CoreStateMachine::pushState Aug 29 18:35:40 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 18:35:40 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Aug 29 18:35:40 volumio volumio[925]: info: MRS: Pushing multiroomSync output update for this device Aug 29 18:35:40 volumio volumio[925]: info: MRS: Pushing multiroomSync output Aug 29 18:35:40 volumio volumio[925]: error: Help! Some callbacks for volumioPushState are crashing! Aug 29 18:35:40 volumio volumio[925]: error: service is not defined Aug 29 18:35:40 volumio volumio[925]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Aug 29 18:35:40 volumio volumio[925]: SPOTIFY: SPOTIFY VOLUME 44 Aug 29 18:35:40 volumio volumio[925]: SPOTIFY: VOLUMIO VOLUME 18 Aug 29 18:35:40 volumio volumio[925]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 29 18:35:40 volumio volumio[925]: info: Setting Spotify Volume from Volumio: 18 Aug 29 18:35:42 volumio volumio[925]: SPOTIFY: SETTING SPOTIFY VOLUME 18 Aug 29 18:35:42 volumio volumio[925]: info: Sending Spotify command with payload to local API: /player/volume Aug 29 18:35:42 volumio go-librespot[1174]: time="2024-08-29T18:35:42+02:00" level=debug msg="update volume to 11796/65535" Aug 29 18:35:42 volumio go-librespot[1174]: time="2024-08-29T18:35:42+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 29 18:35:42 volumio go-librespot[1174]: time="2024-08-29T18:35:42+02:00" level=trace msg="emitting websocket event: volume" Aug 29 18:35:42 volumio volumio[925]: SPOTIFY: received: {"type":"volume","data":{"value":18,"max":100}} Aug 29 18:35:42 volumio volumio[925]: SPOTIFY: RECEIVED SPOTIFY VOLUME 18 Aug 29 18:35:55 volumio volumio[925]: info: MRS: Cast error: read ECONNRESET Aug 29 18:36:03 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 18:36:03 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 18:36:03 volumio volumio[925]: info: Discovery: Getting this device information Aug 29 18:36:03 volumio volumio[925]: info: CoreCommandRouter::volumioGetState Aug 29 18:36:03 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 18:36:09 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 29 18:36:11 volumio volumio[925]: info: CoreCommandRouter::volumioPause Aug 29 18:36:11 volumio volumio[925]: info: CoreStateMachine::pause Aug 29 18:36:11 volumio volumio[925]: info: CoreStateMachine::stPlaybackTimer Aug 29 18:36:11 volumio volumio[925]: info: CoreStateMachine::servicePause Aug 29 18:36:11 volumio volumio[925]: info: CoreCommandRouter::servicePause Aug 29 18:36:11 volumio volumio[925]: info: ControllerMpd::pause Aug 29 18:36:11 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand pause Aug 29 18:36:11 volumio volumio[925]: info: sendMpdCommand pause took 3 milliseconds Aug 29 18:36:11 volumio volumio[925]: info: Aug 29 18:36:11 volumio volumio[925]: ---------------------------- MPD announces state update: player Aug 29 18:36:11 volumio volumio[925]: info: ControllerMpd::getState Aug 29 18:36:11 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand status Aug 29 18:36:11 volumio volumio[925]: info: sendMpdCommand status took 3 milliseconds Aug 29 18:36:11 volumio volumio[925]: verbose: ControllerMpd::parseState Aug 29 18:36:11 volumio volumio[925]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 18:36:11 volumio volumio[925]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 29 18:36:11 volumio volumio[925]: verbose: ControllerMpd::parseTrackInfo Aug 29 18:36:11 volumio volumio[925]: info: ControllerMpd::pushState Aug 29 18:36:11 volumio volumio[925]: info: CoreCommandRouter::servicePushState Aug 29 18:36:11 volumio volumio[925]: info: CoreStateMachine::pushState Aug 29 18:36:11 volumio volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 18:36:11 volumio volumio[925]: info: CoreCommandRouter::volumioPushState Aug 29 18:36:11 volumio volumio[925]: info: MRS: Pushing multiroomSync output update for this device Aug 29 18:36:11 volumio volumio[925]: info: MRS: Pushing multiroomSync output Aug 29 18:36:11 volumio volumio[925]: info: ------------------------------ 35ms Aug 29 18:36:11 volumio volumio[925]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Aug 29 18:36:13 volumio kernel: hwmon hwmon1: Voltage normalised Aug 29 18:36:14 volumio volumio[925]: info: MRS: STARTING volumioStreaming Aug 29 18:36:14 volumio sudo[13808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream0.ts /tmp/hls/stream1.ts /tmp/hls/stream10.ts /tmp/hls/stream11.ts /tmp/hls/stream12.ts /tmp/hls/stream13.ts /tmp/hls/stream14.ts /tmp/hls/stream15.ts /tmp/hls/stream16.ts /tmp/hls/stream17.ts /tmp/hls/stream18.ts /tmp/hls/stream19.ts /tmp/hls/stream2.ts /tmp/hls/stream20.ts /tmp/hls/stream21.ts /tmp/hls/stream22.ts /tmp/hls/stream23.ts /tmp/hls/stream24.ts /tmp/hls/stream25.ts /tmp/hls/stream26.ts /tmp/hls/stream27.ts /tmp/hls/stream28.ts /tmp/hls/stream29.ts /tmp/hls/stream3.ts /tmp/hls/stream30.ts /tmp/hls/stream31.ts /tmp/hls/stream32.ts /tmp/hls/stream33.ts /tmp/hls/stream34.ts /tmp/hls/stream35.ts /tmp/hls/stream36.ts /tmp/hls/stream37.ts /tmp/hls/stream38.ts /tmp/hls/stream39.ts /tmp/hls/stream4.ts /tmp/hls/stream40.ts /tmp/hls/stream41.ts /tmp/hls/stream42.ts /tmp/hls/stream43.ts /tmp/hls/stream44.ts /tmp/hls/stream45.ts Aug 29 18:36:14 volumio sudo[13808]: volumio : (command continued) /tmp/hls/stream46.ts /tmp/hls/stream47.ts /tmp/hls/stream48.ts /tmp/hls/stream49.ts /tmp/hls/stream5.ts /tmp/hls/stream50.ts /tmp/hls/stream6.ts /tmp/hls/stream7.ts /tmp/hls/stream8.ts /tmp/hls/stream9.ts Aug 29 18:36:14 volumio sudo[13808]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 18:36:14 volumio sudo[13808]: pam_unix(sudo:session): session closed for user root Aug 29 18:36:14 volumio volumio[925]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 18:36:14 volumio volumio[925]: TypeError: Cannot read property 'send' of null Aug 29 18:36:14 volumio volumio[925]: at Client.send (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:128:11) Aug 29 18:36:14 volumio volumio[925]: at Channel.send (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:34:12) Aug 29 18:36:14 volumio volumio[925]: at fn.Controller.send (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:28:16) Aug 29 18:36:14 volumio volumio[925]: at fn.RequestResponseController.request (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:34:8) Aug 29 18:36:14 volumio volumio[925]: at fn.MediaController.sessionRequest (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/media.js:91:8) Aug 29 18:36:14 volumio volumio[925]: at fn.MediaController.stop (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/media.js:107:8) Aug 29 18:36:14 volumio volumio[925]: at DefaultMediaReceiver.stop (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/default-media-receiver.js:42:19) Aug 29 18:36:14 volumio volumio[925]: at Timeout._onTimeout (/myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:5002) Aug 29 18:36:14 volumio volumio[925]: at listOnTimeout (internal/timers.js:554:17) Aug 29 18:36:14 volumio volumio[925]: at processTimers (internal/timers.js:497:7) Aug 29 18:36:14 volumio volumio[925]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 18:36:14 volumio sudo[13811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Aug 29 18:36:14 volumio sudo[13811]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 18:36:14 volumio systemd[1]: Stopping VolumioStreamingService... Aug 29 18:36:14 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Aug 29 18:36:14 volumio systemd[1]: volumioStreaming.service: Succeeded. Aug 29 18:36:14 volumio systemd[1]: Stopped VolumioStreamingService. Aug 29 18:36:14 volumio systemd[1]: Started VolumioStreamingService. Aug 29 18:36:14 volumio sudo[13811]: pam_unix(sudo:session): session closed for user root Aug 29 18:36:14 volumio sudo[13823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 29 18:36:14 volumio sudo[13823]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 18:36:14 volumio sudo[13823]: pam_unix(sudo:session): session closed for user root Aug 29 18:36:15 volumio sudo[13827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 18:35 Aug 29 18:36:15 volumio sudo[13827]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"