Feb 09 15:45:01 volumio CRON[21549]: pam_unix(cron:session): session opened for user root(uid=0) by (uid=0) Feb 09 15:45:01 volumio CRON[21550]: pam_unix(cron:session): session opened for user root(uid=0) by (uid=0) Feb 09 15:45:01 volumio CRON[21552]: (root) CMD (sleep 30; /root/ha_post_cpu_rp.sh > /dev/null 2>&1) Feb 09 15:45:01 volumio CRON[21551]: (root) CMD (sleep 30; /root/ha_post_cpu_rp5.sh > /dev/null 2>&1) Feb 09 15:45:05 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:45:05 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:45:15 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:45:15 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:45:15 volumio volumio[1127]: info: Listing playlists Feb 09 15:45:15 volumio volumio[1127]: info: Listing playlists Feb 09 15:45:25 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:45:25 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:45:31 volumio CRON[21549]: pam_unix(cron:session): session closed for user root Feb 09 15:45:34 volumio CRON[21550]: pam_unix(cron:session): session closed for user root Feb 09 15:45:35 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:45:35 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:45:35 volumio volumio[1127]: info: Listing playlists Feb 09 15:45:35 volumio volumio[1127]: info: Listing playlists Feb 09 15:45:40 volumio kernel: usb 1-1.4: new high-speed USB device number 3 using xhci_hcd Feb 09 15:45:40 volumio kernel: usb 1-1.4: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Feb 09 15:45:40 volumio kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Feb 09 15:45:40 volumio kernel: usb 1-1.4: Product: SMSL USB AUDIO Feb 09 15:45:40 volumio kernel: usb 1-1.4: Manufacturer: SMSL Feb 09 15:45:40 volumio kernel: hid-generic 0003:152A:85DD.0001: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-0000:01:00.0-1.4/input3 Feb 09 15:45:41 volumio kernel: usbcore: registered new interface driver snd-usb-audio Feb 09 15:45:41 volumio volumio[1127]: info: Feb 09 15:45:41 volumio volumio[1127]: ---------------------------- USB Audio Device Attached Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::Close All Modals sent Feb 09 15:45:41 volumio volumio[1127]: info: Preparing to save Alsa Options, stopping services first Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:45:41 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::volumioPause Feb 09 15:45:41 volumio volumio[1127]: info: CoreStateMachine::pause Feb 09 15:45:41 volumio volumio[1127]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 09 15:45:41 volumio volumio[1127]: info: Device 5 does not have any Mixer Control Available Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 09 15:45:41 volumio volumio[1127]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 09 15:45:41 volumio volumio[1127]: info: Disabling external Volume Control Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 09 15:45:41 volumio volumio[1127]: info: CoreStateMachine::pushState Feb 09 15:45:41 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState Feb 09 15:45:41 volumio volumio[1127]: info: Preparing to generate the ALSA configuration file Feb 09 15:45:41 volumio volumio[1127]: info: Asound.conf file unchanged, so no further update is needed Feb 09 15:45:41 volumio volumio[1127]: info: Output device has changed, restarting MPD Feb 09 15:45:41 volumio volumio[1127]: info: Output device has changed, restarting Shairport Sync Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 15:45:41 volumio sudo[21677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 09 15:45:41 volumio sudo[21677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:45:41 volumio sudo[21677]: pam_unix(sudo:session): session closed for user root Feb 09 15:45:41 volumio sudo[21679]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 09 15:45:41 volumio sudo[21679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:45:41 volumio volumio[1127]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 09 15:45:41 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 09 15:45:41 volumio volumio[1127]: info: MPD Permissions set Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 15:45:41 volumio volumio[1127]: info: Starting Shairport Sync Feb 09 15:45:41 volumio sudo[21702]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 09 15:45:41 volumio sudo[21702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:45:41 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 09 15:45:41 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 09 15:45:41 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 09 15:45:41 volumio systemd[1]: shairport-sync.service: Consumed 26.397s CPU time. Feb 09 15:45:41 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 09 15:45:41 volumio sudo[21702]: pam_unix(sudo:session): session closed for user root Feb 09 15:45:41 volumio volumio[1127]: info: Shairport-Sync Started Feb 09 15:45:41 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 09 15:45:41 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 09 15:45:41 volumio systemd[1]: mpd.service: Consumed 3.813s CPU time. Feb 09 15:45:41 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 09 15:45:41 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 09 15:45:41 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 09 15:45:41 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 09 15:45:41 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 09 15:45:41 volumio sudo[21720]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 09 15:45:41 volumio sudo[21720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 09 15:45:41 volumio sudo[21720]: pam_unix(sudo:session): session closed for user root Feb 09 15:45:41 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Feb 09 15:45:43 volumio mpd[21722]: 2026-02-09T15:45:43 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 09 15:45:43 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 09 15:45:43 volumio sudo[21679]: pam_unix(sudo:session): session closed for user root Feb 09 15:45:43 volumio volumio[1127]: error: updateQueue error: null Feb 09 15:45:43 volumio sudo[21740]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 09 15:45:43 volumio sudo[21740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:45:43 volumio sudo[21740]: pam_unix(sudo:session): session closed for user root Feb 09 15:45:43 volumio sudo[21742]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 09 15:45:43 volumio sudo[21742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:45:43 volumio sudo[21742]: pam_unix(sudo:session): session closed for user root Feb 09 15:45:44 volumio volumio[1127]: verbose: New Socket.io Connection to 192.168.2.10 from 192.168.2.237 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 09 15:45:44 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 09 15:45:44 volumio volumio[1127]: info: CoreCommandRouter::volumioGetVisibleSources Feb 09 15:45:44 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 15:45:44 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:45:44 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:45:44 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 09 15:45:44 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 09 15:45:44 volumio volumio[1127]: info: Received Get System Info Feb 09 15:45:44 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:45:44 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:45:44 volumio volumio[1127]: info: Discovery: Getting this device information Feb 09 15:45:44 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:45:44 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:45:44 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:45:44 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:45:44 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:45:44 volumio volumio[1127]: info: Listing playlists Feb 09 15:45:44 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 09 15:45:44 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 09 15:45:44 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 09 15:45:45 volumio volumio[1127]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 09 15:45:45 volumio volumio[1127]: info: CoreStateMachine::getcurrentVolume Feb 09 15:45:45 volumio volumio[1127]: info: CoreCommandRouter::volumioRetrievevolume Feb 09 15:45:45 volumio volumio[1127]: info: CoreStateMachine::pushState Feb 09 15:45:45 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:45:45 volumio volumio[1127]: info: CoreCommandRouter::volumioPushState Feb 09 15:45:45 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:45:45 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:45:45 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 09 15:45:45 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 09 15:45:45 volumio volumio[1127]: info: Received Get System Info Feb 09 15:45:45 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:45:45 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:45:45 volumio volumio[1127]: info: Discovery: Getting this device information Feb 09 15:45:45 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:45:45 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:45:45 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:45:46 volumio volumio[1127]: info: CoreCommandRouter::volumioPlay Feb 09 15:45:46 volumio volumio[1127]: info: CoreStateMachine::play index undefined Feb 09 15:45:46 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:45:46 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:45:46 volumio volumio[1127]: info: CoreStateMachine::startPlaybackTimer Feb 09 15:45:46 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:45:46 volumio volumio[1127]: info: [1770644746904] ControllerSpotify::clearAddPlayTrack Feb 09 15:45:46 volumio volumio[1127]: info: Sending Spotify command with payload to local API: /player/play Feb 09 15:45:47 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 09 15:45:47 volumio volumio[1127]: info: Received Get System Info Feb 09 15:45:47 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:45:47 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:45:47 volumio volumio[1127]: info: Discovery: Getting this device information Feb 09 15:45:47 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:45:47 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:45:47 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:45:51 volumio volumio[1127]: info: CoreCommandRouter::volumioNext Feb 09 15:45:51 volumio volumio[1127]: info: CoreStateMachine::next Feb 09 15:45:51 volumio volumio[1127]: info: CoreStateMachine::stop Feb 09 15:45:51 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:45:51 volumio volumio[1127]: info: CoreStateMachine::play index undefined Feb 09 15:45:51 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:45:51 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 Feb 09 15:45:51 volumio volumio[1127]: info: CoreStateMachine::startPlaybackTimer Feb 09 15:45:51 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 Feb 09 15:45:51 volumio volumio[1127]: info: [1770644751611] ControllerSpotify::clearAddPlayTrack Feb 09 15:45:51 volumio volumio[1127]: info: Sending Spotify command with payload to local API: /player/play Feb 09 15:45:51 volumio volumio[1127]: info: CoreStateMachine::updateTrackBlock Feb 09 15:45:51 volumio volumio[1127]: info: CorePlayQueue::getTrackBlock Feb 09 15:45:53 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 09 15:45:55 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:45:55 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 Feb 09 15:45:55 volumio volumio[1127]: info: Listing playlists Feb 09 15:45:55 volumio volumio[1127]: info: Listing playlists Feb 09 15:45:57 volumio volumio[1127]: info: CoreCommandRouter::volumioGetQueue Feb 09 15:45:57 volumio volumio[1127]: info: CoreStateMachine::getQueue Feb 09 15:45:57 volumio volumio[1127]: info: CorePlayQueue::getQueue Feb 09 15:45:59 volumio volumio[1127]: info: Preload queue cleared Feb 09 15:45:59 volumio volumio[1127]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 09 15:45:59 volumio volumio[1127]: info: CoreStateMachine::ClearQueue Feb 09 15:45:59 volumio volumio[1127]: info: CoreStateMachine::stop Feb 09 15:45:59 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:45:59 volumio volumio[1127]: info: CorePlayQueue::clearPlayQueue Feb 09 15:45:59 volumio volumio[1127]: info: CorePlayQueue::saveQueue Feb 09 15:45:59 volumio volumio[1127]: info: CoreCommandRouter::volumioPushQueue Feb 09 15:45:59 volumio volumio[1127]: info: CoreStateMachine::addQueueItems Feb 09 15:45:59 volumio volumio[1127]: info: CorePlayQueue::addQueueItems Feb 09 15:45:59 volumio volumio[1127]: info: Preload queue cleared Feb 09 15:45:59 volumio volumio[1127]: info: Adding Item to queue: spotify:user:spotify:playlist:37i9dQZEVXcXkNu9PNQwip Feb 09 15:45:59 volumio volumio[1127]: info: Exploding uri spotify:user:spotify:playlist:37i9dQZEVXcXkNu9PNQwip in service spop Feb 09 15:45:59 volumio volumio[1127]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:37i9dQZEVXcXkNu9PNQwip Feb 09 15:46:00 volumio volumio[1127]: info: New access token = BQC8IChUQcb7qKSVKwi-A9cCwgZY8FT6Cyprgd5fVY09fHQb7ZOJu-OaCPWaKPAruQfzbbXgT4xPy2YXS8eZrBXC95D5AE58z1TuJvPcuqjCF0ptzTDJ5WZs9KH4up74fwI_cQ4NpShofkPPh2iS9oLD4PHpqOBAFoRKUcP5Gm87SrLPVmwy7iWYkg_XK9RYYpHDetcQ696Z2en6uaFDnFoJsL16C8AEE3PykUGpN0MEtOP0RLKitNp9odT39nHySn90tTx_2L_I8MN0t3i2r14gAaL16j0CIJ0IRwURwZ2Q Feb 09 15:46:00 volumio volumio[1127]: info: CoreCommandRouter::volumioPushQueue Feb 09 15:46:00 volumio volumio[1127]: info: CorePlayQueue::saveQueue Feb 09 15:46:00 volumio volumio[1127]: info: CoreStateMachine::updateTrackBlock Feb 09 15:46:00 volumio volumio[1127]: info: CorePlayQueue::getTrackBlock Feb 09 15:46:00 volumio volumio[1127]: info: CoreCommandRouter::volumioPlay Feb 09 15:46:00 volumio volumio[1127]: info: CoreStateMachine::play index 0 Feb 09 15:46:00 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:46:00 volumio volumio[1127]: info: CoreStateMachine::stop Feb 09 15:46:00 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:46:00 volumio volumio[1127]: info: CoreStateMachine::play index undefined Feb 09 15:46:00 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:46:00 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:46:00 volumio volumio[1127]: info: CoreStateMachine::startPlaybackTimer Feb 09 15:46:00 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:46:00 volumio volumio[1127]: info: [1770644760918] ControllerSpotify::clearAddPlayTrack Feb 09 15:46:00 volumio volumio[1127]: info: Sending Spotify command with payload to local API: /player/play Feb 09 15:46:01 volumio CRON[21766]: pam_unix(cron:session): session opened for user root(uid=0) by (uid=0) Feb 09 15:46:01 volumio CRON[21767]: pam_unix(cron:session): session opened for user root(uid=0) by (uid=0) Feb 09 15:46:01 volumio CRON[21768]: (root) CMD (sleep 30; /root/ha_post_cpu_rp5.sh > /dev/null 2>&1) Feb 09 15:46:01 volumio CRON[21769]: (root) CMD (sleep 30; /root/ha_post_cpu_rp.sh > /dev/null 2>&1) Feb 09 15:46:02 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 09 15:46:02 volumio volumio[1127]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 09 15:46:02 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 09 15:46:02 volumio volumio[1127]: info: Received Get System Version Feb 09 15:46:02 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 09 15:46:02 volumio volumio[1127]: info: Received Get System Info Feb 09 15:46:02 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 09 15:46:02 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 09 15:46:02 volumio volumio[1127]: info: Discovery: Getting this device information Feb 09 15:46:02 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:46:02 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:46:02 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 09 15:46:05 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:46:05 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:46:12 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:46:12 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:46:14 volumio volumio[1127]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 09 15:46:14 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 09 15:46:14 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 09 15:46:14 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 09 15:46:14 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 09 15:46:14 volumio volumio[1127]: info: Received Get System Version Feb 09 15:46:14 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 09 15:46:15 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:46:15 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:46:15 volumio volumio[1127]: info: Listing playlists Feb 09 15:46:15 volumio volumio[1127]: info: Listing playlists Feb 09 15:46:19 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:46:19 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:46:21 volumio volumio[1127]: info: CoreCommandRouter::volumioPlay Feb 09 15:46:21 volumio volumio[1127]: info: CoreStateMachine::play index undefined Feb 09 15:46:21 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:46:21 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:46:21 volumio volumio[1127]: info: CoreStateMachine::startPlaybackTimer Feb 09 15:46:21 volumio volumio[1127]: info: CorePlayQueue::getTrack 0 Feb 09 15:46:21 volumio volumio[1127]: info: [1770644781337] ControllerSpotify::clearAddPlayTrack Feb 09 15:46:21 volumio volumio[1127]: info: Sending Spotify command with payload to local API: /player/play Feb 09 15:46:25 volumio volumio[1127]: info: CoreCommandRouter::volumioNext Feb 09 15:46:25 volumio volumio[1127]: info: CoreStateMachine::next Feb 09 15:46:25 volumio volumio[1127]: info: CoreStateMachine::stop Feb 09 15:46:25 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:46:25 volumio volumio[1127]: info: CoreStateMachine::play index undefined Feb 09 15:46:25 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:46:25 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 Feb 09 15:46:25 volumio volumio[1127]: info: CoreStateMachine::startPlaybackTimer Feb 09 15:46:25 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 Feb 09 15:46:25 volumio volumio[1127]: info: [1770644785186] ControllerSpotify::clearAddPlayTrack Feb 09 15:46:25 volumio volumio[1127]: info: Sending Spotify command with payload to local API: /player/play Feb 09 15:46:25 volumio volumio[1127]: info: CoreStateMachine::updateTrackBlock Feb 09 15:46:25 volumio volumio[1127]: info: CorePlayQueue::getTrackBlock Feb 09 15:46:25 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:46:25 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 Feb 09 15:46:31 volumio CRON[21766]: pam_unix(cron:session): session closed for user root Feb 09 15:46:35 volumio CRON[21767]: pam_unix(cron:session): session closed for user root Feb 09 15:46:35 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:46:35 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 Feb 09 15:46:35 volumio volumio[1127]: info: Listing playlists Feb 09 15:46:35 volumio volumio[1127]: info: Listing playlists Feb 09 15:46:37 volumio volumio[1127]: info: Feb 09 15:46:37 volumio volumio[1127]: ---------------------------- Client requests Volumio play Feb 09 15:46:37 volumio volumio[1127]: info: CoreCommandRouter::volumioPlay Feb 09 15:46:37 volumio volumio[1127]: info: CoreStateMachine::play index undefined Feb 09 15:46:37 volumio volumio[1127]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:46:37 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 Feb 09 15:46:37 volumio volumio[1127]: info: CoreStateMachine::startPlaybackTimer Feb 09 15:46:37 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 Feb 09 15:46:37 volumio volumio[1127]: info: [1770644797444] ControllerSpotify::clearAddPlayTrack Feb 09 15:46:37 volumio volumio[1127]: info: Sending Spotify command with payload to local API: /player/play Feb 09 15:46:37 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:46:37 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 Feb 09 15:46:45 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:46:45 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 Feb 09 15:46:51 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 09 15:46:51 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 09 15:46:55 volumio volumio[1127]: info: CoreCommandRouter::volumioGetState Feb 09 15:46:55 volumio volumio[1127]: info: CorePlayQueue::getTrack 1 Feb 09 15:46:55 volumio volumio[1127]: info: Listing playlists Feb 09 15:46:55 volumio volumio[1127]: info: Listing playlists Feb 09 15:46:55 volumio go-librespot[1492]: time="2026-02-09T15:46:55+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Feb 09 15:46:55 volumio volumio[1127]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Feb 09 15:46:55 volumio volumio[1127]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 15:46:56 volumio volumio[1127]: info: Disabling plugin spop Feb 09 15:46:56 volumio volumio[1127]: info: Done. Feb 09 15:46:56 volumio volumio[1127]: info: Connection to go-librespot Websocket closed Feb 09 15:46:56 volumio sudo[21912]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Feb 09 15:46:56 volumio sudo[21912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 09 15:46:56 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 09 15:46:56 volumio systemd[1]: go-librespot-daemon.service: Killing process 1495 (go-librespot) with signal SIGKILL. Feb 09 15:46:56 volumio volumio[1127]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Feb 09 15:46:56 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 09 15:46:56 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 09 15:46:56 volumio systemd[1]: go-librespot-daemon.service: Consumed 2.059s CPU time. Feb 09 15:46:56 volumio volumio[1127]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Feb 09 15:46:56 volumio volumio[1127]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Feb 09 15:46:56 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 09 15:46:56 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 09 15:46:56 volumio volumio[1127]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 15:46:56 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 09 15:46:56 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 09 15:46:56 volumio sudo[21912]: pam_unix(sudo:session): session closed for user root Feb 09 15:46:56 volumio volumio[1127]: Error: socket hang up Feb 09 15:46:56 volumio volumio[1127]: at connResetException (node:internal/errors:720:14) Feb 09 15:46:56 volumio volumio[1127]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 09 15:46:56 volumio volumio[1127]: at Socket.emit (node:events:526:35) Feb 09 15:46:56 volumio volumio[1127]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 09 15:46:56 volumio volumio[1127]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 09 15:46:56 volumio volumio[1127]: code: 'ECONNRESET', Feb 09 15:46:56 volumio volumio[1127]: response: undefined Feb 09 15:46:56 volumio volumio[1127]: } Feb 09 15:46:56 volumio volumio[1127]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 15:46:56 volumio sudo[21928]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-09 15:45' Feb 09 15:46:56 volumio sudo[21928]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"