Mar 29 18:13:01 volumio volumio[1310]: info: CoreCommandRouter::Close All Modals sent Mar 29 18:13:23 volumio bluealsa[975]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_55_6B_54_8B_8E_A9, ...) Mar 29 18:13:23 volumio rfkill[3452]: unblock set for type bluetooth Mar 29 18:13:24 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:24.566Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.81:54176 Mar 29 18:13:25 volumio sudo[3459]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 18:13:25 volumio sudo[3457]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 18:13:25 volumio sudo[3459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:13:25 volumio sudo[3457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:13:25 volumio sudo[3457]: pam_unix(sudo:session): session closed for user root Mar 29 18:13:25 volumio sudo[3459]: pam_unix(sudo:session): session closed for user root Mar 29 18:13:25 volumio volumio[1310]: verbose: New Socket.io Connection to 192.168.1.99 from 192.168.1.81 UA: Mozilla/5.0 (Linux; Android 13; RMX3085 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 29 18:13:25 volumio sudo[3464]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 18:13:25 volumio sudo[3464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:13:25 volumio sudo[3466]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 18:13:25 volumio sudo[3466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:13:25 volumio sudo[3464]: pam_unix(sudo:session): session closed for user root Mar 29 18:13:25 volumio sudo[3466]: pam_unix(sudo:session): session closed for user root Mar 29 18:13:25 volumio volumio[1310]: verbose: New Socket.io Connection to 192.168.1.99 from 192.168.1.81 UA: Mozilla/5.0 (Linux; Android 13; RMX3085 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 29 18:13:25 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:26 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 18:13:26 volumio volumio[1310]: info: Received Get System Info Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:26 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:26 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:26 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:26 volumio volumio[1310]: info: Listing playlists Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 18:13:26 volumio volumio[1310]: verbose: New Socket.io Connection to 192.168.1.99 from 192.168.1.81 UA: Mozilla/5.0 (Linux; Android 13; RMX3085 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:26 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 18:13:26 volumio volumio[1310]: info: Received Get System Info Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:26 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:26 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:26 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:26 volumio volumio[1310]: info: Listing playlists Mar 29 18:13:26 volumio volumio[1310]: info: CoreCommandRouter::volumioGetQueue Mar 29 18:13:26 volumio volumio[1310]: info: CoreStateMachine::getQueue Mar 29 18:13:26 volumio volumio[1310]: info: CorePlayQueue::getQueue Mar 29 18:13:27 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 18:13:27 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:13:27 volumio volumio[1310]: info: Received Get System Info Mar 29 18:13:27 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:27 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:27 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:27 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:27 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:27 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:27 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 29 18:13:28 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 29 18:13:28 volumio volumio[1310]: info: MyVolumio login type: Token Mar 29 18:13:28 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:13:28 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:28.155Z level=INFO msg="emitting user changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" userId= Mar 29 18:13:28 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:13:28 volumio volumio[1310]: info: Received Get System Info Mar 29 18:13:28 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:28 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:28 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:28 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:28 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:28 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:28 volumio volumio[1310]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 29 18:13:29 volumio volumio[1310]: info: MyVolumio token set successfully Mar 29 18:13:29 volumio volumio[1310]: info: MYVOLUMIO: Adding device Mar 29 18:13:29 volumio volumio[1310]: info: MYVOLUMIO: Evaluating Server Mar 29 18:13:29 volumio volumio[1310]: info: MyVolumio status changed Mar 29 18:13:29 volumio volumio[1310]: info: Streaming services startup Mar 29 18:13:29 volumio volumio[1310]: info: Starting Streaming Daemon Mar 29 18:13:29 volumio volumio[1310]: info: Removing browser output: myVolumio user plan is not superstar Mar 29 18:13:29 volumio volumio[1310]: info: Removing audio output: Mar 29 18:13:29 volumio volumio[1310]: info: Stoppping Tunnel 1 Mar 29 18:13:29 volumio sudo[3494]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 29 18:13:29 volumio sudo[3496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 29 18:13:29 volumio sudo[3496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:13:29 volumio sudo[3494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:13:29 volumio sudo[3494]: pam_unix(sudo:session): session closed for user root Mar 29 18:13:29 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 29 18:13:29 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 29 18:13:29 volumio volumio[1310]: error: Cannot start Volumio Streaming Daemon Mar 29 18:13:29 volumio volumio[1310]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 29 18:13:29 volumio volumio[1310]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 29 18:13:29 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 29 18:13:29 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 29 18:13:29 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 29 18:13:29 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 29 18:13:29 volumio sudo[3496]: pam_unix(sudo:session): session closed for user root Mar 29 18:13:29 volumio volumio[1310]: info: Remote SSH Stopped Mar 29 18:13:30 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:13:30 volumio volumio[1310]: info: Received Get System Info Mar 29 18:13:30 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:30 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:30 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:30 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:30 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:30 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:30 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:13:30 volumio volumio[1310]: info: Received Get System Info Mar 29 18:13:30 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:30 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:30 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:30 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:30 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:30 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:13:31 volumio volumio[1310]: info: Received Get System Info Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:31 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:31 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:31 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:31 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:31 volumio volumio[1310]: verbose: New Socket.io Connection to 192.168.1.99:3000 from 192.168.1.81 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:13:31 volumio volumio[1310]: info: Received Get System Info Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:31 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:31 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:13:31 volumio volumio[1310]: info: Received Get System Info Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:31 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:31 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:31 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:34 volumio volumio[1310]: info: Setting Geolocation for MyVolumio to eu2 Mar 29 18:13:34 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:13:34 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:13:34 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:13:35 volumio volumio[1310]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 29 18:13:35 volumio volumio[1310]: info: Updating MyVolumio device info Mar 29 18:13:35 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:13:35 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:13:35 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:13:35 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 29 18:13:35 volumio volumio[1310]: info: MyVolumio login type: Token Mar 29 18:13:35 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 18:13:35 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:13:35 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 29 18:13:36 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:36.966Z level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=NvXv8Iz3QQfOTW1k54gLMhN9rgD2 tokenExpiry=2026-03-29T14:13:36.966Z Mar 29 18:13:37 volumio volumio[1310]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 29 18:13:37 volumio volumio[1310]: info: CoreCommandRouter::Close All Modals sent Mar 29 18:13:37 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Mar 29 18:13:37 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:37.985Z level=INFO msg="emitting user changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" userId=NvXv8Iz3QQfOTW1k54gLMhN9rgD2 Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:13:38 volumio volumio[1310]: info: Received Get System Info Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:38 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:38 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:13:38 volumio volumio[1310]: info: Received Get System Info Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:38 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:38 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:13:38 volumio volumio[1310]: info: Received Get System Info Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:38 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:38 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:13:38 volumio volumio[1310]: info: Received Get System Info Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:38 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:38 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:13:38 volumio volumio[1310]: info: Received Get System Info Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:38 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:38 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:38 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:39 volumio volumio[1310]: info: MYVOLUMIO: Adding device Mar 29 18:13:39 volumio volumio[1310]: info: MYVOLUMIO: Evaluating Server Mar 29 18:13:40 volumio volumio[1310]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 18:13:40 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 29 18:13:40 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:13:40 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 18:13:40 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:13:40 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:13:40 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:13:40 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:13:40 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:13:40 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:13:40 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:13:40 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 29 18:13:40 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 18:13:41 volumio volumio[1310]: info: MPD Database updated - AlbumList cache refreshed Mar 29 18:13:41 volumio volumio[1310]: info: Mar 29 18:13:41 volumio volumio[1310]: ---------------------------- MPD announces state update: database Mar 29 18:13:41 volumio volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Mar 29 18:13:41 volumio volumio[1310]: info: Mar 29 18:13:41 volumio volumio[1310]: ---------------------------- MPD announces state update: update Mar 29 18:13:41 volumio volumio[1310]: info: ControllerMpd::getState Mar 29 18:13:41 volumio volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Mar 29 18:13:41 volumio volumio[1310]: info: ControllerMpd::getState Mar 29 18:13:41 volumio volumio[1310]: verbose: ControllerMpd::sendMpdCommand status Mar 29 18:13:42 volumio volumio[1310]: info: sendMpdCommand status took 362 milliseconds Mar 29 18:13:42 volumio volumio[1310]: info: sendMpdCommand status took 362 milliseconds Mar 29 18:13:42 volumio volumio[1310]: info: sendMpdCommand status took 362 milliseconds Mar 29 18:13:42 volumio volumio[1310]: verbose: ControllerMpd::parseState Mar 29 18:13:42 volumio volumio[1310]: info: Command Router : Notfying DB Updatefalse Mar 29 18:13:42 volumio volumio[1310]: info: CoreCommandRouter::Close All Modals sent Mar 29 18:13:42 volumio volumio[1310]: verbose: ControllerMpd::parseState Mar 29 18:13:42 volumio volumio[1310]: verbose: ControllerMpd::parseState Mar 29 18:13:42 volumio volumio[1310]: info: ControllerMpd::pushState Mar 29 18:13:42 volumio volumio[1310]: info: CoreCommandRouter::servicePushState Mar 29 18:13:42 volumio volumio[1310]: info: CoreStateMachine::pushState Mar 29 18:13:42 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:42 volumio volumio[1310]: info: CoreCommandRouter::volumioPushState Mar 29 18:13:42 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:42 volumio volumio[1310]: 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} Mar 29 18:13:42 volumio volumio[1310]: verbose: CURRENT POSITION 0 Mar 29 18:13:42 volumio volumio[1310]: info: CoreStateMachine::syncState stateService stop Mar 29 18:13:42 volumio volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Mar 29 18:13:42 volumio volumio[1310]: info: CoreStateMachine::pushState Mar 29 18:13:42 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:42 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 18:13:42 volumio volumio[1310]: info: CoreCommandRouter::volumioPushState Mar 29 18:13:42 volumio volumio[1310]: info: No code Mar 29 18:13:42 volumio volumio[1310]: info: CoreStateMachine::pushState Mar 29 18:13:42 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:42 volumio volumio[1310]: info: CoreCommandRouter::volumioPushState Mar 29 18:13:42 volumio volumio[1310]: info: ControllerMpd::pushState Mar 29 18:13:42 volumio volumio[1310]: info: CoreCommandRouter::servicePushState Mar 29 18:13:42 volumio volumio[1310]: info: CoreStateMachine::pushState Mar 29 18:13:42 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:42 volumio volumio[1310]: info: CoreCommandRouter::volumioPushState Mar 29 18:13:42 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:42 volumio volumio[1310]: 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} Mar 29 18:13:42 volumio volumio[1310]: verbose: CURRENT POSITION 0 Mar 29 18:13:42 volumio volumio[1310]: info: CoreStateMachine::syncState stateService stop Mar 29 18:13:42 volumio volumio[1310]: info: CoreStateMachine::syncState currentStatus stop Mar 29 18:13:42 volumio volumio[1310]: info: CoreStateMachine::pushState Mar 29 18:13:42 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:42 volumio volumio[1310]: info: CoreCommandRouter::volumioPushState Mar 29 18:13:42 volumio volumio[1310]: info: No code Mar 29 18:13:42 volumio volumio[1310]: info: CoreStateMachine::pushState Mar 29 18:13:42 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:42 volumio volumio[1310]: info: CoreCommandRouter::volumioPushState Mar 29 18:13:42 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:42.184Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" state=STATUS_STOPPED positionMs=0 volume=41 Mar 29 18:13:42 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:42.184Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" state=STATUS_STOPPED positionMs=0 volume=41 Mar 29 18:13:42 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:42.184Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" state=STATUS_STOPPED positionMs=0 volume=41 Mar 29 18:13:42 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:42.184Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" state=STATUS_STOPPED positionMs=0 volume=41 Mar 29 18:13:42 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:42.185Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" state=STATUS_STOPPED positionMs=0 volume=41 Mar 29 18:13:42 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:42.185Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" state=STATUS_STOPPED positionMs=0 volume=41 Mar 29 18:13:42 volumio volumio[1310]: info: ------------------------------ 381ms Mar 29 18:13:42 volumio volumio[1310]: info: ------------------------------ 382ms Mar 29 18:13:42 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:42.192Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" id= title= Mar 29 18:13:42 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:42.192Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" id= title= Mar 29 18:13:42 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:42.192Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" id= title= Mar 29 18:13:42 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:42.192Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" id= title= Mar 29 18:13:42 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:42.192Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" id= title= Mar 29 18:13:42 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:42.192Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" id= title= Mar 29 18:13:44 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 18:13:44 volumio volumio[1310]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 18:13:44 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 18:13:44 volumio volumio[1310]: info: Received Get System Version Mar 29 18:13:44 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 18:13:44 volumio volumio[1310]: info: Received Get System Info Mar 29 18:13:44 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:44 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:44 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:44 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:44 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:44 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:44 volumio volumio[1310]: info: Setting Geolocation for MyVolumio to eu2 Mar 29 18:13:44 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:13:44 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:13:44 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:13:45 volumio volumio[1310]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 29 18:13:45 volumio volumio[1310]: info: Updating MyVolumio device info Mar 29 18:13:45 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:13:45 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:13:45 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:13:46 volumio volumio[1310]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 29 18:13:52 volumio volumio[1310]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 29 18:13:52 volumio volumio[1310]: info: Preparing to save Alsa Options, stopping services first Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:52 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::volumioPause Mar 29 18:13:52 volumio volumio[1310]: info: CoreStateMachine::pause Mar 29 18:13:52 volumio volumio[1310]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"E50 II"}} Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 18:13:52 volumio volumio[1310]: info: Setting mixer E50 II for card E50 II Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 29 18:13:52 volumio volumio[1310]: info: Updating Volume Controller Parameters: Device: 5 Name: E50 II Mixer: E50 II Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 29 18:13:52 volumio volumio[1310]: info: Disabling external Volume Control Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 18:13:52 volumio volumio[1310]: info: Preparing to generate the ALSA configuration file Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 18:13:52 volumio volumio[1310]: info: VolumeController:: Volume=100 Mute =false Mar 29 18:13:52 volumio volumio[1310]: info: CoreStateMachine::pushState Mar 29 18:13:52 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 18:13:52 volumio volumio[1310]: info: CoreCommandRouter::volumioPushState Mar 29 18:13:52 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:52.999Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 18:13:53 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:53.000Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" id= title= Mar 29 18:13:53 volumio volumio[1310]: info: Asound.conf file written Mar 29 18:13:53 volumio sudo[3652]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 29 18:13:53 volumio sudo[3652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:13:53 volumio sudo[3652]: pam_unix(sudo:session): session closed for user root Mar 29 18:13:53 volumio volumio[1310]: No state is present for card PCH Mar 29 18:13:53 volumio volumio[1310]: Found hardware: "HDA-Intel" "Realtek Generic" "HDA:10ec0255,102807a1,00100002 HDA:8086280b,80860101,00100000" "0x1028" "0x07a1" Mar 29 18:13:53 volumio volumio[1310]: Hardware is initialized using a generic method Mar 29 18:13:53 volumio volumio[1310]: No state is present for card PCH Mar 29 18:13:53 volumio volumio[1310]: No state is present for card II Mar 29 18:13:53 volumio volumio[1310]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:8750" "" "" Mar 29 18:13:53 volumio volumio[1310]: Hardware is initialized using a generic method Mar 29 18:13:53 volumio volumio[1310]: No state is present for card II Mar 29 18:13:53 volumio volumio[1310]: info: Output device has changed, restarting MPD Mar 29 18:13:53 volumio volumio[1310]: info: Output device has changed, restarting Shairport Sync Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:13:53 volumio sudo[3658]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 18:13:53 volumio sudo[3658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:13:53 volumio volumio[1310]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 18:13:53 volumio volumio[1310]: info: Output device has changed, restarting MPD Mar 29 18:13:53 volumio sudo[3658]: pam_unix(sudo:session): session closed for user root Mar 29 18:13:53 volumio sudo[3662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 18:13:53 volumio sudo[3662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:13:53 volumio sudo[3667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 18:13:53 volumio sudo[3667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:13:53 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 29 18:13:53 volumio sudo[3667]: pam_unix(sudo:session): session closed for user root Mar 29 18:13:53 volumio volumio[1310]: info: Output device has changed, restarting Shairport Sync Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:13:53 volumio sudo[3671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 18:13:53 volumio sudo[3671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:13:53 volumio volumio[1310]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 18:13:53 volumio volumio[1310]: info: MPD Permissions set Mar 29 18:13:53 volumio volumio[1310]: info: MPD Permissions set Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:13:53 volumio volumio[1310]: info: Starting Shairport Sync Mar 29 18:13:53 volumio volumio[1310]: info: Starting Shairport Sync Mar 29 18:13:53 volumio sudo[3696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 18:13:53 volumio sudo[3696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:13:53 volumio sudo[3697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 18:13:53 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 29 18:13:53 volumio sudo[3697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:13:53 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 29 18:13:53 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 18:13:53 volumio systemd[1]: shairport-sync.service: Consumed 1.957s CPU time. Mar 29 18:13:53 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 18:13:53 volumio sudo[3696]: pam_unix(sudo:session): session closed for user root Mar 29 18:13:53 volumio volumio[1310]: info: Shairport-Sync Started Mar 29 18:13:53 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 29 18:13:53 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 29 18:13:53 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 18:13:53 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 18:13:53 volumio sudo[3697]: pam_unix(sudo:session): session closed for user root Mar 29 18:13:53 volumio volumio[1310]: info: Shairport-Sync Started Mar 29 18:13:53 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 29 18:13:53 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 29 18:13:53 volumio systemd[1]: mpd.service: Consumed 13.351s CPU time. Mar 29 18:13:53 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 29 18:13:53 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 29 18:13:53 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 29 18:13:53 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 29 18:13:53 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 29 18:13:53 volumio sudo[3719]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 18:13:53 volumio sudo[3719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:13:53 volumio sudo[3721]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 18:13:53 volumio sudo[3721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:13:53 volumio sudo[3719]: pam_unix(sudo:session): session closed for user root Mar 29 18:13:53 volumio sudo[3721]: pam_unix(sudo:session): session closed for user root Mar 29 18:13:53 volumio volumio[1310]: verbose: New Socket.io Connection to 192.168.1.99 from 192.168.1.81 UA: Mozilla/5.0 (Linux; Android 13; RMX3085 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 29 18:13:53 volumio sudo[3717]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 29 18:13:53 volumio sudo[3717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 29 18:13:53 volumio sudo[3717]: pam_unix(sudo:session): session closed for user root Mar 29 18:13:53 volumio sudo[3727]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 18:13:53 volumio sudo[3727]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:13:53 volumio sudo[3727]: pam_unix(sudo:session): session closed for user root Mar 29 18:13:53 volumio sudo[3729]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 18:13:53 volumio sudo[3729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:13:53 volumio sudo[3729]: pam_unix(sudo:session): session closed for user root Mar 29 18:13:53 volumio volumio[1310]: verbose: New Socket.io Connection to 192.168.1.99 from 192.168.1.81 UA: Mozilla/5.0 (Linux; Android 13; RMX3085 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 18:13:53 volumio mpd[3725]: 2026-03-29T18:13:53 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 29 18:13:53 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 29 18:13:53 volumio sudo[3662]: pam_unix(sudo:session): session closed for user root Mar 29 18:13:53 volumio sudo[3671]: pam_unix(sudo:session): session closed for user root Mar 29 18:13:53 volumio volumio[1310]: error: MPD error: The expression evaluated to a falsy value: Mar 29 18:13:53 volumio volumio[1310]: assert.ok(self.idling) Mar 29 18:13:53 volumio volumio[1310]: error: The expression evaluated to a falsy value: Mar 29 18:13:53 volumio volumio[1310]: assert.ok(self.idling) Mar 29 18:13:53 volumio volumio[1310]: error: updateQueue error: null Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:53 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 18:13:53 volumio volumio[1310]: info: Received Get System Info Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:53 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:53 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:53 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:53 volumio volumio[1310]: info: Listing playlists Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 18:13:53 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 18:13:55 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 29 18:13:55 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:13:55 volumio volumio[1310]: info: Received Get System Info Mar 29 18:13:55 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:55 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:55 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:55 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:55 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:55 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:56 volumio kernel: usb 1-5: new high-speed USB device number 7 using xhci_hcd Mar 29 18:13:56 volumio kernel: usb 1-5: New USB device found, idVendor=2622, idProduct=0061, bcdDevice= 1.58 Mar 29 18:13:56 volumio kernel: usb 1-5: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Mar 29 18:13:56 volumio kernel: usb 1-5: Product: LEAK USB Audio 2.0 Mar 29 18:13:56 volumio kernel: usb 1-5: Manufacturer: LEAK Mar 29 18:13:56 volumio kernel: usb 1-5: SerialNumber: 0 Mar 29 18:13:56 volumio kernel: hid-generic 0003:2622:0061.0008: hiddev2,hidraw5: USB HID v1.10 Device [LEAK LEAK USB Audio 2.0] on usb-0000:00:14.0-5/input3 Mar 29 18:13:56 volumio kernel: input: LEAK LEAK USB Audio 2.0 as /devices/pci0000:00/0000:00:14.0/usb1/1-5/1-5:1.4/0003:2622:0061.0009/input/input25 Mar 29 18:13:56 volumio kernel: hid-generic 0003:2622:0061.0009: input,hidraw6: USB HID v1.10 Device [LEAK LEAK USB Audio 2.0] on usb-0000:00:14.0-5/input4 Mar 29 18:13:56 volumio (udev-worker)[3738]: controlC1: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 1' failed with exit code 99. Mar 29 18:13:56 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:13:56 volumio volumio[1310]: info: Received Get System Info Mar 29 18:13:56 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:13:56 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:13:56 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:13:56 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:13:56 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:56 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:13:56 volumio volumio[1310]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 29 18:13:56 volumio volumio[1310]: info: CoreStateMachine::getcurrentVolume Mar 29 18:13:56 volumio volumio[1310]: info: CoreCommandRouter::volumioRetrievevolume Mar 29 18:13:56 volumio volumio[1310]: info: VolumeController:: Volume=100 Mute =false Mar 29 18:13:56 volumio volumio[1310]: info: CoreStateMachine::pushState Mar 29 18:13:56 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:56 volumio volumio[1310]: info: CoreCommandRouter::volumioPushState Mar 29 18:13:56 volumio volumio[1310]: info: CoreStateMachine::updateTrackBlock Mar 29 18:13:56 volumio volumio[1310]: info: CorePlayQueue::getTrackBlock Mar 29 18:13:56 volumio volumio[1310]: info: CoreCommandRouter::volumioRetrievevolume Mar 29 18:13:56 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:56.991Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 18:13:56 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:56.991Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" id= title= Mar 29 18:13:56 volumio volumio[1310]: info: VolumeController:: Volume=100 Mute =false Mar 29 18:13:56 volumio volumio[1310]: info: CoreStateMachine::pushState Mar 29 18:13:56 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:13:56 volumio volumio[1310]: info: CoreCommandRouter::volumioPushState Mar 29 18:13:57 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:57.001Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 18:13:57 volumio volumio5-onboarding[1840]: time=2026-03-29T13:13:57.001Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.81:54176 @ 0xc00010c9f0" id= title= Mar 29 18:14:03 volumio volumio[1310]: info: CoreCommandRouter::volumioGetQueue Mar 29 18:14:03 volumio volumio[1310]: info: CoreStateMachine::getQueue Mar 29 18:14:03 volumio volumio[1310]: info: CorePlayQueue::getQueue Mar 29 18:14:03 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 18:14:07 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 18:14:07 volumio volumio[1310]: info: CURURI: music-library Mar 29 18:14:07 volumio volumio[1310]: info: Preload queue cleared Mar 29 18:14:08 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 18:14:08 volumio volumio[1310]: info: CURURI: music-library/USB Mar 29 18:14:08 volumio volumio[1310]: info: Preload queue cleared Mar 29 18:14:09 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 18:14:09 volumio volumio[1310]: info: CURURI: music-library/USB/Новый_том Mar 29 18:14:09 volumio volumio[1310]: info: Preload queue cleared Mar 29 18:14:11 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 18:14:11 volumio volumio[1310]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 18:14:11 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 18:14:11 volumio volumio[1310]: info: Received Get System Version Mar 29 18:14:11 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 18:14:11 volumio volumio[1310]: info: Received Get System Info Mar 29 18:14:11 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:14:11 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:14:11 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:14:11 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:14:11 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:14:11 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:14:12 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 18:14:12 volumio volumio[1310]: info: CURURI: music-library/USB/Новый_том/Зарубежный ROCK Mar 29 18:14:12 volumio volumio[1310]: info: Preload queue cleared Mar 29 18:14:17 volumio volumio5-onboarding[1840]: time=2026-03-29T13:14:17.303Z level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Mar 29 18:14:17 volumio sudo[3822]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 18:14:17 volumio sudo[3822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:14:17 volumio sudo[3824]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 18:14:17 volumio sudo[3822]: pam_unix(sudo:session): session closed for user root Mar 29 18:14:17 volumio sudo[3824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:14:17 volumio sudo[3824]: pam_unix(sudo:session): session closed for user root Mar 29 18:14:17 volumio volumio[1310]: verbose: New Socket.io Connection to 192.168.1.99 from 192.168.1.81 UA: Mozilla/5.0 (Linux; Android 13; RMX3085 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 29 18:14:17 volumio dbus-daemon[917]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.16" (uid=0 pid=1840 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.0" (uid=0 pid=916 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 29 18:14:17 volumio sudo[3832]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 18:14:17 volumio sudo[3832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:14:17 volumio sudo[3832]: pam_unix(sudo:session): session closed for user root Mar 29 18:14:17 volumio sudo[3834]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 18:14:17 volumio sudo[3834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:14:17 volumio sudo[3834]: pam_unix(sudo:session): session closed for user root Mar 29 18:14:17 volumio volumio[1310]: verbose: New Socket.io Connection to 192.168.1.99 from 192.168.1.81 UA: Mozilla/5.0 (Linux; Android 13; RMX3085 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Mar 29 18:14:18 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 18:14:18 volumio volumio[1310]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 18:14:18 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:14:18 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:14:18 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:14:18 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 18:14:18 volumio volumio[1310]: info: CoreCommandRouter::volumioGetQueue Mar 29 18:14:18 volumio volumio[1310]: info: CoreStateMachine::getQueue Mar 29 18:14:18 volumio volumio[1310]: info: CorePlayQueue::getQueue Mar 29 18:14:18 volumio volumio[1310]: info: Listing playlists Mar 29 18:14:18 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 18:14:18 volumio volumio[1310]: info: Received Get System Info Mar 29 18:14:18 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:14:18 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:14:18 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:14:18 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:14:18 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:14:18 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:14:18 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:14:18 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:14:18 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 18:14:18 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 18:14:18 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 18:14:18 volumio sudo[3838]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 18:14:18 volumio sudo[3838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:14:18 volumio sudo[3838]: pam_unix(sudo:session): session closed for user root Mar 29 18:14:18 volumio sudo[3840]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 18:14:18 volumio sudo[3840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:14:18 volumio sudo[3840]: pam_unix(sudo:session): session closed for user root Mar 29 18:14:18 volumio volumio[1310]: verbose: New Socket.io Connection to 192.168.1.99 from 192.168.1.81 UA: Mozilla/5.0 (Linux; Android 13; RMX3085 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Mar 29 18:14:18 volumio sudo[3845]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 18:14:18 volumio sudo[3845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:14:18 volumio sudo[3847]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 18:14:18 volumio sudo[3845]: pam_unix(sudo:session): session closed for user root Mar 29 18:14:18 volumio sudo[3847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 18:14:18 volumio sudo[3847]: pam_unix(sudo:session): session closed for user root Mar 29 18:14:18 volumio volumio[1310]: verbose: New Socket.io Connection to 192.168.1.99 from 192.168.1.81 UA: Mozilla/5.0 (Linux; Android 13; RMX3085 Build/SP1A.210812.016; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Mar 29 18:14:19 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 18:14:19 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 18:14:19 volumio volumio[1310]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 18:14:19 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:14:19 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:14:19 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:14:19 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 18:14:19 volumio volumio[1310]: info: CoreCommandRouter::volumioGetQueue Mar 29 18:14:19 volumio volumio[1310]: info: CoreStateMachine::getQueue Mar 29 18:14:19 volumio volumio[1310]: info: CorePlayQueue::getQueue Mar 29 18:14:19 volumio volumio[1310]: info: Listing playlists Mar 29 18:14:19 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 18:14:19 volumio volumio[1310]: info: Received Get System Info Mar 29 18:14:19 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:14:19 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:14:19 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:14:19 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:14:19 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:14:19 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:14:19 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:14:19 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:14:19 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 18:14:19 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 18:14:20 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:14:20 volumio volumio[1310]: info: Received Get System Info Mar 29 18:14:20 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:14:20 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:14:20 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:14:20 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:14:20 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:14:20 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:14:20 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 29 18:14:20 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:14:20 volumio volumio[1310]: info: Received Get System Info Mar 29 18:14:20 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:14:20 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:14:20 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:14:20 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:14:20 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:14:20 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:14:20 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 29 18:14:21 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 29 18:14:21 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:14:21 volumio volumio[1310]: info: Received Get System Info Mar 29 18:14:21 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:14:21 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:14:21 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:14:21 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:14:21 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:14:21 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:14:24 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:14:24 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:14:24 volumio volumio[1310]: info: Received Get System Info Mar 29 18:14:24 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:14:24 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:14:24 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:14:24 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:14:24 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:14:24 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:14:24 volumio volumio[1310]: info: Received Get System Info Mar 29 18:14:24 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:14:24 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:14:24 volumio volumio[1310]: info: Discovery: Getting this device information Mar 29 18:14:24 volumio volumio[1310]: info: CoreCommandRouter::volumioGetState Mar 29 18:14:24 volumio volumio[1310]: info: CorePlayQueue::getTrack 0 Mar 29 18:14:24 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:14:28 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 18:14:28 volumio volumio[1310]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 18:14:32 volumio volumio[1310]: info: Preload queue cleared Mar 29 18:14:32 volumio volumio[1310]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 18:14:32 volumio volumio[1310]: info: CoreStateMachine::ClearQueue Mar 29 18:14:32 volumio volumio[1310]: info: CoreStateMachine::stop Mar 29 18:14:32 volumio volumio[1310]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 18:14:32 volumio volumio[1310]: info: CorePlayQueue::clearPlayQueue Mar 29 18:14:32 volumio volumio[1310]: info: CorePlayQueue::saveQueue Mar 29 18:14:32 volumio volumio[1310]: info: CoreCommandRouter::volumioPushQueue Mar 29 18:14:32 volumio volumio[1310]: info: CoreStateMachine::addQueueItems Mar 29 18:14:32 volumio volumio[1310]: info: CorePlayQueue::addQueueItems Mar 29 18:14:32 volumio volumio[1310]: info: Preload queue cleared Mar 29 18:14:32 volumio volumio[1310]: info: Adding Item to queue: music-library/USB/Новый_том/Зарубежный ROCK/Def Leppard – Pyromania [LP] 1983 Mar 29 18:14:32 volumio volumio[1310]: info: Exploding uri music-library/USB/Новый_том/Зарубежный ROCK/Def Leppard – Pyromania [LP] 1983 in service mpd Mar 29 18:14:32 volumio volumio[1310]: info: ALBUMART /albumart?cacheid=694&web=/Pyromania/extralarge&path=%2Fmnt%2FUSB%2F%D0%9D%D0%BE%D0%B2%D1%8B%D0%B9_%D1%82%D0%BE%D0%BC%2F%D0%97%D0%B0%D1%80%D1%83%D0%B1%D0%B5%D0%B6%D0%BD%D1%8B%D0%B9%20ROCK%2FDef%20Leppard%20%E2%80%93%20Pyromania%20%5BLP%5D%201983%2FDef%20Leppard%20%E2%80%93%20Pyromania%20%5BLP%5D%201983.cue&metadata=false Mar 29 18:14:32 volumio volumio[1310]: info: URI /mnt/USB/Новый_том/Зарубежный ROCK/Def Leppard – Pyromania [LP] 1983/Def Leppard – Pyromania [LP] 1983.cue Mar 29 18:14:32 volumio volumio[1310]: info: ALBUMART /albumart?cacheid=694&web=/Pyromania/extralarge&path=%2Fmnt%2FUSB%2F%D0%9D%D0%BE%D0%B2%D1%8B%D0%B9_%D1%82%D0%BE%D0%BC%2F%D0%97%D0%B0%D1%80%D1%83%D0%B1%D0%B5%D0%B6%D0%BD%D1%8B%D0%B9%20ROCK%2FDef%20Leppard%20%E2%80%93%20Pyromania%20%5BLP%5D%201983%2FDef%20Leppard%20%E2%80%93%20Pyromania%20%5BLP%5D%201983.cue&metadata=false Mar 29 18:14:32 volumio volumio[1310]: info: URI /mnt/USB/Новый_том/Зарубежный ROCK/Def Leppard – Pyromania [LP] 1983/Def Leppard – Pyromania [LP] 1983.cue Mar 29 18:14:32 volumio volumio[1310]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 18:14:32 volumio volumio[1310]: Error: Unable to resolve or reject the same promise twice Mar 29 18:14:32 volumio volumio[1310]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 29 18:14:32 volumio volumio[1310]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Mar 29 18:14:32 volumio volumio[1310]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 29 18:14:32 volumio volumio[1310]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 29 18:14:32 volumio volumio[1310]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 29 18:14:32 volumio volumio[1310]: at Socket.emit (node:events:514:28) Mar 29 18:14:32 volumio volumio[1310]: at addChunk (node:internal/streams/readable:343:12) Mar 29 18:14:32 volumio volumio[1310]: at readableAddChunk (node:internal/streams/readable:312:11) Mar 29 18:14:32 volumio volumio[1310]: at Readable.push (node:internal/streams/readable:253:10) Mar 29 18:14:32 volumio volumio[1310]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Mar 29 18:14:32 volumio volumio[1310]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 18:14:32 volumio sudo[3908]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-29 18:13' Mar 29 18:14:32 volumio sudo[3908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"