-- Logs begin at Fri 2024-08-16 16:34:44 CEST, end at Fri 2024-08-16 17:12:38 CEST. -- Aug 16 17:11:02 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Aug 16 17:11:02 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 16 17:11:02 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Aug 16 17:11:02 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 16 17:11:03 rivo volumio-remote-updater[2836]: No test mode Aug 16 17:11:03 rivo volumio-remote-updater[2836]: No alpha test mode Aug 16 17:11:03 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 16 17:11:03 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 16 17:11:03 rivo volumio-remote-updater[2836]: No test mode Aug 16 17:11:03 rivo volumio-remote-updater[2836]: No alpha test mode Aug 16 17:11:03 rivo volumio[3176]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 16 17:11:03 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 16 17:11:03 rivo volumio[3176]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 16 17:11:03 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 16 17:11:04 rivo volumio[3176]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Aug 16 17:11:04 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Aug 16 17:11:04 rivo volumio[3176]: error: Failed callmethod call: TypeError: Cannot read property 'value' of undefined Aug 16 17:11:05 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Aug 16 17:11:05 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 16 17:11:05 rivo systemd[1]: systemd-timedated.service: Succeeded. Aug 16 17:11:05 rivo volumio-remote-updater[2836]: No test mode Aug 16 17:11:05 rivo volumio-remote-updater[2836]: No alpha test mode Aug 16 17:11:05 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 16 17:11:05 rivo volumio[3176]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 16 17:11:05 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 16 17:11:10 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Aug 16 17:11:10 rivo volumio[3176]: info: CoreCommandRouter::Reload Ui Aug 16 17:11:10 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Aug 16 17:11:10 rivo volumio[3176]: info: Wizard terminated Successfully Aug 16 17:11:10 rivo volumio[3176]: info: CoreCommandRouter::volumiosetStartupVolume Aug 16 17:11:10 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 16 17:11:11 rivo sudo[9649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 16 17:11:11 rivo sudo[9649]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 17:11:11 rivo sudo[9649]: pam_unix(sudo:session): session closed for user root Aug 16 17:11:11 rivo sudo[9653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 16 17:11:11 rivo sudo[9653]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 17:11:11 rivo sudo[9653]: pam_unix(sudo:session): session closed for user root Aug 16 17:11:11 rivo volumio[3176]: verbose: New Socket.io Connection to 192.168.187.22 from 192.168.187.161 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 OPR/112.0.0.0 Engine version: 3 Transport: polling Total Clients: 8 Aug 16 17:11:11 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 16 17:11:11 rivo volumio[3176]: info: CoreCommandRouter::volumioGetVisibleSources Aug 16 17:11:11 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 16 17:11:11 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:11 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:11 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 16 17:11:11 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 16 17:11:11 rivo volumio[3176]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 16 17:11:11 rivo volumio[3176]: info: Received Get System Info Aug 16 17:11:11 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 16 17:11:11 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 16 17:11:11 rivo volumio[3176]: info: Discovery: Getting this device information Aug 16 17:11:11 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:11 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:11 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 16 17:11:11 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:11 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:11 rivo volumio[3176]: info: Listing playlists Aug 16 17:11:11 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 16 17:11:11 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 16 17:11:11 rivo volumio[3176]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 16 17:11:11 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 16 17:11:12 rivo sudo[9657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 16 17:11:12 rivo sudo[9657]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 17:11:12 rivo sudo[9659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 16 17:11:12 rivo sudo[9659]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 17:11:12 rivo sudo[9657]: pam_unix(sudo:session): session closed for user root Aug 16 17:11:12 rivo sudo[9659]: pam_unix(sudo:session): session closed for user root Aug 16 17:11:12 rivo volumio[3176]: verbose: New Socket.io Connection to 192.168.187.22 from 192.168.187.161 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 OPR/112.0.0.0 Engine version: 3 Transport: polling Total Clients: 8 Aug 16 17:11:12 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 16 17:11:12 rivo volumio[3176]: info: CoreCommandRouter::volumioGetVisibleSources Aug 16 17:11:12 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 16 17:11:12 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:12 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:12 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 16 17:11:12 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 16 17:11:12 rivo volumio[3176]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 16 17:11:12 rivo volumio[3176]: info: Received Get System Info Aug 16 17:11:12 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 16 17:11:12 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 16 17:11:12 rivo volumio[3176]: info: Discovery: Getting this device information Aug 16 17:11:12 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:12 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:12 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 16 17:11:12 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:12 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:12 rivo volumio[3176]: info: Listing playlists Aug 16 17:11:12 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 16 17:11:12 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 16 17:11:12 rivo volumio[3176]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 16 17:11:12 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 16 17:11:13 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:13 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:13 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 16 17:11:13 rivo volumio[3176]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 9 Aug 16 17:11:13 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 16 17:11:13 rivo volumio[3176]: info: Received Get System Info Aug 16 17:11:13 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 16 17:11:13 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 16 17:11:13 rivo volumio[3176]: info: Discovery: Getting this device information Aug 16 17:11:13 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:13 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:13 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 16 17:11:14 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 16 17:11:14 rivo volumio[3176]: info: Received Get System Info Aug 16 17:11:14 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 16 17:11:14 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 16 17:11:14 rivo volumio[3176]: info: Discovery: Getting this device information Aug 16 17:11:14 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:14 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:14 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 16 17:11:15 rivo volumio[3176]: info: Executing endpoint metavolumio Aug 16 17:11:15 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 16 17:11:15 rivo volumio[3176]: info: Executing endpoint metavolumio Aug 16 17:11:15 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 16 17:11:15 rivo volumio[3176]: info: Executing endpoint metavolumio Aug 16 17:11:15 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 16 17:11:16 rivo volumio[3176]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 10 Aug 16 17:11:17 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 16 17:11:17 rivo volumio[3176]: info: CoreCommandRouter::volumioGetVisibleSources Aug 16 17:11:17 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 16 17:11:17 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:17 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:17 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 16 17:11:17 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 16 17:11:17 rivo volumio[3176]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 16 17:11:17 rivo volumio[3176]: info: Received Get System Info Aug 16 17:11:17 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 16 17:11:17 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 16 17:11:17 rivo volumio[3176]: info: Discovery: Getting this device information Aug 16 17:11:17 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:17 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:17 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 16 17:11:17 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:17 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:17 rivo volumio[3176]: info: Listing playlists Aug 16 17:11:17 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 16 17:11:17 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 16 17:11:17 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 16 17:11:18 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 16 17:11:18 rivo volumio[3176]: info: Received Get System Info Aug 16 17:11:18 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 16 17:11:18 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 16 17:11:18 rivo volumio[3176]: info: Discovery: Getting this device information Aug 16 17:11:18 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:18 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:18 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 16 17:11:18 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:18 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:18 rivo volumio[3176]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 16 17:11:19 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 16 17:11:19 rivo volumio[3176]: info: Executing endpoint metavolumio Aug 16 17:11:19 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 16 17:11:19 rivo volumio[3176]: info: Executing endpoint metavolumio Aug 16 17:11:19 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 16 17:11:19 rivo volumio[3176]: info: Executing endpoint metavolumio Aug 16 17:11:19 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 16 17:11:20 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 16 17:11:20 rivo volumio[3176]: info: Received Get System Info Aug 16 17:11:20 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 16 17:11:20 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 16 17:11:20 rivo volumio[3176]: info: Discovery: Getting this device information Aug 16 17:11:20 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:20 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:20 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 16 17:11:20 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Aug 16 17:11:21 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Aug 16 17:11:22 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 16 17:11:22 rivo volumio[3176]: error: Failed request for metavolumio API Aug 16 17:11:26 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 16 17:11:27 rivo volumio[3176]: error: Failed request for metavolumio API Aug 16 17:11:30 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 16 17:11:30 rivo volumio[3176]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 16 17:11:30 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 16 17:11:30 rivo volumio[3176]: info: Received Get System Version Aug 16 17:11:30 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 16 17:11:30 rivo volumio[3176]: info: Received Get System Info Aug 16 17:11:30 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 16 17:11:30 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 16 17:11:30 rivo volumio[3176]: info: Discovery: Getting this device information Aug 16 17:11:30 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:30 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:30 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 16 17:11:32 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Aug 16 17:11:32 rivo volumio[3176]: info: MRS: Starting browser stream Aug 16 17:11:32 rivo volumio[3176]: info: MRS: Setting this device as single Aug 16 17:11:32 rivo volumio[3176]: info: Aug 16 17:11:32 rivo volumio[3176]: [1723821092882] ---------------------------- Setting Multiroom Single Aug 16 17:11:32 rivo volumio[3176]: info: MRS: disable multiroom output Aug 16 17:11:32 rivo volumio[3176]: info: MRS: Set multiroom target PCM to volumioDiscard Aug 16 17:11:32 rivo volumio[3176]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Aug 16 17:11:33 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 16 17:11:33 rivo volumio[3176]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 16 17:11:33 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 16 17:11:33 rivo volumio[3176]: info: Received Get System Version Aug 16 17:11:33 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 16 17:11:33 rivo volumio[3176]: info: Received Get System Info Aug 16 17:11:33 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 16 17:11:33 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 16 17:11:33 rivo volumio[3176]: info: Discovery: Getting this device information Aug 16 17:11:33 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:33 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:33 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 16 17:11:33 rivo volumio[3176]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Aug 16 17:11:33 rivo volumio[3176]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Aug 16 17:11:34 rivo volumio[3176]: info: MRS: STOPPING volumioStreaming Aug 16 17:11:34 rivo sudo[9681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 16 17:11:34 rivo sudo[9681]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 17:11:34 rivo systemd[1]: Stopping VolumioStreamingService... Aug 16 17:11:34 rivo systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Aug 16 17:11:34 rivo sudo[9684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 16 17:11:34 rivo volumio[3176]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 16 17:11:34 rivo sudo[9684]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 17:11:34 rivo systemd[1]: volumioStreaming.service: Succeeded. Aug 16 17:11:34 rivo systemd[1]: Stopped VolumioStreamingService. Aug 16 17:11:34 rivo sudo[9684]: pam_unix(sudo:session): session closed for user root Aug 16 17:11:34 rivo sudo[9681]: pam_unix(sudo:session): session closed for user root Aug 16 17:11:34 rivo volumio[3176]: info: MRS: Removed streaming files Aug 16 17:11:34 rivo volumio[3176]: info: MRS: volumioStreaming STOPPED Aug 16 17:11:37 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Aug 16 17:11:37 rivo volumio[3176]: info: MRS: Starting browser stream Aug 16 17:11:37 rivo volumio[3176]: info: MRS: Setting this device as Streaming Server Aug 16 17:11:37 rivo volumio[3176]: info: Aug 16 17:11:37 rivo volumio[3176]: [1723821097074] ---------------------------- MRS: Setting Streaming Server Aug 16 17:11:37 rivo volumio[3176]: info: Enabled audio output: browserPlayback Aug 16 17:11:37 rivo volumio[3176]: info: MRS: enable multiroom server output Aug 16 17:11:37 rivo volumio[3176]: info: MRS: Set multiroom target PCM to volumioMultiRoom Aug 16 17:11:37 rivo volumio[3176]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Aug 16 17:11:37 rivo volumio[3176]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Aug 16 17:11:37 rivo volumio[3176]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Aug 16 17:11:37 rivo volumio[3176]: info: MRS: STARTING volumioStreaming Aug 16 17:11:37 rivo sudo[9690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 16 17:11:37 rivo sudo[9690]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 17:11:37 rivo sudo[9690]: pam_unix(sudo:session): session closed for user root Aug 16 17:11:37 rivo sudo[9693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Aug 16 17:11:37 rivo sudo[9693]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 17:11:37 rivo systemd[1]: Started VolumioStreamingService. Aug 16 17:11:37 rivo sudo[9693]: pam_unix(sudo:session): session closed for user root Aug 16 17:11:37 rivo volumio[3176]: info: MRS: volumioStreaming STARTED Aug 16 17:11:37 rivo sudo[9698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 16 17:11:37 rivo sudo[9698]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 17:11:37 rivo sudo[9698]: pam_unix(sudo:session): session closed for user root Aug 16 17:11:39 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Aug 16 17:11:39 rivo volumio[3176]: info: MRS: Starting browser stream Aug 16 17:11:39 rivo volumio[3176]: info: MRS: Setting this device as single Aug 16 17:11:39 rivo volumio[3176]: info: Aug 16 17:11:39 rivo volumio[3176]: [1723821099207] ---------------------------- Setting Multiroom Single Aug 16 17:11:39 rivo volumio[3176]: info: MRS: disable multiroom output Aug 16 17:11:39 rivo volumio[3176]: info: MRS: Set multiroom target PCM to volumioDiscard Aug 16 17:11:39 rivo volumio[3176]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Aug 16 17:11:39 rivo volumio[3176]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Aug 16 17:11:40 rivo volumio[3176]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Aug 16 17:11:40 rivo volumio[3176]: info: MRS: STOPPING volumioStreaming Aug 16 17:11:40 rivo sudo[9709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 16 17:11:40 rivo sudo[9709]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 17:11:40 rivo systemd[1]: Stopping VolumioStreamingService... Aug 16 17:11:40 rivo sudo[9712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 16 17:11:40 rivo sudo[9712]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 17:11:40 rivo systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Aug 16 17:11:40 rivo sudo[9712]: pam_unix(sudo:session): session closed for user root Aug 16 17:11:40 rivo volumio[3176]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 16 17:11:40 rivo systemd[1]: volumioStreaming.service: Succeeded. Aug 16 17:11:40 rivo volumio[3176]: info: MRS: Removed streaming files Aug 16 17:11:40 rivo systemd[1]: Stopped VolumioStreamingService. Aug 16 17:11:40 rivo sudo[9709]: pam_unix(sudo:session): session closed for user root Aug 16 17:11:40 rivo volumio[3176]: info: MRS: volumioStreaming STOPPED Aug 16 17:11:41 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Aug 16 17:11:41 rivo volumio[3176]: info: MRS: Starting browser stream Aug 16 17:11:41 rivo volumio[3176]: info: MRS: Setting this device as Streaming Server Aug 16 17:11:41 rivo volumio[3176]: info: Aug 16 17:11:41 rivo volumio[3176]: [1723821101787] ---------------------------- MRS: Setting Streaming Server Aug 16 17:11:41 rivo volumio[3176]: info: Enabled audio output: browserPlayback Aug 16 17:11:41 rivo volumio[3176]: info: MRS: enable multiroom server output Aug 16 17:11:41 rivo volumio[3176]: info: MRS: Set multiroom target PCM to volumioMultiRoom Aug 16 17:11:41 rivo volumio[3176]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Aug 16 17:11:41 rivo volumio[3176]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Aug 16 17:11:41 rivo volumio[3176]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Aug 16 17:11:41 rivo volumio[3176]: info: MRS: STARTING volumioStreaming Aug 16 17:11:41 rivo sudo[9720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 16 17:11:41 rivo sudo[9720]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 17:11:42 rivo sudo[9720]: pam_unix(sudo:session): session closed for user root Aug 16 17:11:42 rivo sudo[9723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Aug 16 17:11:42 rivo sudo[9723]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 17:11:42 rivo systemd[1]: Started VolumioStreamingService. Aug 16 17:11:42 rivo sudo[9723]: pam_unix(sudo:session): session closed for user root Aug 16 17:11:42 rivo volumio[3176]: info: MRS: volumioStreaming STARTED Aug 16 17:11:42 rivo sudo[9730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 16 17:11:42 rivo sudo[9730]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 17:11:42 rivo sudo[9730]: pam_unix(sudo:session): session closed for user root Aug 16 17:11:46 rivo volumio[3176]: info: VolumeController::SetAlsaVolume26 Aug 16 17:11:46 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:46 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:46 rivo volumio[3176]: info: CoreStateMachine::pushState Aug 16 17:11:46 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:46 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 16 17:11:46 rivo volumio[3176]: info: CoreCommandRouter::volumioPushState Aug 16 17:11:46 rivo volumio[3176]: info: MRS: Pushing multiroomSync output update for this device Aug 16 17:11:46 rivo volumio[3176]: info: MRS: Pushing multiroomSync output Aug 16 17:11:46 rivo volumio[3176]: info: CoreCommandRouter::volumioGetQueue Aug 16 17:11:46 rivo volumio[3176]: info: CoreStateMachine::getQueue Aug 16 17:11:46 rivo volumio[3176]: info: CorePlayQueue::getQueue Aug 16 17:11:46 rivo volumio[3176]: info: Exiting sleep status due to volume change Aug 16 17:11:46 rivo volumio[3176]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Aug 16 17:11:46 rivo volumio[3176]: SPOTIFY: SPOTIFY VOLUME 25 Aug 16 17:11:46 rivo volumio[3176]: SPOTIFY: VOLUMIO VOLUME 26 Aug 16 17:11:46 rivo volumio[3176]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 16 17:11:46 rivo volumio[3176]: info: Updating RAAT Signal Path Aug 16 17:11:46 rivo volumio[3176]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Aug 16 17:11:46 rivo volumio[3176]: xcb_connection_has_error() returned true Aug 16 17:11:46 rivo volumio[3176]: info: MCU Signalled Sleep Mode Disabled Aug 16 17:11:46 rivo sudo[9739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Aug 16 17:11:46 rivo sudo[9739]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 17:11:46 rivo kernel: fb: osd[0] enable: 1 (Xorg) Aug 16 17:11:46 rivo kernel: fb: osd[0] enable: 1 (Xorg) Aug 16 17:11:46 rivo sudo[9739]: pam_unix(sudo:session): session closed for user root Aug 16 17:11:46 rivo sudo[9742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Aug 16 17:11:46 rivo sudo[9742]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 16 17:11:46 rivo sudo[9742]: pam_unix(sudo:session): session closed for user root Aug 16 17:11:47 rivo volumio[3176]: info: VolumeController::SetAlsaVolume57 Aug 16 17:11:47 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:47 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:47 rivo volumio[3176]: info: CoreStateMachine::pushState Aug 16 17:11:47 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:47 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 16 17:11:47 rivo volumio[3176]: info: CoreCommandRouter::volumioPushState Aug 16 17:11:47 rivo volumio[3176]: info: MRS: Pushing multiroomSync output update for this device Aug 16 17:11:47 rivo volumio[3176]: info: MRS: Pushing multiroomSync output Aug 16 17:11:47 rivo volumio[3176]: info: CoreCommandRouter::volumioGetQueue Aug 16 17:11:47 rivo volumio[3176]: info: CoreStateMachine::getQueue Aug 16 17:11:47 rivo volumio[3176]: info: CorePlayQueue::getQueue Aug 16 17:11:47 rivo volumio[3176]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Aug 16 17:11:47 rivo volumio[3176]: SPOTIFY: SPOTIFY VOLUME 25 Aug 16 17:11:47 rivo volumio[3176]: SPOTIFY: VOLUMIO VOLUME 57 Aug 16 17:11:47 rivo volumio[3176]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 16 17:11:47 rivo volumio[3176]: info: Setting Spotify Volume from Volumio: 57 Aug 16 17:11:47 rivo volumio[3176]: info: Updating RAAT Signal Path Aug 16 17:11:47 rivo volumio[3176]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Aug 16 17:11:47 rivo volumio[3176]: xcb_connection_has_error() returned true Aug 16 17:11:47 rivo volumio[3176]: info: VolumeController::SetAlsaVolume54 Aug 16 17:11:47 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:47 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:47 rivo volumio[3176]: info: CoreStateMachine::pushState Aug 16 17:11:47 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:47 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 16 17:11:47 rivo volumio[3176]: info: CoreCommandRouter::volumioPushState Aug 16 17:11:47 rivo volumio[3176]: info: MRS: Pushing multiroomSync output update for this device Aug 16 17:11:47 rivo volumio[3176]: info: MRS: Pushing multiroomSync output Aug 16 17:11:47 rivo volumio[3176]: info: CoreCommandRouter::volumioGetQueue Aug 16 17:11:47 rivo volumio[3176]: info: CoreStateMachine::getQueue Aug 16 17:11:47 rivo volumio[3176]: info: CorePlayQueue::getQueue Aug 16 17:11:47 rivo volumio[3176]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Aug 16 17:11:47 rivo volumio[3176]: SPOTIFY: SPOTIFY VOLUME 57 Aug 16 17:11:47 rivo volumio[3176]: SPOTIFY: VOLUMIO VOLUME 54 Aug 16 17:11:47 rivo volumio[3176]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 16 17:11:47 rivo volumio[3176]: info: Setting Spotify Volume from Volumio: 54 Aug 16 17:11:47 rivo volumio[3176]: info: Updating RAAT Signal Path Aug 16 17:11:47 rivo volumio[3176]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Aug 16 17:11:47 rivo volumio[3176]: xcb_connection_has_error() returned true Aug 16 17:11:47 rivo volumio[3176]: info: VolumeController::SetAlsaVolume53 Aug 16 17:11:47 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:47 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:47 rivo volumio[3176]: info: CoreStateMachine::pushState Aug 16 17:11:47 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:47 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 16 17:11:47 rivo volumio[3176]: info: CoreCommandRouter::volumioPushState Aug 16 17:11:47 rivo volumio[3176]: info: MRS: Pushing multiroomSync output update for this device Aug 16 17:11:47 rivo volumio[3176]: info: MRS: Pushing multiroomSync output Aug 16 17:11:47 rivo volumio[3176]: info: CoreCommandRouter::volumioGetQueue Aug 16 17:11:47 rivo volumio[3176]: info: CoreStateMachine::getQueue Aug 16 17:11:47 rivo volumio[3176]: info: CorePlayQueue::getQueue Aug 16 17:11:47 rivo volumio[3176]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Aug 16 17:11:47 rivo volumio[3176]: SPOTIFY: SPOTIFY VOLUME 54 Aug 16 17:11:47 rivo volumio[3176]: SPOTIFY: VOLUMIO VOLUME 53 Aug 16 17:11:47 rivo volumio[3176]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 16 17:11:47 rivo volumio[3176]: info: Updating RAAT Signal Path Aug 16 17:11:47 rivo volumio[3176]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Aug 16 17:11:47 rivo volumio[3176]: xcb_connection_has_error() returned true Aug 16 17:11:49 rivo volumio[3176]: SPOTIFY: SETTING SPOTIFY VOLUME 54 Aug 16 17:11:49 rivo volumio[3176]: info: Sending Spotify command with payload to local API: /player/volume Aug 16 17:11:49 rivo volumio[3176]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 16 17:11:49 rivo volumio[3176]: No protocol specified Aug 16 17:11:49 rivo volumio[3176]: xcb_connection_has_error() returned true Aug 16 17:11:49 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 16 17:11:49 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 16 17:11:49 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 16 17:11:49 rivo volumio[3176]: No protocol specified Aug 16 17:11:49 rivo volumio[3176]: xcb_connection_has_error() returned true Aug 16 17:11:49 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 17:11:49 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 17:11:49 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 17:11:49 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 17:11:49 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 17:11:49 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 17:11:49 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 16 17:11:49 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 16 17:11:49 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 16 17:11:49 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Aug 16 17:11:49 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 16 17:11:49 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 16 17:11:54 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:54 rivo volumio[3176]: info: CorePlayQueue::getTrack 2 Aug 16 17:11:54 rivo volumio[3176]: info: Prefetching next song Aug 16 17:11:54 rivo volumio[3176]: info: Doing Prefetch in UPNP Aug 16 17:11:54 rivo volumio[3176]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.187.5:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I5865227.flac" Aug 16 17:11:54 rivo volumio[3176]: info: Aug 16 17:11:54 rivo volumio[3176]: ---------------------------- MPD announces system playlist update Aug 16 17:11:54 rivo volumio[3176]: info: Ignoring MPD Status Update Aug 16 17:11:54 rivo volumio[3176]: info: sendMpdCommand add "http://192.168.187.5:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I5865227.flac" took 6 milliseconds Aug 16 17:11:54 rivo volumio[3176]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 16 17:11:54 rivo volumio[3176]: info: Aug 16 17:11:54 rivo volumio[3176]: ---------------------------- MPD announces state update: options Aug 16 17:11:54 rivo volumio[3176]: info: ------------------------------ 12ms Aug 16 17:11:54 rivo volumio[3176]: info: sendMpdCommand consume 1 took 8 milliseconds Aug 16 17:11:54 rivo volumio[3176]: info: ControllerMpd::getState Aug 16 17:11:54 rivo volumio[3176]: verbose: ControllerMpd::sendMpdCommand status Aug 16 17:11:55 rivo volumio[3176]: info: sendMpdCommand status took 21 milliseconds Aug 16 17:11:55 rivo volumio[3176]: verbose: ControllerMpd::parseState Aug 16 17:11:55 rivo volumio[3176]: info: ControllerMpd::pushState Aug 16 17:11:55 rivo volumio[3176]: info: CoreCommandRouter::servicePushState Aug 16 17:11:55 rivo volumio[3176]: info: CoreStateMachine::pushState Aug 16 17:11:55 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:55 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 16 17:11:55 rivo volumio[3176]: info: CoreCommandRouter::volumioPushState Aug 16 17:11:55 rivo volumio[3176]: info: MRS: Pushing multiroomSync output update for this device Aug 16 17:11:55 rivo volumio[3176]: info: MRS: Pushing multiroomSync output Aug 16 17:11:55 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:55 rivo volumio[3176]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 16 17:11:55 rivo volumio[3176]: verbose: CURRENT POSITION 1 Aug 16 17:11:55 rivo volumio[3176]: info: CoreStateMachine::syncState stateService stop Aug 16 17:11:55 rivo volumio[3176]: info: CoreStateMachine::syncState currentStatus pause Aug 16 17:11:55 rivo volumio[3176]: info: CoreStateMachine::pushState Aug 16 17:11:55 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:55 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 16 17:11:55 rivo volumio[3176]: info: CoreCommandRouter::volumioPushState Aug 16 17:11:55 rivo volumio[3176]: info: MRS: Pushing multiroomSync output update for this device Aug 16 17:11:55 rivo volumio[3176]: info: MRS: Pushing multiroomSync output Aug 16 17:11:55 rivo volumio[3176]: info: ------------------------------ 67ms Aug 16 17:11:55 rivo volumio[3176]: info: CoreCommandRouter::volumioGetQueue Aug 16 17:11:55 rivo volumio[3176]: info: CoreStateMachine::getQueue Aug 16 17:11:55 rivo volumio[3176]: info: CorePlayQueue::getQueue Aug 16 17:11:55 rivo volumio[3176]: info: CoreCommandRouter::volumioGetQueue Aug 16 17:11:55 rivo volumio[3176]: info: CoreStateMachine::getQueue Aug 16 17:11:55 rivo volumio[3176]: info: CorePlayQueue::getQueue Aug 16 17:11:55 rivo volumio[3176]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Aug 16 17:11:55 rivo volumio[3176]: SPOTIFY: SPOTIFY VOLUME 54 Aug 16 17:11:55 rivo volumio[3176]: SPOTIFY: VOLUMIO VOLUME 53 Aug 16 17:11:55 rivo volumio[3176]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 16 17:11:55 rivo volumio[3176]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Aug 16 17:11:55 rivo volumio[3176]: SPOTIFY: SPOTIFY VOLUME 54 Aug 16 17:11:55 rivo volumio[3176]: SPOTIFY: VOLUMIO VOLUME 53 Aug 16 17:11:55 rivo volumio[3176]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 16 17:11:55 rivo volumio[3176]: info: Updating RAAT Signal Path Aug 16 17:11:55 rivo volumio[3176]: info: Updating RAAT Signal Path Aug 16 17:11:56 rivo volumio[3176]: info: CoreCommandRouter::volumioGetState Aug 16 17:11:56 rivo volumio[3176]: info: CorePlayQueue::getTrack 1 Aug 16 17:11:58 rivo volumio[3176]: info: Executing endpoint metavolumio Aug 16 17:11:58 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 16 17:11:58 rivo volumio[3176]: info: Executing endpoint metavolumio Aug 16 17:11:58 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 16 17:11:58 rivo volumio[3176]: info: Executing endpoint metavolumio Aug 16 17:11:58 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 16 17:11:59 rivo volumio[3176]: info: CoreStateMachine::startPlaybackTimer Aug 16 17:11:59 rivo volumio[3176]: info: CorePlayQueue::getTrack 2 Aug 16 17:11:59 rivo volumio[3176]: info: CoreCommandRouter::volumioGetQueue Aug 16 17:11:59 rivo volumio[3176]: info: CoreStateMachine::getQueue Aug 16 17:11:59 rivo volumio[3176]: info: CorePlayQueue::getQueue Aug 16 17:12:00 rivo volumio[3176]: info: CoreStateMachine::pushState Aug 16 17:12:00 rivo volumio[3176]: info: CorePlayQueue::getTrack 2 Aug 16 17:12:00 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 16 17:12:00 rivo volumio[3176]: info: CoreCommandRouter::volumioPushState Aug 16 17:12:00 rivo volumio[3176]: info: MRS: Pushing multiroomSync output update for this device Aug 16 17:12:00 rivo volumio[3176]: info: MRS: Pushing multiroomSync output Aug 16 17:12:00 rivo volumio[3176]: info: CoreCommandRouter::volumioGetQueue Aug 16 17:12:00 rivo volumio[3176]: info: CoreStateMachine::getQueue Aug 16 17:12:00 rivo volumio[3176]: info: CorePlayQueue::getQueue Aug 16 17:12:00 rivo volumio[3176]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Aug 16 17:12:00 rivo volumio[3176]: SPOTIFY: SPOTIFY VOLUME 54 Aug 16 17:12:00 rivo volumio[3176]: SPOTIFY: VOLUMIO VOLUME 53 Aug 16 17:12:00 rivo volumio[3176]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 16 17:12:00 rivo volumio[3176]: info: Updating RAAT Signal Path Aug 16 17:12:02 rivo volumio[3176]: info: Executing endpoint metavolumio Aug 16 17:12:02 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 16 17:12:02 rivo volumio[3176]: info: Executing endpoint metavolumio Aug 16 17:12:02 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 16 17:12:02 rivo volumio[3176]: info: Executing endpoint metavolumio Aug 16 17:12:02 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 16 17:12:03 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 16 17:12:03 rivo volumio[3176]: info: CURURI: music-library Aug 16 17:12:03 rivo volumio[3176]: info: Preload queue cleared Aug 16 17:12:09 rivo ntpd[7480]: 162.159.200.1 local addr 192.168.187.22 -> Aug 16 17:12:11 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 16 17:12:11 rivo volumio[3176]: info: Preload queue cleared Aug 16 17:12:26 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 16 17:12:28 rivo volumio[3176]: info: Preload queue cleared Aug 16 17:12:37 rivo volumio[3176]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 16 17:12:37 rivo volumio[3176]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 16 17:12:37 rivo volumio[3176]: TypeError: Cannot read property 'length' of undefined Aug 16 17:12:37 rivo volumio[3176]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Aug 16 17:12:37 rivo volumio[3176]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Aug 16 17:12:37 rivo volumio[3176]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Aug 16 17:12:37 rivo volumio[3176]: at Parser.emit (events.js:400:28) Aug 16 17:12:37 rivo volumio[3176]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Aug 16 17:12:37 rivo volumio[3176]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Aug 16 17:12:37 rivo volumio[3176]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Aug 16 17:12:37 rivo volumio[3176]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Aug 16 17:12:37 rivo volumio[3176]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Aug 16 17:12:37 rivo volumio[3176]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Aug 16 17:12:37 rivo volumio[3176]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Aug 16 17:12:37 rivo volumio[3176]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Aug 16 17:12:37 rivo volumio[3176]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Aug 16 17:12:37 rivo volumio[3176]: at IncomingMessage.emit (events.js:412:35) Aug 16 17:12:37 rivo volumio[3176]: at endReadableNT (internal/streams/readable.js:1333:12) Aug 16 17:12:37 rivo volumio[3176]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Aug 16 17:12:37 rivo volumio[3176]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 16 17:12:38 rivo sudo[9806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-16 17:11 Aug 16 17:12:38 rivo sudo[9806]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 05:06:46 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="420eec03f218d056e8306370042003fc"