-- Logs begin at Fri 2024-11-01 11:36:19 EDT, end at Fri 2024-11-01 11:41:53 EDT. -- Nov 01 11:40:06 myvolumio volumio[976]: info: MRS: Found cast device: SHIELD-Android-TV-cb464da1e8c5fac543ac8fc1f5711381 Nov 01 11:40:26 myvolumio volumio[976]: info: CoreCommandRouter::volumioGetState Nov 01 11:40:26 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:40:26 myvolumio volumio[976]: info: CoreCommandRouter::volumioGetState Nov 01 11:40:26 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:40:26 myvolumio volumio[976]: info: Currently active: radio_paradise Nov 01 11:40:26 myvolumio volumio[976]: info: Stopping currently active service Nov 01 11:40:26 myvolumio volumio[976]: info: CoreCommandRouter::volumioStop Nov 01 11:40:26 myvolumio volumio[976]: info: CoreStateMachine::stop Nov 01 11:40:26 myvolumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 11:40:26 myvolumio volumio[976]: info: TidalConnect Active Nov 01 11:40:26 myvolumio volumio[976]: info: CoreCommandRouter::volumioGetState Nov 01 11:40:26 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:40:26 myvolumio volumio[976]: info: Currently active: radio_paradise Nov 01 11:40:26 myvolumio volumio[976]: info: Setting Volatile state to tidalconnect Nov 01 11:40:26 myvolumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 11:40:26 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:26 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:26 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:40:26 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:26 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:26 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:26 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:40:26 myvolumio volumio[976]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Nov 01 11:40:26 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:26 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:26 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:26 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:26 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:26 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:40:26 myvolumio volumio[976]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Nov 01 11:40:26 myvolumio volumio[976]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Ge48UXf8IAbFWHQTNT6LPw8gzUs2.361e3223a977f6b6c552d8b23700f839.state.status' Nov 01 11:40:27 myvolumio vtcs[1646]: [2024-11-01 11:40:27.302] [tisoc] [warning] [PlaybackControllerImpl.cpp:510] Illegal state:1 Nov 01 11:40:27 myvolumio vtcs[1646]: [2024-11-01 11:40:27.303] [tisoc] [warning] [PlaybackControllerImpl.cpp:214] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Nov 01 11:40:27 myvolumio volumio[976]: info: Pushing metadata Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:27 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:27 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:27 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:27 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:40:27 myvolumio volumio[976]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:27 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:27 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:27 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:27 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:40:27 myvolumio volumio[976]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:27 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:27 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:27 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:27 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:27 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:27 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:27 myvolumio volumio[976]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Ge48UXf8IAbFWHQTNT6LPw8gzUs2.361e3223a977f6b6c552d8b23700f839.state.status' Nov 01 11:40:27 myvolumio volumio[976]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Ge48UXf8IAbFWHQTNT6LPw8gzUs2.361e3223a977f6b6c552d8b23700f839.state.status' Nov 01 11:40:27 myvolumio volumio[976]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 01 11:40:27 myvolumio volumio[976]: info: Pushing metadata Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:27 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:27 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:27 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:27 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:27 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:27 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:27 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:27 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:27 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:27 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:27 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:27 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:27 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:27 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:27 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:27 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:28 myvolumio vtcs[1646]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Nov 01 11:40:28 myvolumio vtcs[1646]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Nov 01 11:40:28 myvolumio vtcs[1646]: [2024-11-01 11:40:28.098] [tisoc] [error] [pcm_sink_alsa.cpp:114] Failed to open device volumio, error: No such device Nov 01 11:40:28 myvolumio vtcs[1646]: [2024-11-01 11:40:28.098] [tisoc] [error] [media_player_tidal.cpp:178] Could not open pcm sink: 1 Nov 01 11:40:28 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:28 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:28 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:40:28 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:28 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:28 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:28 myvolumio volumio[976]: info: Unknown command: notifyPlaybackError Nov 01 11:40:28 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:28 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:28 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:40:28 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:28 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:28 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:28 myvolumio volumio[976]: info: touch_display: Setting screensaver timeout to 600 seconds. Nov 01 11:40:28 myvolumio volumio[976]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 01 11:40:28 myvolumio volumio[976]: info: touch_display: Setting screensaver timeout to 600 seconds. Nov 01 11:40:30 myvolumio vtcs[1646]: [2024-11-01 11:40:30.396] [tisoc] [warning] [PlaybackControllerImpl.cpp:214] Ignore play request because playback state is not STARTED. state_=5, playstate_=1, player_state_=0 Nov 01 11:40:30 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:30 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:30 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:40:30 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:30 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:30 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:30 myvolumio volumio[976]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 01 11:40:47 myvolumio vtcs[1646]: [2024-11-01 11:40:47.690] [tisoc] [warning] [PlaybackControllerImpl.cpp:221] Ignore play request because player state is already PLAYING. state_=5, playstate_=1, player_state_=0 Nov 01 11:40:49 myvolumio vtcs[1646]: [2024-11-01 11:40:49.084] [tisoc] [warning] [PlaybackControllerImpl.cpp:221] Ignore play request because player state is already PLAYING. state_=5, playstate_=1, player_state_=0 Nov 01 11:40:50 myvolumio vtcs[1646]: [2024-11-01 11:40:50.470] [tisoc] [warning] [PlaybackControllerImpl.cpp:221] Ignore play request because player state is already PLAYING. state_=5, playstate_=1, player_state_=0 Nov 01 11:40:52 myvolumio vtcs[1646]: [2024-11-01 11:40:52.048] [tisoc] [error] [QueueManagerImpl.cpp:1413] Illegal state:2 Nov 01 11:40:52 myvolumio vtcs[1646]: [2024-11-01 11:40:52.506] [tisoc] [warning] [PlaybackControllerImpl.cpp:214] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:52 myvolumio volumio[976]: info: Pushing metadata Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:52 myvolumio volumio[976]: info: Pushing metadata Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:52 myvolumio vtcs[1646]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Nov 01 11:40:52 myvolumio vtcs[1646]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Nov 01 11:40:52 myvolumio vtcs[1646]: [2024-11-01 11:40:52.776] [tisoc] [error] [pcm_sink_alsa.cpp:114] Failed to open device volumio, error: No such device Nov 01 11:40:52 myvolumio vtcs[1646]: [2024-11-01 11:40:52.776] [tisoc] [error] [media_player_tidal.cpp:178] Could not open pcm sink: 1 Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:52 myvolumio volumio[976]: info: Unknown command: notifyPlaybackError Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:40:52 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:40:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:40:53 myvolumio volumio[976]: info: touch_display: Setting screensaver timeout to 600 seconds. Nov 01 11:40:53 myvolumio volumio[976]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 01 11:40:53 myvolumio volumio[976]: info: touch_display: Setting screensaver timeout to 600 seconds. Nov 01 11:40:53 myvolumio volumio[976]: info: touch_display: Setting screensaver timeout to 600 seconds. Nov 01 11:40:53 myvolumio volumio[976]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 01 11:41:01 myvolumio vtcs[1646]: [2024-11-01 11:41:01.186] [tisoc] [warning] [PlaybackControllerImpl.cpp:214] Ignore play request because playback state is not STARTED. state_=5, playstate_=1, player_state_=0 Nov 01 11:41:01 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:41:01 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:41:01 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:41:01 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:41:01 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:41:01 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:41:01 myvolumio volumio[976]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 01 11:41:12 myvolumio volumio[976]: info: Volume: {"mute":false} Nov 01 11:41:12 myvolumio volumio[976]: info: VolumeController::SetAlsaVolume1 Nov 01 11:41:13 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:41:13 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:41:13 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:41:13 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:41:13 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:41:13 myvolumio volumio[976]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Nov 01 11:41:13 myvolumio volumio[976]: SPOTIFY: SPOTIFY VOLUME 100 Nov 01 11:41:13 myvolumio volumio[976]: SPOTIFY: VOLUMIO VOLUME 1 Nov 01 11:41:13 myvolumio volumio[976]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 01 11:41:13 myvolumio volumio[976]: info: Setting Spotify Volume from Volumio: 1 Nov 01 11:41:13 myvolumio volumio[976]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Nov 01 11:41:13 myvolumio volumio[976]: info: Volume: {"mute":false} Nov 01 11:41:13 myvolumio volumio[976]: info: VolumeController::SetAlsaVolume22 Nov 01 11:41:13 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:41:13 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:41:13 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:41:13 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:41:13 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:41:13 myvolumio volumio[976]: SPOTIFY: RECEIVED VOLUMIO VOLUME 22 Nov 01 11:41:13 myvolumio volumio[976]: SPOTIFY: SPOTIFY VOLUME 1 Nov 01 11:41:13 myvolumio volumio[976]: SPOTIFY: VOLUMIO VOLUME 22 Nov 01 11:41:13 myvolumio volumio[976]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 01 11:41:13 myvolumio volumio[976]: info: Setting Spotify Volume from Volumio: 22 Nov 01 11:41:13 myvolumio volumio[976]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Nov 01 11:41:13 myvolumio volumio[976]: info: Volume: {"mute":false} Nov 01 11:41:13 myvolumio volumio[976]: info: VolumeController::SetAlsaVolume48 Nov 01 11:41:14 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:41:14 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:41:14 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:41:14 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:41:14 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:41:14 myvolumio volumio[976]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Nov 01 11:41:14 myvolumio volumio[976]: SPOTIFY: SPOTIFY VOLUME 22 Nov 01 11:41:14 myvolumio volumio[976]: SPOTIFY: VOLUMIO VOLUME 48 Nov 01 11:41:14 myvolumio volumio[976]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 01 11:41:14 myvolumio volumio[976]: info: Setting Spotify Volume from Volumio: 48 Nov 01 11:41:14 myvolumio volumio[976]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Nov 01 11:41:14 myvolumio volumio[976]: info: Volume: {"mute":false} Nov 01 11:41:14 myvolumio volumio[976]: info: VolumeController::SetAlsaVolume47 Nov 01 11:41:14 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:41:14 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:41:14 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:41:14 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:41:14 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:41:14 myvolumio volumio[976]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Nov 01 11:41:14 myvolumio volumio[976]: SPOTIFY: SPOTIFY VOLUME 48 Nov 01 11:41:14 myvolumio volumio[976]: SPOTIFY: VOLUMIO VOLUME 47 Nov 01 11:41:14 myvolumio volumio[976]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 11:41:14 myvolumio volumio[976]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Nov 01 11:41:15 myvolumio volumio[976]: SPOTIFY: SETTING SPOTIFY VOLUME 48 Nov 01 11:41:15 myvolumio volumio[976]: info: Sending Spotify command with payload to local API: /player/volume Nov 01 11:41:15 myvolumio go-librespot[1206]: time="2024-11-01T11:41:15-04:00" level=debug msg="update volume to 31456/65535" Nov 01 11:41:15 myvolumio go-librespot[1206]: time="2024-11-01T11:41:15-04:00" level=debug msg="put connect state because VOLUME_CHANGED" Nov 01 11:41:15 myvolumio go-librespot[1206]: time="2024-11-01T11:41:15-04:00" level=trace msg="emitting websocket event: volume" Nov 01 11:41:15 myvolumio volumio[976]: SPOTIFY: received: {"type":"volume","data":{"value":48,"max":100}} Nov 01 11:41:15 myvolumio volumio[976]: SPOTIFY: RECEIVED SPOTIFY VOLUME 48 Nov 01 11:41:18 myvolumio vtcs[1646]: [2024-11-01 11:41:18.025] [tisoc] [warning] [PlaybackControllerImpl.cpp:221] Ignore play request because player state is already PLAYING. state_=5, playstate_=1, player_state_=0 Nov 01 11:41:21 myvolumio volumio[976]: info: PlaybackInactive Nov 01 11:41:21 myvolumio volumio[976]: info: Device Session is_active: true Nov 01 11:41:21 myvolumio volumio[976]: info: DeviceInactive Nov 01 11:41:21 myvolumio volumio[976]: info: Relinquishing Volumio State Nov 01 11:41:21 myvolumio volumio[976]: verbose: UNSET VOLATILE: Service: tidalconnect Nov 01 11:41:21 myvolumio volumio[976]: info: unSetVolatile called Nov 01 11:41:21 myvolumio volumio[976]: info: Relinquishing Volumio State to another service Nov 01 11:41:21 myvolumio vtcs[1646]: [2024-11-01 11:41:21.247] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Nov 01 11:41:21 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:41:21 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:41:21 myvolumio volumio[976]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Nov 01 11:41:21 myvolumio vtcs[1646]: [2024-11-01 11:41:21] [info] asio async_shutdown error: asio.misc:2 (End of file) Nov 01 11:41:32 myvolumio volumio[976]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Nov 01 11:41:32 myvolumio volumio[976]: info: Preparing to save Alsa Options, stopping services first Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::volumioGetState Nov 01 11:41:32 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::volumioPause Nov 01 11:41:32 myvolumio volumio[976]: info: CoreStateMachine::pause Nov 01 11:41:32 myvolumio volumio[976]: info: CoreStateMachine::stPlaybackTimer Nov 01 11:41:32 myvolumio volumio[976]: info: CoreStateMachine::servicePause Nov 01 11:41:32 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::servicePause Nov 01 11:41:32 myvolumio volumio[976]: verbose: ControllerMpd::sendMpdCommand pause Nov 01 11:41:32 myvolumio volumio[976]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Headphones"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 01 11:41:32 myvolumio volumio[976]: info: Found match in Cards Database: setting mixer PCM for card Headphones Nov 01 11:41:32 myvolumio vtcs[1646]: [2024-11-01 11:41:32.389] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Nov 01 11:41:32 myvolumio vtcs[1646]: [2024-11-01 11:41:32.389] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Nov 01 11:41:32 myvolumio sudo[2054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 11:41:32 myvolumio sudo[2054]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:32 myvolumio systemd[1]: Stopping Volumio Tidal Connect Service... Nov 01 11:41:32 myvolumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 01 11:41:32 myvolumio systemd[1]: vtcs.service: Succeeded. Nov 01 11:41:32 myvolumio systemd[1]: Stopped Volumio Tidal Connect Service. Nov 01 11:41:32 myvolumio sudo[2054]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 01 11:41:32 myvolumio volumio[976]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 01 11:41:32 myvolumio volumio[976]: info: Disabling external Volume Control Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 11:41:32 myvolumio volumio[976]: info: Preparing to generate the ALSA configuration file Nov 01 11:41:32 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 11:41:32 myvolumio volumio[976]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 01 11:41:32 myvolumio sudo[2061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 11:41:32 myvolumio volumio[976]: info: Reading ALSA contributions from plugins. Nov 01 11:41:32 myvolumio sudo[2061]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:32 myvolumio volumio[976]: info: sendMpdCommand pause took 314 milliseconds Nov 01 11:41:32 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:41:32 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:41:32 myvolumio volumio[976]: verbose: STATE SERVICE {"status":"pause","seek":0} Nov 01 11:41:32 myvolumio volumio[976]: verbose: CURRENT POSITION 0 Nov 01 11:41:32 myvolumio volumio[976]: info: CoreStateMachine::syncState stateService pause Nov 01 11:41:32 myvolumio volumio[976]: info: CoreStateMachine::syncState currentStatus pause Nov 01 11:41:32 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:41:32 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:41:32 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:41:32 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:41:32 myvolumio volumio[976]: info: CoreStateMachine::stPlaybackTimer Nov 01 11:41:32 myvolumio sudo[2061]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:32 myvolumio volumio[976]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Nov 01 11:41:32 myvolumio volumio[976]: SPOTIFY: SPOTIFY VOLUME 48 Nov 01 11:41:32 myvolumio volumio[976]: SPOTIFY: VOLUMIO VOLUME 47 Nov 01 11:41:32 myvolumio volumio[976]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 01 11:41:32 myvolumio volumio[976]: info: VolumeController:: Volume=86 Mute =false Nov 01 11:41:32 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:41:32 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:41:32 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:41:32 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:41:32 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:41:33 myvolumio volumio[976]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Nov 01 11:41:33 myvolumio volumio[976]: SPOTIFY: SPOTIFY VOLUME 48 Nov 01 11:41:33 myvolumio volumio[976]: SPOTIFY: VOLUMIO VOLUME 86 Nov 01 11:41:33 myvolumio volumio[976]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 01 11:41:33 myvolumio volumio[976]: info: Setting Spotify Volume from Volumio: 86 Nov 01 11:41:33 myvolumio volumio[976]: info: touch_display: Setting screensaver timeout to 600 seconds. Nov 01 11:41:33 myvolumio volumio[976]: info: Asound.conf file written Nov 01 11:41:33 myvolumio sudo[2072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 01 11:41:33 myvolumio sudo[2072]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:33 myvolumio sudo[2072]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:33 myvolumio volumio[976]: info: Output device has changed, restarting MPD Nov 01 11:41:33 myvolumio sudo[2078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 11:41:33 myvolumio sudo[2078]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:33 myvolumio sudo[2078]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:33 myvolumio volumio[976]: info: Output device has changed, restarting Shairport Sync Nov 01 11:41:33 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:33 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 11:41:33 myvolumio sudo[2081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 11:41:33 myvolumio sudo[2081]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:33 myvolumio systemd[1]: Stopping Music Player Daemon... Nov 01 11:41:33 myvolumio volumio[976]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 01 11:41:33 myvolumio volumio[976]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 01 11:41:33 myvolumio systemd[1]: mpd.service: Succeeded. Nov 01 11:41:33 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:33 myvolumio systemd[1]: Stopped Music Player Daemon. Nov 01 11:41:33 myvolumio volumio[976]: info: Output device has changed, restarting MPD Nov 01 11:41:33 myvolumio sudo[2090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 11:41:33 myvolumio systemd[1]: Starting Music Player Daemon... Nov 01 11:41:33 myvolumio sudo[2090]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:33 myvolumio sudo[2093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 11:41:33 myvolumio sudo[2093]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:33 myvolumio volumio[976]: info: Output device has changed, restarting Shairport Sync Nov 01 11:41:33 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:33 myvolumio sudo[2090]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:33 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 11:41:33 myvolumio sudo[2093]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:33 myvolumio sudo[2086]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 11:41:33 myvolumio sudo[2086]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:33 myvolumio sudo[2086]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:33 myvolumio sudo[2096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 11:41:33 myvolumio sudo[2096]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:33 myvolumio volumio[976]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 01 11:41:33 myvolumio volumio[976]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 01 11:41:33 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:33 myvolumio sudo[2107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 11:41:33 myvolumio sudo[2107]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:33 myvolumio sudo[2110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 11:41:33 myvolumio sudo[2110]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:33 myvolumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 01 11:41:33 myvolumio systemd[1]: mpd.service: Succeeded. Nov 01 11:41:33 myvolumio systemd[1]: Stopped Music Player Daemon. Nov 01 11:41:33 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 11:41:33 myvolumio sudo[2113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 11:41:33 myvolumio systemd[1]: Starting Music Player Daemon... Nov 01 11:41:33 myvolumio sudo[2113]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:33 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 11:41:33 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 11:41:33 myvolumio sudo[2107]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:33 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 11:41:33 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 11:41:33 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 11:41:33 myvolumio volumio[976]: info: MPD Permissions set Nov 01 11:41:33 myvolumio volumio[976]: info: MPD Permissions set Nov 01 11:41:33 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 11:41:33 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:33 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:33 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:33 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:33 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:33 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:33 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:33 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:33 myvolumio sudo[2113]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:33 myvolumio sudo[2110]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:33 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 11:41:33 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:33 myvolumio sudo[2114]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 11:41:33 myvolumio sudo[2114]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:33 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:33 myvolumio sudo[2114]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:34 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 11:41:34 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:34 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 11:41:34 myvolumio volumio[976]: info: Starting Shairport Sync Nov 01 11:41:34 myvolumio volumio[976]: info: Starting Shairport Sync Nov 01 11:41:34 myvolumio sudo[2131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 01 11:41:34 myvolumio sudo[2131]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:34 myvolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 01 11:41:34 myvolumio systemd[1]: shairport-sync.service: Succeeded. Nov 01 11:41:34 myvolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 01 11:41:34 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 11:41:34 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 11:41:34 myvolumio volumio[976]: info: Preparing to generate the ALSA configuration file Nov 01 11:41:34 myvolumio sudo[2134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 01 11:41:34 myvolumio sudo[2134]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:34 myvolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 01 11:41:34 myvolumio sudo[2131]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:34 myvolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 01 11:41:34 myvolumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 01 11:41:34 myvolumio systemd[1]: shairport-sync.service: Succeeded. Nov 01 11:41:34 myvolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 01 11:41:34 myvolumio volumio[976]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 01 11:41:34 myvolumio volumio[976]: info: Reading ALSA contributions from plugins. Nov 01 11:41:34 myvolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 01 11:41:34 myvolumio sudo[2134]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:34 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 11:41:34 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 11:41:34 myvolumio volumio[976]: info: Shairport-Sync Started Nov 01 11:41:34 myvolumio volumio[976]: info: Shairport-Sync Started Nov 01 11:41:34 myvolumio volumio[976]: info: Asound.conf file written Nov 01 11:41:34 myvolumio sudo[2142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 01 11:41:34 myvolumio sudo[2142]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:34 myvolumio sudo[2142]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:34 myvolumio volumio[976]: info: Output device has changed, restarting MPD Nov 01 11:41:34 myvolumio sudo[2147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 11:41:34 myvolumio sudo[2147]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:34 myvolumio volumio[976]: info: Output device has changed, restarting Shairport Sync Nov 01 11:41:34 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:34 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 11:41:34 myvolumio sudo[2147]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:34 myvolumio sudo[2150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 11:41:34 myvolumio sudo[2150]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:34 myvolumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 01 11:41:34 myvolumio systemd[1]: mpd.service: Succeeded. Nov 01 11:41:34 myvolumio systemd[1]: Stopped Music Player Daemon. Nov 01 11:41:34 myvolumio volumio[976]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 01 11:41:34 myvolumio volumio[976]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 01 11:41:34 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:34 myvolumio systemd[1]: Starting Music Player Daemon... Nov 01 11:41:34 myvolumio volumio[976]: info: Preparing to generate the ALSA configuration file Nov 01 11:41:34 myvolumio sudo[2159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 11:41:34 myvolumio sudo[2159]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:34 myvolumio volumio[976]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Nov 01 11:41:34 myvolumio volumio[976]: info: Sending Spotify command with payload to local API: /player/volume Nov 01 11:41:34 myvolumio sudo[2159]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:34 myvolumio sudo[2155]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 11:41:34 myvolumio sudo[2155]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:34 myvolumio volumio[976]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 01 11:41:34 myvolumio volumio[976]: info: Reading ALSA contributions from plugins. Nov 01 11:41:34 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 11:41:34 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 11:41:34 myvolumio sudo[2155]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:34 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 11:41:34 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 11:41:34 myvolumio sudo[2162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 11:41:34 myvolumio sudo[2162]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:34 myvolumio go-librespot[1206]: time="2024-11-01T11:41:34-04:00" level=debug msg="update volume to 56360/65535" Nov 01 11:41:34 myvolumio volumio[976]: info: MPD Permissions set Nov 01 11:41:34 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:34 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:34 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:34 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:34 myvolumio sudo[2162]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:34 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 11:41:34 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:34 myvolumio volumio[976]: info: Starting Shairport Sync Nov 01 11:41:34 myvolumio volumio[976]: info: Asound.conf file unchanged, so no further update is needed Nov 01 11:41:34 myvolumio volumio[976]: info: Output device has changed, restarting MPD Nov 01 11:41:34 myvolumio sudo[2173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 01 11:41:34 myvolumio sudo[2173]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:34 myvolumio go-librespot[1206]: time="2024-11-01T11:41:34-04:00" level=debug msg="put connect state because VOLUME_CHANGED" Nov 01 11:41:34 myvolumio go-librespot[1206]: time="2024-11-01T11:41:34-04:00" level=trace msg="emitting websocket event: volume" Nov 01 11:41:34 myvolumio sudo[2176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 01 11:41:34 myvolumio sudo[2176]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:34 myvolumio sudo[2176]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:34 myvolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 01 11:41:34 myvolumio volumio[976]: info: Output device has changed, restarting Shairport Sync Nov 01 11:41:34 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:34 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 11:41:34 myvolumio systemd[1]: shairport-sync.service: Succeeded. Nov 01 11:41:34 myvolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 01 11:41:34 myvolumio sudo[2179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 01 11:41:34 myvolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 01 11:41:34 myvolumio sudo[2179]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:34 myvolumio sudo[2173]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:35 myvolumio volumio[976]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 01 11:41:35 myvolumio volumio[976]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 01 11:41:35 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:35 myvolumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 01 11:41:35 myvolumio systemd[1]: mpd.service: Succeeded. Nov 01 11:41:35 myvolumio systemd[1]: Stopped Music Player Daemon. Nov 01 11:41:35 myvolumio systemd[1]: Starting Music Player Daemon... Nov 01 11:41:35 myvolumio sudo[2193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 11:41:35 myvolumio sudo[2193]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:35 myvolumio volumio[976]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Nov 01 11:41:35 myvolumio volumio[976]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Nov 01 11:41:35 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 11:41:35 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 11:41:35 myvolumio sudo[2188]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 01 11:41:35 myvolumio sudo[2188]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:35 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 11:41:35 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 11:41:35 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 11:41:35 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 11:41:35 myvolumio sudo[2193]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:35 myvolumio sudo[2188]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:35 myvolumio volumio[976]: info: MPD Permissions set Nov 01 11:41:35 myvolumio volumio[976]: info: Shairport-Sync Started Nov 01 11:41:35 myvolumio sudo[2196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 01 11:41:35 myvolumio sudo[2196]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:35 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:35 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 11:41:35 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 11:41:35 myvolumio volumio[976]: info: Starting Shairport Sync Nov 01 11:41:35 myvolumio sudo[2196]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:35 myvolumio sudo[2207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 01 11:41:35 myvolumio sudo[2207]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:35 myvolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 01 11:41:35 myvolumio systemd[1]: shairport-sync.service: Succeeded. Nov 01 11:41:35 myvolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 01 11:41:35 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 01 11:41:35 myvolumio volumio[976]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 01 11:41:35 myvolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 01 11:41:35 myvolumio sudo[2207]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:35 myvolumio volumio[976]: info: Shairport-Sync Started Nov 01 11:41:35 myvolumio volumio[976]: info: TidalConnect service stoped! Nov 01 11:41:35 myvolumio volumio[976]: info: TidalConnect service stoped! Nov 01 11:41:35 myvolumio volumio[976]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 01 11:41:35 myvolumio volumio[976]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 01 11:41:35 myvolumio sudo[2221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 01 11:41:35 myvolumio sudo[2221]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:35 myvolumio systemd[1]: Started Volumio Tidal Connect Service. Nov 01 11:41:35 myvolumio sudo[2221]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:36 myvolumio volumio[976]: info: Executing endpoint tc_getconfig Nov 01 11:41:36 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 01 11:41:36 myvolumio vtcs[2223]: STARTING TidalConnect services, version: 1.4.0.34 Nov 01 11:41:36 myvolumio vtcs[2223]: STARTED TidalConnect services. Nov 01 11:41:36 myvolumio volumio[976]: info: Executing endpoint tc_connect Nov 01 11:41:36 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 01 11:41:36 myvolumio volumio[976]: info: Connecting to TidalConnect Nov 01 11:41:36 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:41:36 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:41:36 myvolumio volumio[976]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Nov 01 11:41:36 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:41:36 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:41:36 myvolumio volumio[976]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Nov 01 11:41:36 myvolumio mpd[2199]: Nov 01 11:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 01 11:41:36 myvolumio systemd[1]: Started Music Player Daemon. Nov 01 11:41:36 myvolumio sudo[2096]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:36 myvolumio sudo[2179]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:36 myvolumio sudo[2150]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:36 myvolumio sudo[2081]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:36 myvolumio volumio[976]: error: MPD error: The expression evaluated to a falsy value: Nov 01 11:41:36 myvolumio volumio[976]: assert.ok(self.idling) Nov 01 11:41:36 myvolumio volumio[976]: error: The expression evaluated to a falsy value: Nov 01 11:41:36 myvolumio volumio[976]: assert.ok(self.idling) Nov 01 11:41:36 myvolumio volumio[976]: error: MPD error: The expression evaluated to a falsy value: Nov 01 11:41:36 myvolumio volumio[976]: assert.ok(self.idling) Nov 01 11:41:36 myvolumio volumio[976]: error: The expression evaluated to a falsy value: Nov 01 11:41:36 myvolumio volumio[976]: assert.ok(self.idling) Nov 01 11:41:36 myvolumio volumio[976]: error: MPD error: The expression evaluated to a falsy value: Nov 01 11:41:36 myvolumio volumio[976]: assert.ok(self.idling) Nov 01 11:41:36 myvolumio volumio[976]: error: The expression evaluated to a falsy value: Nov 01 11:41:36 myvolumio volumio[976]: assert.ok(self.idling) Nov 01 11:41:36 myvolumio volumio[976]: error: updateQueue error: null Nov 01 11:41:36 myvolumio volumio[976]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Nov 01 11:41:36 myvolumio volumio[976]: info: CoreStateMachine::getcurrentVolume Nov 01 11:41:36 myvolumio volumio[976]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 11:41:36 myvolumio volumio[976]: info: TidalConnect service stoped! Nov 01 11:41:36 myvolumio volumio[976]: info: VolumeController:: Volume=86 Mute =false Nov 01 11:41:36 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:41:36 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:41:36 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:41:36 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:41:36 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:41:36 myvolumio volumio[976]: info: CoreStateMachine::updateTrackBlock Nov 01 11:41:36 myvolumio volumio[976]: info: CorePlayQueue::getTrackBlock Nov 01 11:41:36 myvolumio volumio[976]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 11:41:36 myvolumio volumio[976]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Nov 01 11:41:36 myvolumio volumio[976]: info: VolumeController:: Volume=86 Mute =false Nov 01 11:41:36 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:41:36 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:41:36 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:41:36 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:41:36 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:41:36 myvolumio volumio[976]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Nov 01 11:41:36 myvolumio volumio[976]: info: TidalConnect service stoped! Nov 01 11:41:36 myvolumio volumio[976]: info: TidalConnect service stoped! Nov 01 11:41:36 myvolumio volumio[976]: info: TidalConnect service stoped! Nov 01 11:41:37 myvolumio volumio[976]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 01 11:41:37 myvolumio volumio[976]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 01 11:41:37 myvolumio volumio[976]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 01 11:41:37 myvolumio volumio[976]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 01 11:41:37 myvolumio sudo[2265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 01 11:41:37 myvolumio sudo[2265]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:37 myvolumio sudo[2265]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:37 myvolumio sudo[2268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 01 11:41:37 myvolumio sudo[2268]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:37 myvolumio sudo[2268]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:37 myvolumio volumio[976]: info: TidalConnect service stoped! Nov 01 11:41:37 myvolumio volumio[976]: info: TidalConnect service stoped! Nov 01 11:41:37 myvolumio volumio[976]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 01 11:41:37 myvolumio volumio[976]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 01 11:41:37 myvolumio sudo[2281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 01 11:41:37 myvolumio sudo[2281]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:37 myvolumio sudo[2281]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:38 myvolumio volumio[976]: info: TidalConnect service stoped! Nov 01 11:41:38 myvolumio volumio[976]: info: TidalConnect service stoped! Nov 01 11:41:38 myvolumio volumio[976]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 01 11:41:38 myvolumio volumio[976]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 01 11:41:38 myvolumio sudo[2293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 01 11:41:38 myvolumio sudo[2293]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 11:41:38 myvolumio sudo[2293]: pam_unix(sudo:session): session closed for user root Nov 01 11:41:38 myvolumio volumio[976]: info: TidalConnect service started! Nov 01 11:41:40 myvolumio volumio[976]: info: TidalConnect service started! Nov 01 11:41:40 myvolumio volumio[976]: info: TidalConnect service started! Nov 01 11:41:40 myvolumio volumio[976]: info: TidalConnect service started! Nov 01 11:41:41 myvolumio volumio[976]: info: TidalConnect service started! Nov 01 11:41:41 myvolumio volumio[976]: info: CoreCommandRouter::volumioGetState Nov 01 11:41:41 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:41:43 myvolumio volumio[976]: info: Executing endpoint metavolumio Nov 01 11:41:43 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 11:41:52 myvolumio volumio[976]: info: CoreCommandRouter::volumioPlay Nov 01 11:41:52 myvolumio volumio[976]: info: CoreStateMachine::play index undefined Nov 01 11:41:52 myvolumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 11:41:52 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:41:52 myvolumio volumio[976]: info: CoreStateMachine::startPlaybackTimer Nov 01 11:41:52 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:41:52 myvolumio volumio[976]: info: CoreCommandRouter::volumioGetVisibleSources Nov 01 11:41:52 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 11:41:52 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 01 11:41:52 myvolumio volumio[976]: verbose: ControllerMpd::sendMpdCommand play Nov 01 11:41:52 myvolumio volumio[976]: info: sendMpdCommand play took 1 milliseconds Nov 01 11:41:52 myvolumio volumio[976]: info: CoreCommandRouter::servicePushState Nov 01 11:41:52 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:41:52 myvolumio volumio[976]: verbose: STATE SERVICE {"status":"play","seek":0} Nov 01 11:41:52 myvolumio volumio[976]: verbose: CURRENT POSITION 0 Nov 01 11:41:52 myvolumio volumio[976]: info: CoreStateMachine::syncState stateService play Nov 01 11:41:52 myvolumio volumio[976]: info: CoreStateMachine::syncState currentStatus pause Nov 01 11:41:52 myvolumio volumio[976]: info: CoreStateMachine::pushState Nov 01 11:41:52 myvolumio volumio[976]: info: CorePlayQueue::getTrack 0 Nov 01 11:41:52 myvolumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 11:41:52 myvolumio volumio[976]: info: CoreCommandRouter::volumioPushState Nov 01 11:41:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output update for this device Nov 01 11:41:52 myvolumio volumio[976]: info: MRS: Pushing multiroomSync output Nov 01 11:41:52 myvolumio volumio[976]: info: [1730475712802] [RadioParadise] getMetadata started with url undefined Nov 01 11:41:52 myvolumio volumio[976]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Nov 01 11:41:52 myvolumio volumio[976]: info: [1730475712860] [RadioParadise] Error: connect ECONNREFUSED 127.0.0.1:443 Nov 01 11:41:52 myvolumio volumio[976]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 11:41:52 myvolumio volumio[976]: TypeError: Cannot read property 'toUpperCase' of undefined Nov 01 11:41:52 myvolumio volumio[976]: at ControllerRadioParadise.errorToast (/data/plugins/music_service/radio_paradise/index.js:481:41) Nov 01 11:41:52 myvolumio volumio[976]: at ClientRequest. (/data/plugins/music_service/radio_paradise/index.js:452:14) Nov 01 11:41:52 myvolumio volumio[976]: at ClientRequest.emit (events.js:315:20) Nov 01 11:41:52 myvolumio volumio[976]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Nov 01 11:41:52 myvolumio volumio[976]: at TLSSocket.emit (events.js:315:20) Nov 01 11:41:52 myvolumio volumio[976]: at emitErrorNT (internal/streams/destroy.js:106:8) Nov 01 11:41:52 myvolumio volumio[976]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Nov 01 11:41:52 myvolumio volumio[976]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 01 11:41:52 myvolumio volumio[976]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 11:41:53 myvolumio sudo[2307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-01 11:40 Nov 01 11:41:53 myvolumio sudo[2307]: 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="35b821ff7c588ea2ff28d7b2bc9bb02e49e3ab6c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8ef80245b1f7b577b29fe08b77efdc88d53369e5" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 18 Sep 2024 02:17:42 PM CEST" VOLUMIO_VERSION="3.758" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cfa71b4386e95e4d11d00ae35602129a"