Mar 29 23:05:10 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 23:05:10 volumio volumio[17924]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 23:05:10 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 23:05:10 volumio volumio[17924]: info: Received Get System Version Mar 29 23:05:10 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 23:05:10 volumio volumio[17924]: info: Received Get System Info Mar 29 23:05:10 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:05:10 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:05:10 volumio volumio[17924]: info: Discovery: Getting this device information Mar 29 23:05:10 volumio volumio[17924]: info: CoreCommandRouter::volumioGetState Mar 29 23:05:10 volumio volumio[17924]: info: CorePlayQueue::getTrack 0 Mar 29 23:05:10 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:05:15 volumio volumio[17924]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.38 UA: Mozilla/5.0 (Linux; Android 13; SM-G781B Build/TP1A.220624.014; 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 23:05:15 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 23:05:15 volumio volumio[17924]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 23:05:15 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 23:05:15 volumio volumio[17924]: info: CoreCommandRouter::volumioGetState Mar 29 23:05:15 volumio volumio[17924]: info: CorePlayQueue::getTrack 0 Mar 29 23:05:15 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 23:05:15 volumio volumio[17924]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 23:05:15 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 23:05:15 volumio volumio[17924]: info: Received Get System Info Mar 29 23:05:15 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:05:15 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:05:15 volumio volumio[17924]: info: Discovery: Getting this device information Mar 29 23:05:15 volumio volumio[17924]: info: CoreCommandRouter::volumioGetState Mar 29 23:05:15 volumio volumio[17924]: info: CorePlayQueue::getTrack 0 Mar 29 23:05:15 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:05:15 volumio volumio[17924]: info: CoreCommandRouter::volumioGetState Mar 29 23:05:15 volumio volumio[17924]: info: CorePlayQueue::getTrack 0 Mar 29 23:05:15 volumio volumio[17924]: info: Listing playlists Mar 29 23:05:15 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 23:05:15 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 23:05:15 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 23:05:17 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 29 23:05:17 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 23:05:17 volumio volumio[17924]: info: Received Get System Info Mar 29 23:05:17 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:05:17 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:05:17 volumio volumio[17924]: info: Discovery: Getting this device information Mar 29 23:05:17 volumio volumio[17924]: info: CoreCommandRouter::volumioGetState Mar 29 23:05:17 volumio volumio[17924]: info: CorePlayQueue::getTrack 0 Mar 29 23:05:17 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:05:17 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 23:05:17 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 29 23:05:18 volumio go-librespot[18128]: time="2026-03-29T23:05:18+05:30" level=trace msg="received accesspoint ping" Mar 29 23:05:18 volumio go-librespot[18128]: time="2026-03-29T23:05:18+05:30" level=trace msg="received accesspoint pong ack" Mar 29 23:05:18 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 23:05:18 volumio volumio[17924]: info: Received Get System Info Mar 29 23:05:18 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:05:18 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:05:18 volumio volumio[17924]: info: Discovery: Getting this device information Mar 29 23:05:18 volumio volumio[17924]: info: CoreCommandRouter::volumioGetState Mar 29 23:05:18 volumio volumio[17924]: info: CorePlayQueue::getTrack 0 Mar 29 23:05:18 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:05:19 volumio go-librespot[18128]: time="2026-03-29T23:05:19+05:30" level=trace msg="sent dealer ping" Mar 29 23:05:19 volumio go-librespot[18128]: time="2026-03-29T23:05:19+05:30" level=trace msg="received dealer pong" Mar 29 23:05:25 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 23:05:33 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 23:05:33 volumio volumio[17924]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 23:05:33 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 23:05:33 volumio volumio[17924]: info: Received Get System Version Mar 29 23:05:33 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 23:05:33 volumio volumio[17924]: info: Received Get System Info Mar 29 23:05:33 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:05:33 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:05:33 volumio volumio[17924]: info: Discovery: Getting this device information Mar 29 23:05:33 volumio volumio[17924]: info: CoreCommandRouter::volumioGetState Mar 29 23:05:33 volumio volumio[17924]: info: CorePlayQueue::getTrack 0 Mar 29 23:05:33 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:05:42 volumio volumio[17924]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 23:05:49 volumio go-librespot[18128]: time="2026-03-29T23:05:49+05:30" level=trace msg="sent dealer ping" Mar 29 23:05:49 volumio go-librespot[18128]: time="2026-03-29T23:05:49+05:30" level=trace msg="received dealer pong" Mar 29 23:06:01 volumio volumio[17924]: info: CALLMETHOD: music_service ytmusic configSaveI18n [object Object] Mar 29 23:06:01 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: ytmusic , configSaveI18n Mar 29 23:06:01 volumio volumio[17924]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 23:06:19 volumio go-librespot[18128]: time="2026-03-29T23:06:19+05:30" level=trace msg="sent dealer ping" Mar 29 23:06:19 volumio go-librespot[18128]: time="2026-03-29T23:06:19+05:30" level=trace msg="received dealer pong" Mar 29 23:06:21 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 23:06:21 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 29 23:06:22 volumio volumio[17924]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 23:06:24 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 23:06:24 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 29 23:06:26 volumio volumio[17924]: info: CoreCommandRouter::volumioPlay Mar 29 23:06:26 volumio volumio[17924]: info: CoreStateMachine::play index undefined Mar 29 23:06:26 volumio volumio[17924]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 23:06:26 volumio volumio[17924]: info: CorePlayQueue::getTrack 0 Mar 29 23:06:26 volumio volumio[17924]: info: CoreStateMachine::startPlaybackTimer Mar 29 23:06:26 volumio volumio[17924]: info: CorePlayQueue::getTrack 0 Mar 29 23:06:26 volumio volumio[17924]: info: [1774805786617] ControllerSpotify::clearAddPlayTrack Mar 29 23:06:26 volumio volumio[17924]: info: Sending Spotify command with payload to local API: /player/play Mar 29 23:06:26 volumio go-librespot[18128]: time="2026-03-29T23:06:26+05:30" level=debug msg="resolved context of track" uri="spotify:track:4PhoP49dtLjz2BnLP6tgQ1" Mar 29 23:06:26 volumio go-librespot[18128]: time="2026-03-29T23:06:26+05:30" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:4PhoP49dtLjz2BnLP6tgQ1" Mar 29 23:06:26 volumio go-librespot[18128]: time="2026-03-29T23:06:26+05:30" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:4PhoP49dtLjz2BnLP6tgQ1" Mar 29 23:06:27 volumio go-librespot[18128]: time="2026-03-29T23:06:27+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 29 23:06:27 volumio go-librespot[18128]: time="2026-03-29T23:06:27+05:30" level=trace msg="emitting websocket event: will_play" Mar 29 23:06:27 volumio volumio[17924]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:4PhoP49dtLjz2BnLP6tgQ1","uri":"spotify:track:4PhoP49dtLjz2BnLP6tgQ1","play_origin":"go-librespot"}} Mar 29 23:06:27 volumio go-librespot[18128]: time="2026-03-29T23:06:27+05:30" level=debug msg="selected format OGG_VORBIS_320 (81eccb3b75c9a19072c39c04c4adb340cc4ea50f)" uri="spotify:track:4PhoP49dtLjz2BnLP6tgQ1" Mar 29 23:06:27 volumio go-librespot[18128]: time="2026-03-29T23:06:27+05:30" level=debug msg="requested aes key for file 81eccb3b75c9a19072c39c04c4adb340cc4ea50f, gid: 4PhoP49dtLjz2BnLP6tgQ1" Mar 29 23:06:28 volumio go-librespot[18128]: time="2026-03-29T23:06:28+05:30" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:4PhoP49dtLjz2BnLP6tgQ1: failed retrieving audio key: failed retrieving aes key with code 1" Mar 29 23:06:28 volumio volumio[17924]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Mar 29 23:06:29 volumio volumio[17924]: info: CoreCommandRouter::volumioPlay Mar 29 23:06:29 volumio volumio[17924]: info: CoreStateMachine::play index undefined Mar 29 23:06:29 volumio volumio[17924]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 23:06:29 volumio volumio[17924]: info: CorePlayQueue::getTrack 0 Mar 29 23:06:29 volumio volumio[17924]: info: CoreStateMachine::startPlaybackTimer Mar 29 23:06:29 volumio volumio[17924]: info: CorePlayQueue::getTrack 0 Mar 29 23:06:29 volumio volumio[17924]: info: [1774805789030] ControllerSpotify::clearAddPlayTrack Mar 29 23:06:29 volumio volumio[17924]: info: Sending Spotify command with payload to local API: /player/play Mar 29 23:06:29 volumio go-librespot[18128]: time="2026-03-29T23:06:29+05:30" level=debug msg="resolved context of track" uri="spotify:track:4PhoP49dtLjz2BnLP6tgQ1" Mar 29 23:06:29 volumio go-librespot[18128]: time="2026-03-29T23:06:29+05:30" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:4PhoP49dtLjz2BnLP6tgQ1" Mar 29 23:06:29 volumio go-librespot[18128]: time="2026-03-29T23:06:29+05:30" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:4PhoP49dtLjz2BnLP6tgQ1" Mar 29 23:06:29 volumio go-librespot[18128]: time="2026-03-29T23:06:29+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 29 23:06:29 volumio go-librespot[18128]: time="2026-03-29T23:06:29+05:30" level=trace msg="emitting websocket event: will_play" Mar 29 23:06:29 volumio volumio[17924]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:4PhoP49dtLjz2BnLP6tgQ1","uri":"spotify:track:4PhoP49dtLjz2BnLP6tgQ1","play_origin":"go-librespot"}} Mar 29 23:06:29 volumio go-librespot[18128]: time="2026-03-29T23:06:29+05:30" level=debug msg="selected format OGG_VORBIS_320 (81eccb3b75c9a19072c39c04c4adb340cc4ea50f)" uri="spotify:track:4PhoP49dtLjz2BnLP6tgQ1" Mar 29 23:06:29 volumio go-librespot[18128]: time="2026-03-29T23:06:29+05:30" level=debug msg="requested aes key for file 81eccb3b75c9a19072c39c04c4adb340cc4ea50f, gid: 4PhoP49dtLjz2BnLP6tgQ1" Mar 29 23:06:29 volumio go-librespot[18128]: time="2026-03-29T23:06:29+05:30" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:4PhoP49dtLjz2BnLP6tgQ1: failed retrieving audio key: failed retrieving aes key with code 1" Mar 29 23:06:29 volumio volumio[17924]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Mar 29 23:06:29 volumio volumio[17924]: info: CoreCommandRouter::volumioPlay Mar 29 23:06:29 volumio volumio[17924]: info: CoreStateMachine::play index undefined Mar 29 23:06:29 volumio volumio[17924]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 23:06:29 volumio volumio[17924]: info: CorePlayQueue::getTrack 0 Mar 29 23:06:29 volumio volumio[17924]: info: CoreStateMachine::startPlaybackTimer Mar 29 23:06:29 volumio volumio[17924]: info: CorePlayQueue::getTrack 0 Mar 29 23:06:29 volumio volumio[17924]: info: [1774805789711] ControllerSpotify::clearAddPlayTrack Mar 29 23:06:29 volumio volumio[17924]: info: Sending Spotify command with payload to local API: /player/play Mar 29 23:06:29 volumio go-librespot[18128]: time="2026-03-29T23:06:29+05:30" level=debug msg="resolved context of track" uri="spotify:track:4PhoP49dtLjz2BnLP6tgQ1" Mar 29 23:06:29 volumio go-librespot[18128]: time="2026-03-29T23:06:29+05:30" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:4PhoP49dtLjz2BnLP6tgQ1" Mar 29 23:06:29 volumio go-librespot[18128]: time="2026-03-29T23:06:29+05:30" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:4PhoP49dtLjz2BnLP6tgQ1" Mar 29 23:06:30 volumio go-librespot[18128]: time="2026-03-29T23:06:30+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 29 23:06:30 volumio go-librespot[18128]: time="2026-03-29T23:06:30+05:30" level=trace msg="emitting websocket event: will_play" Mar 29 23:06:30 volumio volumio[17924]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:4PhoP49dtLjz2BnLP6tgQ1","uri":"spotify:track:4PhoP49dtLjz2BnLP6tgQ1","play_origin":"go-librespot"}} Mar 29 23:06:30 volumio go-librespot[18128]: time="2026-03-29T23:06:30+05:30" level=debug msg="selected format OGG_VORBIS_320 (81eccb3b75c9a19072c39c04c4adb340cc4ea50f)" uri="spotify:track:4PhoP49dtLjz2BnLP6tgQ1" Mar 29 23:06:30 volumio go-librespot[18128]: time="2026-03-29T23:06:30+05:30" level=debug msg="requested aes key for file 81eccb3b75c9a19072c39c04c4adb340cc4ea50f, gid: 4PhoP49dtLjz2BnLP6tgQ1" Mar 29 23:06:30 volumio go-librespot[18128]: time="2026-03-29T23:06:30+05:30" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:4PhoP49dtLjz2BnLP6tgQ1: failed retrieving audio key: failed retrieving aes key with code 1" Mar 29 23:06:30 volumio volumio[17924]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Mar 29 23:06:31 volumio volumio[17924]: info: CoreCommandRouter::volumioGetQueue Mar 29 23:06:31 volumio volumio[17924]: info: CoreStateMachine::getQueue Mar 29 23:06:31 volumio volumio[17924]: info: CorePlayQueue::getQueue Mar 29 23:06:33 volumio volumio[17924]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 23:06:34 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 23:06:34 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 29 23:06:40 volumio volumio5-onboarding[1494]: time=2026-03-29T23:06:40.067+05:30 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%02,192.168.1.38:49818 @ 0x295f1d0" latency=389.796867ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 29 23:06:40 volumio sudo[20516]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 23:06:40 volumio sudo[20514]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 23:06:40 volumio sudo[20516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:06:40 volumio sudo[20514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:06:40 volumio sudo[20516]: pam_unix(sudo:session): session closed for user root Mar 29 23:06:40 volumio sudo[20514]: pam_unix(sudo:session): session closed for user root Mar 29 23:06:40 volumio volumio[17924]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.38 UA: Mozilla/5.0 (Linux; Android 13; SM-G781B Build/TP1A.220624.014; 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 23:06:40 volumio sudo[20520]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 23:06:40 volumio sudo[20520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:06:40 volumio sudo[20520]: pam_unix(sudo:session): session closed for user root Mar 29 23:06:40 volumio sudo[20522]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 23:06:40 volumio sudo[20522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:06:40 volumio sudo[20522]: pam_unix(sudo:session): session closed for user root Mar 29 23:06:40 volumio volumio[17924]: verbose: New Socket.io Connection to 192.168.1.49 from 192.168.1.38 UA: Mozilla/5.0 (Linux; Android 13; SM-G781B Build/TP1A.220624.014; 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 23:06:41 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 23:06:41 volumio volumio[17924]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 23:06:41 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 23:06:41 volumio volumio[17924]: info: CoreCommandRouter::volumioGetState Mar 29 23:06:41 volumio volumio[17924]: info: CorePlayQueue::getTrack 0 Mar 29 23:06:41 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 23:06:41 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 23:06:41 volumio volumio[17924]: info: Received Get System Info Mar 29 23:06:41 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:06:41 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:06:41 volumio volumio[17924]: info: Discovery: Getting this device information Mar 29 23:06:41 volumio volumio[17924]: info: CoreCommandRouter::volumioGetState Mar 29 23:06:41 volumio volumio[17924]: info: CorePlayQueue::getTrack 0 Mar 29 23:06:41 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:06:41 volumio volumio[17924]: info: CoreCommandRouter::volumioGetState Mar 29 23:06:41 volumio volumio[17924]: info: CorePlayQueue::getTrack 0 Mar 29 23:06:41 volumio volumio[17924]: info: Listing playlists Mar 29 23:06:41 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 23:06:41 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 23:06:41 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 23:06:42 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 29 23:06:42 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 23:06:42 volumio volumio[17924]: info: Received Get System Info Mar 29 23:06:42 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:06:42 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:06:42 volumio volumio[17924]: info: Discovery: Getting this device information Mar 29 23:06:42 volumio volumio[17924]: info: CoreCommandRouter::volumioGetState Mar 29 23:06:42 volumio volumio[17924]: info: CorePlayQueue::getTrack 0 Mar 29 23:06:42 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:06:44 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 23:06:44 volumio volumio[17924]: info: Received Get System Info Mar 29 23:06:44 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 23:06:44 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 23:06:44 volumio volumio[17924]: info: Discovery: Getting this device information Mar 29 23:06:44 volumio volumio[17924]: info: CoreCommandRouter::volumioGetState Mar 29 23:06:44 volumio volumio[17924]: info: CorePlayQueue::getTrack 0 Mar 29 23:06:44 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 23:06:45 volumio volumio[17924]: info: Retrieving Cloud Streaming UI Mar 29 23:06:45 volumio volumio[17924]: info: Getting Tidal Cloud Configuration Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 23:06:45 volumio volumio[17924]: info: Getting Qobuz Cloud Configuration Mar 29 23:06:45 volumio volumio[17924]: info: Asking plugin for UI Config Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 23:06:45 volumio volumio[17924]: info: Getting Spotify Cloud Configuration Mar 29 23:06:45 volumio volumio[17924]: info: Asking plugin for UI Config Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 23:06:45 volumio volumio[17924]: info: Saving Spotify Acccount Mar 29 23:06:45 volumio volumio[17924]: info: Got Tidal Cloud Configuration Mar 29 23:06:45 volumio volumio[17924]: info: Got it Mar 29 23:06:45 volumio volumio[17924]: info: Got it Mar 29 23:06:45 volumio volumio[17924]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 23:06:45 volumio volumio[17924]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 23:06:45 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 29 23:06:47 volumio volumio[17924]: info: Disabling MyMusic plugin upnp Mar 29 23:06:47 volumio sudo[20541]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Mar 29 23:06:47 volumio sudo[20541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 23:06:47 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Mar 29 23:06:49 volumio go-librespot[18128]: time="2026-03-29T23:06:49+05:30" level=trace msg="sent dealer ping" Mar 29 23:06:49 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 23:06:49 volumio go-librespot[18128]: time="2026-03-29T23:06:49+05:30" level=trace msg="received dealer pong" Mar 29 23:06:49 volumio volumio[17924]: info: Enabling MyMusic plugin upnp Mar 29 23:06:49 volumio volumio[17924]: info: Enabling plugin upnp Mar 29 23:06:49 volumio volumio[17924]: info: Loading plugin "upnp"... Mar 29 23:06:49 volumio volumio[17924]: info: [1774805809880] Starting Upmpd Daemon Mar 29 23:06:49 volumio volumio[17924]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 29 23:06:49 volumio volumio[17924]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 23:06:49 volumio volumio[17924]: Error: listen EADDRINUSE: address already in use :::6599 Mar 29 23:06:49 volumio volumio[17924]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Mar 29 23:06:49 volumio volumio[17924]: at listenInCluster (node:net:1920:12) Mar 29 23:06:49 volumio volumio[17924]: at Server.listen (node:net:2008:7) Mar 29 23:06:49 volumio volumio[17924]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Mar 29 23:06:49 volumio volumio[17924]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Mar 29 23:06:49 volumio volumio[17924]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Mar 29 23:06:49 volumio volumio[17924]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 29 23:06:49 volumio volumio[17924]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Mar 29 23:06:49 volumio volumio[17924]: code: 'EADDRINUSE', Mar 29 23:06:49 volumio volumio[17924]: errno: -98, Mar 29 23:06:49 volumio volumio[17924]: syscall: 'listen', Mar 29 23:06:49 volumio volumio[17924]: address: '::', Mar 29 23:06:49 volumio volumio[17924]: port: 6599 Mar 29 23:06:49 volumio volumio[17924]: } Mar 29 23:06:49 volumio volumio[17924]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 23:06:50 volumio sudo[20558]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-29 23:05' Mar 29 23:06:50 volumio sudo[20558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"