-- Logs begin at Fri 2024-04-05 21:23:48 JST, end at Thu 2024-05-09 12:07:34 JST. -- May 09 12:06:01 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:01 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:01 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:01 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:01 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:01 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:06:04 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:04 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:04 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:04 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:05 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:05 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:06:08 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:08 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:08 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:08 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:08 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:08 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:06:11 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:11 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:11 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:11 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:11 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:11 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:06:14 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:14 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:14 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:14 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:14 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:14 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:06:17 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:17 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:17 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:17 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:17 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:17 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:06:20 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:20 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:20 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:20 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:20 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:20 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:06:23 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:23 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:23 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:23 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:23 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:23 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:06:27 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:27 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:27 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:27 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:27 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:27 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:06:30 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:30 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:30 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:30 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:30 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:30 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:06:31 rivo volumio[5428]: info: Received OAUTH Data May 09 12:06:31 rivo volumio[5428]: info: Refreshing QOBUZ token May 09 12:06:31 rivo sudo[14354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 12:06:31 rivo sudo[14354]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 12:06:31 rivo sudo[14354]: pam_unix(sudo:session): session closed for user root May 09 12:06:31 rivo sudo[14358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 12:06:31 rivo sudo[14358]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 12:06:31 rivo sudo[14358]: pam_unix(sudo:session): session closed for user root May 09 12:06:31 rivo volumio[5428]: verbose: New Socket.io Connection to 192.168.68.54 from 192.168.68.53 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.4.1 Safari/605.1.15 Total Clients: 8 May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 12:06:32 rivo volumio[5428]: info: Retrieving Cloud Streaming UI May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::volumioGetState May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::volumioGetVisibleSources May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 12:06:32 rivo volumio[5428]: info: Access Token successfully retrieved May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 12:06:32 rivo volumio[5428]: info: [1715223992086] CoreMusicLibrary::Adding element QOBUZ May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 12:06:32 rivo volumio[5428]: Cannot find translation for source QOBUZ May 09 12:06:32 rivo volumio[5428]: info: Getting Tidal Cloud Configuration May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 12:06:32 rivo volumio[5428]: info: Getting Qobuz Cloud Configuration May 09 12:06:32 rivo volumio[5428]: info: Asking plugin for UI Config May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 12:06:32 rivo volumio[5428]: info: Getting Spotify Cloud Configuration May 09 12:06:32 rivo volumio[5428]: info: Asking plugin for UI Config May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 12:06:32 rivo volumio[5428]: info: Saving Spotify Acccount May 09 12:06:32 rivo volumio[5428]: info: Got it May 09 12:06:32 rivo volumio[5428]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 09 12:06:32 rivo volumio[5428]: info: Got Tidal Cloud Configuration May 09 12:06:32 rivo volumio[5428]: info: Got it May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::volumioGetBrowseSources May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::volumioGetBrowseSources May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::volumioGetBrowseSources May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 09 12:06:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 09 12:06:33 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:33 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:33 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:33 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:33 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:33 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:06:34 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 12:06:34 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 12:06:36 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri May 09 12:06:36 rivo volumio[5428]: info: CoreCommandRouter::volumioGetQueue May 09 12:06:36 rivo volumio[5428]: info: CoreStateMachine::getQueue May 09 12:06:36 rivo volumio[5428]: info: CorePlayQueue::getQueue May 09 12:06:36 rivo volumio[5428]: info: Listing playlists May 09 12:06:36 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:36 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:36 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:36 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:36 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:37 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:06:37 rivo volumio[5428]: info: handleBrowseUri took 924 milliseconds May 09 12:06:37 rivo volumio[5428]: info: Preload queue cleared May 09 12:06:37 rivo volumio[5428]: info: Preload queue cleared May 09 12:06:38 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri May 09 12:06:39 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:39 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:39 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:39 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:40 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:40 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:06:40 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri May 09 12:06:41 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri May 09 12:06:41 rivo volumio[5428]: info: handleBrowseUri took 2958 milliseconds May 09 12:06:41 rivo volumio[5428]: info: Preload queue cleared May 09 12:06:42 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri May 09 12:06:42 rivo volumio[5428]: info: handleBrowseUri took 2887 milliseconds May 09 12:06:42 rivo volumio[5428]: info: Preload queue cleared May 09 12:06:43 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:43 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:43 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:43 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:43 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:43 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:06:43 rivo volumio[5428]: info: handleBrowseUri took 1135 milliseconds May 09 12:06:43 rivo volumio[5428]: info: Preload queue cleared May 09 12:06:43 rivo volumio[5428]: info: Preloading song: qobuz://song/532999 May 09 12:06:43 rivo volumio[5428]: info: Preloading song: qobuz://song/533001 May 09 12:06:43 rivo volumio[5428]: info: Preloading song: qobuz://song/533003 May 09 12:06:43 rivo volumio[5428]: info: Preloading song: qobuz://song/533005 May 09 12:06:43 rivo volumio[5428]: info: Preloading song: qobuz://song/533007 May 09 12:06:43 rivo volumio[5428]: info: Preloading song: qobuz://song/533009 May 09 12:06:43 rivo volumio[5428]: info: Preloading song: qobuz://song/533010 May 09 12:06:43 rivo volumio[5428]: info: Preloading song: qobuz://song/533011 May 09 12:06:43 rivo volumio[5428]: info: Preloading song: qobuz://song/533012 May 09 12:06:43 rivo volumio[5428]: info: Preloading song: qobuz://song/533013 May 09 12:06:43 rivo volumio[5428]: info: Preloading song: qobuz://song/533000 May 09 12:06:43 rivo volumio[5428]: info: Preloading song: qobuz://song/533002 May 09 12:06:43 rivo volumio[5428]: info: Preloading song: qobuz://song/533004 May 09 12:06:43 rivo volumio[5428]: info: Preloading song: qobuz://song/533006 May 09 12:06:43 rivo volumio[5428]: info: Preloading song: qobuz://song/533008 May 09 12:06:43 rivo volumio[5428]: info: Exploding uri qobuz://song/532999 in service qobuz May 09 12:06:43 rivo volumio[5428]: info: Exploding uri qobuz://song/533001 in service qobuz May 09 12:06:43 rivo volumio[5428]: info: Exploding uri qobuz://song/533003 in service qobuz May 09 12:06:43 rivo volumio[5428]: info: Exploding uri qobuz://song/533005 in service qobuz May 09 12:06:43 rivo volumio[5428]: info: Exploding uri qobuz://song/533007 in service qobuz May 09 12:06:43 rivo volumio[5428]: info: Exploding uri qobuz://song/533009 in service qobuz May 09 12:06:43 rivo volumio[5428]: info: Exploding uri qobuz://song/533010 in service qobuz May 09 12:06:43 rivo volumio[5428]: info: Exploding uri qobuz://song/533011 in service qobuz May 09 12:06:43 rivo volumio[5428]: info: Exploding uri qobuz://song/533012 in service qobuz May 09 12:06:43 rivo volumio[5428]: info: Exploding uri qobuz://song/533013 in service qobuz May 09 12:06:43 rivo volumio[5428]: info: Exploding uri qobuz://song/533000 in service qobuz May 09 12:06:43 rivo volumio[5428]: info: Exploding uri qobuz://song/533002 in service qobuz May 09 12:06:43 rivo volumio[5428]: info: Exploding uri qobuz://song/533004 in service qobuz May 09 12:06:44 rivo volumio[5428]: info: Exploding uri qobuz://song/533006 in service qobuz May 09 12:06:44 rivo volumio[5428]: info: Exploding uri qobuz://song/533008 in service qobuz May 09 12:06:44 rivo volumio[5428]: info: handleBrowseUri took 2958 milliseconds May 09 12:06:44 rivo volumio[5428]: info: Preload queue cleared May 09 12:06:44 rivo volumio[5428]: info: explodeUri took 1070 milliseconds May 09 12:06:44 rivo volumio[5428]: info: explodeUri took 1064 milliseconds May 09 12:06:44 rivo volumio[5428]: info: explodeUri took 1074 milliseconds May 09 12:06:44 rivo volumio[5428]: info: explodeUri took 1054 milliseconds May 09 12:06:44 rivo volumio[5428]: info: explodeUri took 1079 milliseconds May 09 12:06:44 rivo volumio[5428]: info: explodeUri took 1075 milliseconds May 09 12:06:44 rivo volumio[5428]: info: explodeUri took 1082 milliseconds May 09 12:06:44 rivo volumio[5428]: info: explodeUri took 1057 milliseconds May 09 12:06:44 rivo volumio[5428]: info: explodeUri took 1112 milliseconds May 09 12:06:44 rivo volumio[5428]: info: explodeUri took 1079 milliseconds May 09 12:06:44 rivo volumio[5428]: info: explodeUri took 1047 milliseconds May 09 12:06:45 rivo volumio[5428]: info: explodeUri took 1085 milliseconds May 09 12:06:45 rivo volumio[5428]: info: explodeUri took 1044 milliseconds May 09 12:06:45 rivo volumio[5428]: info: explodeUri took 1150 milliseconds May 09 12:06:45 rivo volumio[5428]: info: explodeUri took 1093 milliseconds May 09 12:06:46 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:46 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:46 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:46 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:46 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:46 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:06:49 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:49 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:49 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:49 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:49 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:49 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:06:50 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 12:06:50 rivo volumio[5428]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 09 12:06:50 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 09 12:06:50 rivo volumio[5428]: info: Received Get System Version May 09 12:06:50 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 12:06:50 rivo volumio[5428]: info: Received Get System Info May 09 12:06:50 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 12:06:50 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 12:06:50 rivo volumio[5428]: info: Discovery: Getting this device information May 09 12:06:50 rivo volumio[5428]: info: CoreCommandRouter::volumioGetState May 09 12:06:50 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 12:06:52 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:52 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:52 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:52 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:52 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:52 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:06:55 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:55 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:55 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:55 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:55 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:55 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:06:58 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:06:58 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:06:58 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:06:58 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:06:58 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:06:58 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:07:01 rivo ntpd[13332]: 45.76.218.37 local addr 192.168.68.54 -> May 09 12:07:02 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:07:02 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:07:02 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:07:02 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:07:02 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:07:02 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:07:03 rivo ntpd[13332]: 172.105.204.105 local addr 192.168.68.54 -> May 09 12:07:05 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:07:05 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:07:05 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:07:05 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:07:05 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:07:05 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:07:08 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:07:08 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:07:08 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:07:08 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:07:09 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:07:09 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:07:11 rivo sudo[14366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 12:07:11 rivo sudo[14366]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 12:07:11 rivo sudo[14369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 12:07:11 rivo sudo[14366]: pam_unix(sudo:session): session closed for user root May 09 12:07:11 rivo sudo[14369]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 12:07:11 rivo sudo[14369]: pam_unix(sudo:session): session closed for user root May 09 12:07:11 rivo volumio[5428]: verbose: New Socket.io Connection to 192.168.68.54 from 192.168.68.53 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.4.1 Safari/605.1.15 Total Clients: 7 May 09 12:07:11 rivo volumio[5428]: verbose: New Socket.io Connection to 192.168.68.54 from 192.168.68.53 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.4.1 Safari/605.1.15 Total Clients: 8 May 09 12:07:12 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:07:12 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:07:12 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:07:12 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:07:12 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:07:12 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:07:12 rivo volumio[5428]: info: CoreCommandRouter::volumioGetVisibleSources May 09 12:07:12 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 12:07:12 rivo volumio[5428]: info: CoreCommandRouter::volumioGetState May 09 12:07:12 rivo volumio[5428]: info: CoreCommandRouter::volumioGetQueue May 09 12:07:12 rivo volumio[5428]: info: CoreStateMachine::getQueue May 09 12:07:12 rivo volumio[5428]: info: CorePlayQueue::getQueue May 09 12:07:12 rivo volumio[5428]: info: Listing playlists May 09 12:07:12 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 09 12:07:12 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 09 12:07:12 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 09 12:07:12 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 09 12:07:12 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 12:07:14 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 12:07:14 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 09 12:07:15 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:07:15 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:07:15 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:07:15 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:07:15 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:07:15 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:07:15 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 09 12:07:15 rivo volumio[5428]: info: Preload queue cleared May 09 12:07:17 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 09 12:07:17 rivo volumio[5428]: info: Preload queue cleared May 09 12:07:17 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 09 12:07:17 rivo volumio[5428]: info: Preload queue cleared May 09 12:07:17 rivo volumio[5428]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 09 12:07:18 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:07:18 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:07:18 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:07:18 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:07:18 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:07:18 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:07:18 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 12:07:18 rivo volumio[5428]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 09 12:07:18 rivo volumio[5428]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 09 12:07:18 rivo volumio[5428]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 09 12:07:19 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 09 12:07:19 rivo volumio[5428]: info: Preload queue cleared May 09 12:07:21 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:07:21 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:07:21 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:07:21 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:07:21 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:07:21 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:07:24 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:07:24 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:07:24 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:07:24 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:07:24 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:07:24 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:07:25 rivo volumio[5428]: info: Preload queue cleared May 09 12:07:25 rivo volumio[5428]: info: CoreCommandRouter::volumioReplaceandPlayItems May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::ClearQueue May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::stop May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::serviceStop May 09 12:07:25 rivo volumio[5428]: info: CoreCommandRouter::serviceStop May 09 12:07:25 rivo volumio[5428]: info: CorePlayQueue::clearPlayQueue May 09 12:07:25 rivo volumio[5428]: info: CorePlayQueue::saveQueue May 09 12:07:25 rivo volumio[5428]: info: CoreCommandRouter::volumioPushQueue May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::addQueueItems May 09 12:07:25 rivo volumio[5428]: info: CorePlayQueue::addQueueItems May 09 12:07:25 rivo volumio[5428]: info: Preload queue cleared May 09 12:07:25 rivo volumio[5428]: info: Adding Item to queue: upnp/folder/http://192.168.68.55:9050/dev0/srv1/control@0$1$12$10781 May 09 12:07:25 rivo volumio[5428]: info: Exploding uri upnp/folder/http://192.168.68.55:9050/dev0/srv1/control@0$1$12$10781 in service upnp_browser May 09 12:07:25 rivo volumio[5428]: info: CoreCommandRouter::volumioPushQueue May 09 12:07:25 rivo volumio[5428]: info: CorePlayQueue::saveQueue May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::updateTrackBlock May 09 12:07:25 rivo volumio[5428]: info: CorePlayQueue::getTrackBlock May 09 12:07:25 rivo volumio[5428]: info: CoreCommandRouter::volumioPlay May 09 12:07:25 rivo volumio[5428]: verbose: UNSET VOLATILE: Service: raat May 09 12:07:25 rivo volumio[5428]: info: RAAT Giving Up Control May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::play index 0 May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::stop May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::stPlaybackTimer May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::updateTrackBlock May 09 12:07:25 rivo volumio[5428]: info: CorePlayQueue::getTrackBlock May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:07:25 rivo volumio[5428]: info: CorePlayQueue::getTrack 0 May 09 12:07:25 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:07:25 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::serviceStop May 09 12:07:25 rivo volumio[5428]: info: CorePlayQueue::getTrack 0 May 09 12:07:25 rivo volumio[5428]: info: CoreCommandRouter::serviceStop May 09 12:07:25 rivo volumio[5428]: info: [1715224045681] ControllerUPNPBrowser::stop May 09 12:07:25 rivo volumio[5428]: verbose: ControllerMpd::sendMpdCommand stop May 09 12:07:25 rivo volumio[5428]: info: sendMpdCommand stop took 12 milliseconds May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::play index undefined May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 12:07:25 rivo volumio[5428]: info: CorePlayQueue::getTrack 0 May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::startPlaybackTimer May 09 12:07:25 rivo volumio[5428]: info: CorePlayQueue::getTrack 0 May 09 12:07:25 rivo volumio[5428]: info: [1715224045698] ControllerUPNPBrowser::clearAddPlayTrack May 09 12:07:25 rivo volumio[5428]: verbose: ControllerMpd::sendMpdCommand stop May 09 12:07:25 rivo volumio[5428]: info: sendMpdCommand stop took 15 milliseconds May 09 12:07:25 rivo volumio[5428]: verbose: ControllerMpd::sendMpdCommand clear May 09 12:07:25 rivo volumio[5428]: info: May 09 12:07:25 rivo volumio[5428]: ---------------------------- MPD announces system playlist update May 09 12:07:25 rivo volumio[5428]: info: Ignoring MPD Status Update May 09 12:07:25 rivo volumio[5428]: info: sendMpdCommand clear took 16 milliseconds May 09 12:07:25 rivo volumio[5428]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.68.55:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I1254667.aif" May 09 12:07:25 rivo volumio[5428]: error: updateQueue error: null May 09 12:07:25 rivo volumio[5428]: info: ------------------------------ 52ms May 09 12:07:25 rivo volumio[5428]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.68.55:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I1254667.aif" May 09 12:07:25 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:07:25 rivo volumio[5428]: info: CorePlayQueue::getTrack 0 May 09 12:07:25 rivo volumio[5428]: verbose: STATE SERVICE {"status":"pause","service":"","title":"The Four Seasons, Violin Concerto in F Minor, Op. 8 No. 4, RV 297 \"Winter\": III. Allegro","artist":"Anne‐Sophie Mutter / Wiener Philharmoniker / Antonio Vivaldi","album":"Vivaldi: Die vier Jahreszeiten","albumart":"/albumart","uri":"","trackType":"","seek":100000,"duration":196,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":false,"push_time":1715224044069,"random":false,"repeat":false,"repeatSingle":false,"stream":false,"codec":"","repeatMode":"all"} May 09 12:07:25 rivo volumio[5428]: verbose: CURRENT POSITION 0 May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::syncState stateService pause May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::syncState currentStatus stop May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:07:25 rivo volumio[5428]: info: CorePlayQueue::getTrack 0 May 09 12:07:25 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:07:25 rivo volumio[5428]: info: RAAT Giving Up Control: OK May 09 12:07:25 rivo volumio[5428]: info: May 09 12:07:25 rivo volumio[5428]: ---------------------------- MPD announces system playlist update May 09 12:07:25 rivo volumio[5428]: info: Ignoring MPD Status Update May 09 12:07:25 rivo volumio[5428]: info: sendMpdCommand add "http://192.168.68.55:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I1254667.aif" took 125 milliseconds May 09 12:07:25 rivo volumio[5428]: info: CoreStateMachine::setConsumeUpdateService mpd May 09 12:07:25 rivo volumio[5428]: verbose: ControllerMpd::sendMpdCommand play May 09 12:07:25 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:07:25 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:07:26 rivo volumio[5428]: info: ------------------------------ 86ms May 09 12:07:26 rivo volumio[5428]: info: sendMpdCommand play took 80 milliseconds May 09 12:07:26 rivo volumio[5428]: info: MCU Signalled Playback Inactive May 09 12:07:27 rivo volumio[5428]: info: May 09 12:07:27 rivo volumio[5428]: ---------------------------- MPD announces state update: player May 09 12:07:27 rivo volumio[5428]: info: ControllerMpd::getState May 09 12:07:27 rivo volumio[5428]: verbose: ControllerMpd::sendMpdCommand status May 09 12:07:27 rivo volumio[5428]: info: May 09 12:07:27 rivo volumio[5428]: ---------------------------- MPD announces state update: player May 09 12:07:27 rivo volumio[5428]: info: sendMpdCommand status took 155 milliseconds May 09 12:07:27 rivo volumio[5428]: info: ControllerMpd::getState May 09 12:07:27 rivo volumio[5428]: verbose: ControllerMpd::sendMpdCommand status May 09 12:07:27 rivo volumio[5428]: verbose: ControllerMpd::parseState May 09 12:07:27 rivo volumio[5428]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 12:07:27 rivo volumio[5428]: info: sendMpdCommand status took 6 milliseconds May 09 12:07:27 rivo volumio[5428]: info: sendMpdCommand playlistinfo took 4 milliseconds May 09 12:07:27 rivo volumio[5428]: verbose: ControllerMpd::parseState May 09 12:07:27 rivo volumio[5428]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 12:07:27 rivo volumio[5428]: verbose: ControllerMpd::parseTrackInfo May 09 12:07:27 rivo volumio[5428]: info: ControllerMpd::pushState May 09 12:07:27 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:07:27 rivo volumio[5428]: info: CorePlayQueue::getTrack 0 May 09 12:07:27 rivo volumio[5428]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I1254667.aif","artist":null,"album":null,"uri":"http://192.168.68.55:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I1254667.aif","trackType":"aif"} May 09 12:07:27 rivo volumio[5428]: verbose: CURRENT POSITION 0 May 09 12:07:27 rivo volumio[5428]: info: CoreStateMachine::syncState stateService play May 09 12:07:27 rivo volumio[5428]: info: CoreStateMachine::syncState currentStatus stop May 09 12:07:27 rivo volumio[5428]: info: ------------------------------ 178ms May 09 12:07:27 rivo volumio[5428]: info: sendMpdCommand playlistinfo took 12 milliseconds May 09 12:07:27 rivo volumio[5428]: verbose: ControllerMpd::parseTrackInfo May 09 12:07:27 rivo volumio[5428]: info: ControllerMpd::pushState May 09 12:07:27 rivo volumio[5428]: info: CoreCommandRouter::servicePushState May 09 12:07:27 rivo volumio[5428]: info: CorePlayQueue::getTrack 0 May 09 12:07:27 rivo volumio[5428]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":204,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I1254667.aif","artist":null,"album":null,"uri":"http://192.168.68.55:9050/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I1254667.aif","trackType":"aif"} May 09 12:07:27 rivo volumio[5428]: verbose: CURRENT POSITION 0 May 09 12:07:27 rivo volumio[5428]: info: CoreStateMachine::syncState stateService play May 09 12:07:27 rivo volumio[5428]: info: CoreStateMachine::syncState currentStatus play May 09 12:07:27 rivo volumio[5428]: info: Received an update from plugin. extracting info from payload May 09 12:07:27 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:07:27 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 12:07:27 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:07:27 rivo volumio[5428]: info: CoreStateMachine::pushState May 09 12:07:27 rivo volumio[5428]: info: CoreCommandRouter::volumioPushState May 09 12:07:27 rivo volumio[5428]: info: ------------------------------ 58ms May 09 12:07:27 rivo volumio[5428]: info: Signalling Playback active due to playback status change May 09 12:07:27 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:07:27 rivo volumio[5428]: info: Updating RAAT Signal Path May 09 12:07:27 rivo volumio[5428]: info: MCU Signalled Playback Active May 09 12:07:28 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 09 12:07:28 rivo volumio[5428]: info: Preload queue cleared May 09 12:07:30 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 09 12:07:30 rivo volumio[5428]: info: Preload queue cleared May 09 12:07:30 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 12:07:30 rivo volumio[5428]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 09 12:07:30 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 09 12:07:30 rivo volumio[5428]: info: Received Get System Version May 09 12:07:30 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 12:07:30 rivo volumio[5428]: info: Received Get System Info May 09 12:07:30 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 12:07:30 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 12:07:30 rivo volumio[5428]: info: Discovery: Getting this device information May 09 12:07:30 rivo volumio[5428]: info: CoreCommandRouter::volumioGetState May 09 12:07:30 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 12:07:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 09 12:07:32 rivo volumio[5428]: info: Preload queue cleared May 09 12:07:32 rivo volumio[5428]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 09 12:07:33 rivo volumio[5428]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 12:07:33 rivo volumio[5428]: TypeError: Cannot read property 'length' of undefined May 09 12:07:33 rivo volumio[5428]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 09 12:07:33 rivo volumio[5428]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 09 12:07:33 rivo volumio[5428]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 09 12:07:33 rivo volumio[5428]: at Parser.emit (events.js:400:28) May 09 12:07:33 rivo volumio[5428]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 09 12:07:33 rivo volumio[5428]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 09 12:07:33 rivo volumio[5428]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 09 12:07:33 rivo volumio[5428]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 09 12:07:33 rivo volumio[5428]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 09 12:07:33 rivo volumio[5428]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 09 12:07:33 rivo volumio[5428]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 09 12:07:33 rivo volumio[5428]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 09 12:07:33 rivo volumio[5428]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 09 12:07:33 rivo volumio[5428]: at IncomingMessage.emit (events.js:412:35) May 09 12:07:33 rivo volumio[5428]: at endReadableNT (internal/streams/readable.js:1333:12) May 09 12:07:33 rivo volumio[5428]: at processTicksAndRejections (internal/process/task_queues.js:82:21) May 09 12:07:33 rivo volumio[5428]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 12:07:34 rivo sudo[14430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 12:06 May 09 12:07:34 rivo sudo[14430]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e831866afe2db61aee9333a8f5ba9d9fd36609c7" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 18 Feb 2024 11:41:55 AM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b0437c7088d6645a661a49f6d9fa596a"