Dec 26 16:39:01 volumio-blimbla volumio[1351]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 16:39:01 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 26 16:39:01 volumio-blimbla volumio[1351]: Disk list : [] Dec 26 16:39:01 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 26 16:39:01 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 26 16:39:01 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 26 16:39:01 volumio-blimbla volumio[1351]: info: Received Get System Version Dec 26 16:39:01 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 16:39:05 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:39:05-05:00" level=trace msg="sent dealer ping" Dec 26 16:39:05 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:39:05-05:00" level=trace msg="received dealer pong" Dec 26 16:39:35 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:39:35-05:00" level=trace msg="sent dealer ping" Dec 26 16:39:35 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:39:35-05:00" level=trace msg="received dealer pong" Dec 26 16:39:46 volumio-blimbla volumio[1351]: info: CALLMETHOD: miscellanea appearance setVolumio3UI [object Object] Dec 26 16:39:46 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , setVolumio3UI Dec 26 16:39:46 volumio-blimbla volumio[1351]: info: Setting active UI to: {"uiPrettyName":"Contemporary","uiName":"contemporary","uiPath":"/volumio/http/www3"} Dec 26 16:39:47 volumio-blimbla volumio[1351]: info: CoreCommandRouter::Reload Ui Dec 26 16:39:48 volumio-blimbla sudo[26327]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 16:39:48 volumio-blimbla sudo[26327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:39:48 volumio-blimbla sudo[26327]: pam_unix(sudo:session): session closed for user root Dec 26 16:39:48 volumio-blimbla sudo[26329]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 16:39:48 volumio-blimbla sudo[26329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:39:48 volumio-blimbla sudo[26329]: pam_unix(sudo:session): session closed for user root Dec 26 16:39:48 volumio-blimbla volumio[1351]: verbose: New Socket.io Connection to 10.0.0.140 from 10.0.0.141 UA: Mozilla/5.0 (Linux; Android 14; TB350FU Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 26 16:39:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 16:39:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 26 16:39:48 volumio-blimbla volumio[1351]: Disk list : [] Dec 26 16:39:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 26 16:39:48 volumio-blimbla volumio[1351]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 26 16:39:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioGetState Dec 26 16:39:48 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:39:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioGetVisibleSources Dec 26 16:39:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 16:39:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 26 16:39:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 26 16:39:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 26 16:39:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 16:39:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 26 16:39:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 26 16:39:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 26 16:39:48 volumio-blimbla volumio[1351]: info: Received Get System Version Dec 26 16:39:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 16:39:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Dec 26 16:39:51 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 16:39:51 volumio-blimbla volumio[1351]: info: Received Get System Info Dec 26 16:39:51 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 16:39:51 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 16:39:51 volumio-blimbla volumio[1351]: info: Discovery: Getting this device information Dec 26 16:39:51 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioGetState Dec 26 16:39:51 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:39:51 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 16:39:51 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 16:39:51 volumio-blimbla volumio[1351]: info: Received Get System Info Dec 26 16:39:51 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 16:39:51 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 16:39:51 volumio-blimbla volumio[1351]: info: Discovery: Getting this device information Dec 26 16:39:51 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioGetState Dec 26 16:39:51 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:39:51 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 16:40:05 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:05-05:00" level=trace msg="sent dealer ping" Dec 26 16:40:05 volumio-blimbla systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 26 16:40:05 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:05-05:00" level=trace msg="received dealer pong" Dec 26 16:40:06 volumio-blimbla systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 26 16:40:06 volumio-blimbla systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 26 16:40:06 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 16:40:06 volumio-blimbla volumio[1351]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 26 16:40:06 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 26 16:40:06 volumio-blimbla volumio[1351]: info: Received Get System Version Dec 26 16:40:06 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 16:40:06 volumio-blimbla volumio[1351]: info: Received Get System Info Dec 26 16:40:06 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 16:40:06 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 16:40:06 volumio-blimbla volumio[1351]: info: Discovery: Getting this device information Dec 26 16:40:06 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioGetState Dec 26 16:40:06 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:40:06 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 16:40:06 volumio-blimbla volumio[1351]: info: Listing playlists Dec 26 16:40:08 volumio-blimbla volumio[1351]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 16:40:30 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 26 16:40:30 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 26 16:40:31 volumio-blimbla sudo[26477]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 16:40:31 volumio-blimbla sudo[26477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:40:31 volumio-blimbla sudo[26477]: pam_unix(sudo:session): session closed for user root Dec 26 16:40:31 volumio-blimbla sudo[26479]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 16:40:31 volumio-blimbla sudo[26479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:40:31 volumio-blimbla sudo[26479]: pam_unix(sudo:session): session closed for user root Dec 26 16:40:31 volumio-blimbla volumio[1351]: verbose: New Socket.io Connection to 10.0.0.140 from 10.0.0.141 UA: Mozilla/5.0 (Linux; Android 14; TB350FU Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 26 16:40:31 volumio-blimbla sudo[26483]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 16:40:31 volumio-blimbla sudo[26483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:40:31 volumio-blimbla sudo[26483]: pam_unix(sudo:session): session closed for user root Dec 26 16:40:31 volumio-blimbla sudo[26485]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 16:40:31 volumio-blimbla sudo[26485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:40:31 volumio-blimbla sudo[26485]: pam_unix(sudo:session): session closed for user root Dec 26 16:40:31 volumio-blimbla volumio[1351]: verbose: New Socket.io Connection to 10.0.0.140 from 10.0.0.141 UA: Mozilla/5.0 (Linux; Android 14; TB350FU Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 26 16:40:31 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioGetState Dec 26 16:40:31 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:40:31 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 26 16:40:31 volumio-blimbla volumio[1351]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 26 16:40:31 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioGetVisibleSources Dec 26 16:40:31 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 16:40:31 volumio-blimbla volumio[1351]: info: Listing playlists Dec 26 16:40:31 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 26 16:40:31 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 26 16:40:31 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 26 16:40:31 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 16:40:34 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 16:40:34 volumio-blimbla volumio[1351]: info: Received Get System Info Dec 26 16:40:34 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 16:40:34 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 16:40:34 volumio-blimbla volumio[1351]: info: Discovery: Getting this device information Dec 26 16:40:34 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioGetState Dec 26 16:40:34 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:40:34 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 16:40:34 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 16:40:34 volumio-blimbla volumio[1351]: info: Received Get System Info Dec 26 16:40:34 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 16:40:34 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 16:40:34 volumio-blimbla volumio[1351]: info: Discovery: Getting this device information Dec 26 16:40:34 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioGetState Dec 26 16:40:34 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:40:34 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 16:40:35 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:35-05:00" level=trace msg="received accesspoint ping" Dec 26 16:40:35 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:35-05:00" level=trace msg="received accesspoint pong ack" Dec 26 16:40:35 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:35-05:00" level=trace msg="sent dealer ping" Dec 26 16:40:35 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:35-05:00" level=trace msg="received dealer pong" Dec 26 16:40:36 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 26 16:40:36 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 26 16:40:36 volumio-blimbla sudo[26490]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 16:40:36 volumio-blimbla sudo[26490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:40:36 volumio-blimbla sudo[26490]: pam_unix(sudo:session): session closed for user root Dec 26 16:40:36 volumio-blimbla sudo[26492]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 16:40:36 volumio-blimbla sudo[26492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:40:36 volumio-blimbla sudo[26492]: pam_unix(sudo:session): session closed for user root Dec 26 16:40:36 volumio-blimbla volumio[1351]: verbose: New Socket.io Connection to 10.0.0.140 from 10.0.0.141 UA: Mozilla/5.0 (Linux; Android 14; TB350FU Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 26 16:40:37 volumio-blimbla sudo[26496]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 16:40:37 volumio-blimbla sudo[26496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:40:37 volumio-blimbla sudo[26496]: pam_unix(sudo:session): session closed for user root Dec 26 16:40:37 volumio-blimbla sudo[26498]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 16:40:37 volumio-blimbla sudo[26498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 16:40:37 volumio-blimbla sudo[26498]: pam_unix(sudo:session): session closed for user root Dec 26 16:40:37 volumio-blimbla volumio[1351]: verbose: New Socket.io Connection to 10.0.0.140 from 10.0.0.141 UA: Mozilla/5.0 (Linux; Android 14; TB350FU Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 26 16:40:37 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioGetState Dec 26 16:40:37 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:40:37 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 26 16:40:37 volumio-blimbla volumio[1351]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 26 16:40:37 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioGetVisibleSources Dec 26 16:40:37 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 16:40:37 volumio-blimbla volumio[1351]: info: Listing playlists Dec 26 16:40:37 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 26 16:40:37 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 26 16:40:37 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 26 16:40:37 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 16:40:39 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 16:40:39 volumio-blimbla volumio[1351]: info: Received Get System Info Dec 26 16:40:39 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 16:40:39 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 16:40:39 volumio-blimbla volumio[1351]: info: Discovery: Getting this device information Dec 26 16:40:39 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioGetState Dec 26 16:40:39 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:40:39 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 16:40:40 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 16:40:40 volumio-blimbla volumio[1351]: info: Received Get System Info Dec 26 16:40:40 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 16:40:40 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 16:40:40 volumio-blimbla volumio[1351]: info: Discovery: Getting this device information Dec 26 16:40:40 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioGetState Dec 26 16:40:40 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:40:40 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 16:40:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioSeek Dec 26 16:40:48 volumio-blimbla volumio[1351]: info: CoreStateMachine::seek Dec 26 16:40:48 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:40:48 volumio-blimbla volumio[1351]: info: TRACKBLOCK {"uri":"spotify:track:7uoFMmxln0GPXQ0AcCBXRq","service":"spop","name":"Snowman","artist":"Sia","album":"Everyday Is Christmas (Deluxe Edition)","type":"song","duration":165,"albumart":"https://i.scdn.co/image/ab67616d0000b273e8b11389352ddae61df3a93b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"} Dec 26 16:40:48 volumio-blimbla volumio[1351]: info: CoreStateMachine::startPlaybackTimer Dec 26 16:40:48 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:40:48 volumio-blimbla volumio[1351]: info: Spotify seek to: 22000 Dec 26 16:40:48 volumio-blimbla volumio[1351]: info: Sending Spotify command with payload to local API: /player/seek Dec 26 16:40:48 volumio-blimbla volumio[1351]: info: CoreStateMachine::pushState Dec 26 16:40:48 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:40:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 16:40:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioPushState Dec 26 16:40:48 volumio-blimbla volumio[1351]: info: MRS: Pushing multiroomSync output update for this device Dec 26 16:40:48 volumio-blimbla volumio[1351]: info: MRS: Pushing multiroomSync output Dec 26 16:40:48 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioGetState Dec 26 16:40:48 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:40:48 volumio-blimbla volumio[1351]: info: FusionDsp - Volumio is not playing Dec 26 16:40:48 volumio-blimbla volumio[1351]: info: FusionDsp - Clipped samples monitor stopped Dec 26 16:40:48 volumio-blimbla volumio[1351]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 26 16:40:49 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioGetState Dec 26 16:40:49 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:40:49 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 26 16:40:49 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioPlay Dec 26 16:40:49 volumio-blimbla volumio[1351]: info: CoreStateMachine::play index undefined Dec 26 16:40:49 volumio-blimbla volumio[1351]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 16:40:49 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:40:49 volumio-blimbla volumio[1351]: info: CoreStateMachine::startPlaybackTimer Dec 26 16:40:49 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:40:49 volumio-blimbla volumio[1351]: info: [1766785249083] ControllerSpotify::clearAddPlayTrack Dec 26 16:40:49 volumio-blimbla volumio[1351]: info: Sending Spotify command with payload to local API: /player/play Dec 26 16:40:49 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:49-05:00" level=debug msg="resolved context of track" uri="spotify:track:7uoFMmxln0GPXQ0AcCBXRq" Dec 26 16:40:49 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:49-05:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:7uoFMmxln0GPXQ0AcCBXRq" Dec 26 16:40:49 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:49-05:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:7uoFMmxln0GPXQ0AcCBXRq" Dec 26 16:40:49 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:49-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 26 16:40:49 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:49-05:00" level=trace msg="emitting websocket event: will_play" Dec 26 16:40:49 volumio-blimbla volumio[1351]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:7uoFMmxln0GPXQ0AcCBXRq","uri":"spotify:track:7uoFMmxln0GPXQ0AcCBXRq","play_origin":"go-librespot"}} Dec 26 16:40:49 volumio-blimbla volumio[1351]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:7uoFMmxln0GPXQ0AcCBXRq","uri":"spotify:track:7uoFMmxln0GPXQ0AcCBXRq","play_origin":"go-librespot"}} Dec 26 16:40:49 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:49-05:00" level=debug msg="selected format OGG_VORBIS_320 (bd472ff06c65b8f43c946793e0a38c1c3278b3b4)" uri="spotify:track:7uoFMmxln0GPXQ0AcCBXRq" Dec 26 16:40:49 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:49-05:00" level=debug msg="requested aes key for file bd472ff06c65b8f43c946793e0a38c1c3278b3b4, gid: 7uoFMmxln0GPXQ0AcCBXRq" Dec 26 16:40:49 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:49-05:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:7uoFMmxln0GPXQ0AcCBXRq: failed retrieving audio key: failed retrieving aes key with code 1" Dec 26 16:40:49 volumio-blimbla volumio[1351]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 26 16:40:55 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 16:40:55 volumio-blimbla volumio[1351]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 26 16:40:55 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 26 16:40:55 volumio-blimbla volumio[1351]: info: Received Get System Version Dec 26 16:40:55 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 16:40:55 volumio-blimbla volumio[1351]: info: Received Get System Info Dec 26 16:40:55 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 16:40:55 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 16:40:55 volumio-blimbla volumio[1351]: info: Discovery: Getting this device information Dec 26 16:40:55 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioGetState Dec 26 16:40:55 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:40:55 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 16:40:56 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Dec 26 16:40:56 volumio-blimbla volumio[1351]: info: Playing Remote Device: http://10.0.0.140 Dec 26 16:40:56 volumio-blimbla volumio[1351]: info: Dec 26 16:40:56 volumio-blimbla volumio[1351]: ---------------------------- Client requests Volumio play Dec 26 16:40:56 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioGetState Dec 26 16:40:56 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:40:56 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 26 16:40:56 volumio-blimbla volumio[1351]: info: CoreCommandRouter::volumioPlay Dec 26 16:40:56 volumio-blimbla volumio[1351]: info: CoreStateMachine::play index undefined Dec 26 16:40:56 volumio-blimbla volumio[1351]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 16:40:56 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:40:56 volumio-blimbla volumio[1351]: info: CoreStateMachine::startPlaybackTimer Dec 26 16:40:56 volumio-blimbla volumio[1351]: info: CorePlayQueue::getTrack 11 Dec 26 16:40:56 volumio-blimbla volumio[1351]: info: [1766785256798] ControllerSpotify::clearAddPlayTrack Dec 26 16:40:56 volumio-blimbla volumio[1351]: info: Sending Spotify command with payload to local API: /player/play Dec 26 16:40:56 volumio-blimbla volumio[1351]: info: Done playing: Dec 26 16:40:56 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:56-05:00" level=debug msg="resolved context of track" uri="spotify:track:7uoFMmxln0GPXQ0AcCBXRq" Dec 26 16:40:56 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:56-05:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:7uoFMmxln0GPXQ0AcCBXRq" Dec 26 16:40:56 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:56-05:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:7uoFMmxln0GPXQ0AcCBXRq" Dec 26 16:40:56 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:56-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 26 16:40:56 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:56-05:00" level=trace msg="emitting websocket event: will_play" Dec 26 16:40:56 volumio-blimbla volumio[1351]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:7uoFMmxln0GPXQ0AcCBXRq","uri":"spotify:track:7uoFMmxln0GPXQ0AcCBXRq","play_origin":"go-librespot"}} Dec 26 16:40:56 volumio-blimbla volumio[1351]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:7uoFMmxln0GPXQ0AcCBXRq","uri":"spotify:track:7uoFMmxln0GPXQ0AcCBXRq","play_origin":"go-librespot"}} Dec 26 16:40:57 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:57-05:00" level=debug msg="selected format OGG_VORBIS_320 (bd472ff06c65b8f43c946793e0a38c1c3278b3b4)" uri="spotify:track:7uoFMmxln0GPXQ0AcCBXRq" Dec 26 16:40:57 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:57-05:00" level=debug msg="requested aes key for file bd472ff06c65b8f43c946793e0a38c1c3278b3b4, gid: 7uoFMmxln0GPXQ0AcCBXRq" Dec 26 16:40:57 volumio-blimbla go-librespot[26138]: time="2025-12-26T16:40:57-05:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:7uoFMmxln0GPXQ0AcCBXRq: failed retrieving audio key: failed retrieving aes key with code 1" Dec 26 16:40:57 volumio-blimbla volumio[1351]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 26 16:40:59 volumio-blimbla volumio[1351]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Dec 26 16:40:59 volumio-blimbla volumio[1351]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Dec 26 16:40:59 volumio-blimbla volumio[1351]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 16:40:59 volumio-blimbla volumio[1351]: TypeError: Cannot read properties of undefined (reading 'then') Dec 26 16:40:59 volumio-blimbla volumio[1351]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Dec 26 16:40:59 volumio-blimbla volumio[1351]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Dec 26 16:40:59 volumio-blimbla volumio[1351]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1467:26) Dec 26 16:40:59 volumio-blimbla volumio[1351]: at Socket.emit (node:events:514:28) Dec 26 16:40:59 volumio-blimbla volumio[1351]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 26 16:40:59 volumio-blimbla volumio[1351]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Dec 26 16:40:59 volumio-blimbla volumio[1351]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 16:40:59 volumio-blimbla sudo[26574]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-26 16:39' Dec 26 16:40:59 volumio-blimbla sudo[26574]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"