-- Logs begin at Thu 2019-02-14 14:11:58 +04, end at Fri 2025-07-04 21:15:55 +04. -- Jul 04 21:14:01 volumiopi4 volumio[1072]: verbose: New Socket.io Connection to 192.168.2.136:3000 from 192.168.2.150 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jul 04 21:14:08 volumiopi4 go-librespot[1305]: time="2025-07-04T21:14:08+04:00" level=debug msg="fetched chunk 4/25, size: 524288" uri="spotify:track:2Ddfm2NQwTRsf7YVlt258S" Jul 04 21:14:15 volumiopi4 go-librespot[1305]: time="2025-07-04T21:14:15+04:00" level=trace msg="sent dealer ping" Jul 04 21:14:15 volumiopi4 go-librespot[1305]: time="2025-07-04T21:14:15+04:00" level=trace msg="received dealer pong" Jul 04 21:14:23 volumiopi4 go-librespot[1305]: time="2025-07-04T21:14:23+04:00" level=debug msg="fetched chunk 5/25, size: 524288" uri="spotify:track:2Ddfm2NQwTRsf7YVlt258S" Jul 04 21:14:33 volumiopi4 ntpd[800]: Soliciting pool server 2001:ac8:81:65:0:2:0:3 Jul 04 21:14:38 volumiopi4 go-librespot[1305]: time="2025-07-04T21:14:38+04:00" level=debug msg="fetched chunk 6/25, size: 524288" uri="spotify:track:2Ddfm2NQwTRsf7YVlt258S" Jul 04 21:14:45 volumiopi4 go-librespot[1305]: time="2025-07-04T21:14:45+04:00" level=trace msg="sent dealer ping" Jul 04 21:14:45 volumiopi4 go-librespot[1305]: time="2025-07-04T21:14:45+04:00" level=trace msg="received dealer pong" Jul 04 21:14:46 volumiopi4 sudo[7319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 04 21:14:46 volumiopi4 sudo[7319]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 21:14:46 volumiopi4 sudo[7319]: pam_unix(sudo:session): session closed for user root Jul 04 21:14:46 volumiopi4 sudo[7322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 04 21:14:46 volumiopi4 sudo[7322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 21:14:46 volumiopi4 sudo[7322]: pam_unix(sudo:session): session closed for user root Jul 04 21:14:46 volumiopi4 volumio[1072]: verbose: New Socket.io Connection to 192.168.2.136 from 192.168.2.148 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 04 21:14:46 volumiopi4 sudo[7325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 04 21:14:46 volumiopi4 sudo[7325]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 21:14:46 volumiopi4 sudo[7325]: pam_unix(sudo:session): session closed for user root Jul 04 21:14:46 volumiopi4 sudo[7328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 04 21:14:46 volumiopi4 sudo[7328]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 21:14:46 volumiopi4 sudo[7328]: pam_unix(sudo:session): session closed for user root Jul 04 21:14:46 volumiopi4 volumio[1072]: verbose: New Socket.io Connection to 192.168.2.136 from 192.168.2.148 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 10 Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetVisibleSources Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetState Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 04 21:14:46 volumiopi4 volumio[1072]: info: Received Get System Info Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 21:14:46 volumiopi4 volumio[1072]: info: Discovery: Getting this device information Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetState Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetState Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:14:46 volumiopi4 volumio[1072]: info: Listing playlists Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 04 21:14:46 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 04 21:14:47 volumiopi4 volumio[1072]: verbose: New Socket.io Connection to 192.168.2.136 from 192.168.2.148 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Edg/138.0.0.0 Engine version: 3 Transport: polling Total Clients: 11 Jul 04 21:14:47 volumiopi4 go-librespot[1305]: time="2025-07-04T21:14:47+04:00" level=trace msg="received accesspoint ping" Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetVisibleSources Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetState Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 04 21:14:47 volumiopi4 volumio[1072]: info: Received Get System Info Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 21:14:47 volumiopi4 volumio[1072]: info: Discovery: Getting this device information Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetState Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetState Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:14:47 volumiopi4 volumio[1072]: info: Listing playlists Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetState Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:14:47 volumiopi4 go-librespot[1305]: time="2025-07-04T21:14:47+04:00" level=trace msg="received accesspoint pong ack" Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 04 21:14:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 04 21:14:48 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 04 21:14:48 volumiopi4 volumio[1072]: info: Received Get System Info Jul 04 21:14:48 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 21:14:48 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 21:14:48 volumiopi4 volumio[1072]: info: Discovery: Getting this device information Jul 04 21:14:48 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetState Jul 04 21:14:48 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:14:48 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 21:14:48 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetState Jul 04 21:14:48 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:14:49 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 04 21:14:49 volumiopi4 volumio[1072]: info: Received Get System Info Jul 04 21:14:49 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 21:14:49 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 21:14:49 volumiopi4 volumio[1072]: info: Discovery: Getting this device information Jul 04 21:14:49 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetState Jul 04 21:14:49 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:14:49 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 21:14:49 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 04 21:14:49 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 04 21:14:49 volumiopi4 volumio[1072]: info: Received Get System Info Jul 04 21:14:49 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 21:14:49 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 21:14:49 volumiopi4 volumio[1072]: info: Discovery: Getting this device information Jul 04 21:14:49 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetState Jul 04 21:14:49 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:14:49 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 21:14:50 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 04 21:14:50 volumiopi4 volumio[1072]: info: Received Get System Info Jul 04 21:14:50 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 21:14:50 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 21:14:50 volumiopi4 volumio[1072]: info: Discovery: Getting this device information Jul 04 21:14:50 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetState Jul 04 21:14:50 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:14:50 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 21:14:53 volumiopi4 go-librespot[1305]: time="2025-07-04T21:14:53+04:00" level=debug msg="fetched chunk 7/25, size: 524288" uri="spotify:track:2Ddfm2NQwTRsf7YVlt258S" Jul 04 21:14:57 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 04 21:15:06 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 04 21:15:06 volumiopi4 volumio[1072]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 04 21:15:06 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 04 21:15:06 volumiopi4 volumio[1072]: info: Received Get System Version Jul 04 21:15:06 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 21:15:06 volumiopi4 volumio[1072]: info: Received Get System Info Jul 04 21:15:06 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 21:15:06 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 21:15:06 volumiopi4 volumio[1072]: info: Discovery: Getting this device information Jul 04 21:15:06 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:06 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:15:06 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 21:15:07 volumiopi4 go-librespot[1305]: time="2025-07-04T21:15:07+04:00" level=debug msg="fetched chunk 8/25, size: 524288" uri="spotify:track:2Ddfm2NQwTRsf7YVlt258S" Jul 04 21:15:15 volumiopi4 go-librespot[1305]: time="2025-07-04T21:15:15+04:00" level=trace msg="sent dealer ping" Jul 04 21:15:15 volumiopi4 go-librespot[1305]: time="2025-07-04T21:15:15+04:00" level=trace msg="received dealer pong" Jul 04 21:15:21 volumiopi4 go-librespot[1305]: time="2025-07-04T21:15:21+04:00" level=debug msg="fetched chunk 9/25, size: 524288" uri="spotify:track:2Ddfm2NQwTRsf7YVlt258S" Jul 04 21:15:25 volumiopi4 volumio[1072]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 04 21:15:25 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 04 21:15:25 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 21:15:25 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 04 21:15:25 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 21:15:25 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 21:15:25 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 21:15:25 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 21:15:25 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 21:15:25 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 21:15:25 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 21:15:25 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 04 21:15:25 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 04 21:15:36 volumiopi4 go-librespot[1305]: time="2025-07-04T21:15:36+04:00" level=debug msg="fetched chunk 10/25, size: 524288" uri="spotify:track:2Ddfm2NQwTRsf7YVlt258S" Jul 04 21:15:37 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetState Jul 04 21:15:37 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:15:39 volumiopi4 ntpd[800]: Soliciting pool server 2a13:8a40::368 Jul 04 21:15:42 volumiopi4 volumio[1072]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 04 21:15:42 volumiopi4 volumio[1072]: info: Retrieving Cloud Streaming UI Jul 04 21:15:42 volumiopi4 volumio[1072]: info: Getting Tidal Cloud Configuration Jul 04 21:15:42 volumiopi4 volumio[1072]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 04 21:15:42 volumiopi4 volumio[1072]: info: Getting Qobuz Cloud Configuration Jul 04 21:15:42 volumiopi4 volumio[1072]: info: Asking plugin for UI Config Jul 04 21:15:42 volumiopi4 volumio[1072]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 04 21:15:42 volumiopi4 volumio[1072]: info: Getting Spotify Cloud Configuration Jul 04 21:15:42 volumiopi4 volumio[1072]: info: Asking plugin for UI Config Jul 04 21:15:42 volumiopi4 volumio[1072]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 04 21:15:42 volumiopi4 volumio[1072]: info: Saving Spotify Acccount Jul 04 21:15:42 volumiopi4 volumio[1072]: info: Got Tidal Cloud Configuration Jul 04 21:15:42 volumiopi4 volumio[1072]: info: Got it Jul 04 21:15:42 volumiopi4 volumio[1072]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 04 21:15:42 volumiopi4 volumio[1072]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 04 21:15:42 volumiopi4 volumio[1072]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 04 21:15:42 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 04 21:15:42 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 04 21:15:42 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 04 21:15:42 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 04 21:15:42 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 21:15:42 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 21:15:42 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 21:15:42 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 21:15:42 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetBrowseSources Jul 04 21:15:42 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetBrowseSources Jul 04 21:15:42 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioGetBrowseSources Jul 04 21:15:42 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 04 21:15:42 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jul 04 21:15:45 volumiopi4 volumio[1072]: verbose: ControllerMpd::sendMpdCommand rescan Jul 04 21:15:45 volumiopi4 volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Jul 04 21:15:45 volumiopi4 volumio[1072]: info: Jul 04 21:15:45 volumiopi4 volumio[1072]: ---------------------------- MPD announces state update: update Jul 04 21:15:45 volumiopi4 volumio[1072]: info: sendMpdCommand rescan took 6 milliseconds Jul 04 21:15:45 volumiopi4 volumio[1072]: info: ControllerMpd::getState Jul 04 21:15:45 volumiopi4 volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Jul 04 21:15:45 volumiopi4 volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Jul 04 21:15:45 volumiopi4 volumio[1072]: info: Jul 04 21:15:45 volumiopi4 volumio[1072]: ---------------------------- MPD announces state update: update Jul 04 21:15:45 volumiopi4 volumio[1072]: info: ControllerMpd::getState Jul 04 21:15:45 volumiopi4 volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Jul 04 21:15:45 volumiopi4 volumio[1072]: info: sendMpdCommand status took 5 milliseconds Jul 04 21:15:45 volumiopi4 volumio[1072]: info: sendMpdCommand status took 5 milliseconds Jul 04 21:15:45 volumiopi4 volumio[1072]: info: sendMpdCommand status took 4 milliseconds Jul 04 21:15:45 volumiopi4 volumio[1072]: info: sendMpdCommand status took 3 milliseconds Jul 04 21:15:45 volumiopi4 volumio[1072]: verbose: ControllerMpd::parseState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: Command Router : Notfying DB Updatetrue Jul 04 21:15:45 volumiopi4 volumio[1072]: verbose: ControllerMpd::parseState Jul 04 21:15:45 volumiopi4 volumio[1072]: verbose: ControllerMpd::parseState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: Command Router : Notfying DB Updatetrue Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CoreCommandRouter::Close All Modals sent Jul 04 21:15:45 volumiopi4 volumio[1072]: verbose: ControllerMpd::parseState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: ControllerMpd::pushState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CoreCommandRouter::servicePushState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CoreStateMachine::pushState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioPushState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:15:45 volumiopi4 volumio[1072]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Jul 04 21:15:45 volumiopi4 volumio[1072]: info: ControllerMpd::pushState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CoreCommandRouter::servicePushState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CoreStateMachine::pushState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioPushState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:15:45 volumiopi4 volumio[1072]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Jul 04 21:15:45 volumiopi4 volumio[1072]: info: ------------------------------ 221ms Jul 04 21:15:45 volumiopi4 volumio[1072]: info: ------------------------------ 220ms Jul 04 21:15:45 volumiopi4 volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Jul 04 21:15:45 volumiopi4 volumio[1072]: info: Jul 04 21:15:45 volumiopi4 volumio[1072]: ---------------------------- MPD announces state update: update Jul 04 21:15:45 volumiopi4 volumio[1072]: info: ControllerMpd::getState Jul 04 21:15:45 volumiopi4 volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Jul 04 21:15:45 volumiopi4 volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Jul 04 21:15:45 volumiopi4 volumio[1072]: info: Jul 04 21:15:45 volumiopi4 volumio[1072]: ---------------------------- MPD announces state update: update Jul 04 21:15:45 volumiopi4 volumio[1072]: info: ControllerMpd::getState Jul 04 21:15:45 volumiopi4 volumio[1072]: verbose: ControllerMpd::sendMpdCommand status Jul 04 21:15:45 volumiopi4 volumio[1072]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Jul 04 21:15:45 volumiopi4 volumio[1072]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Jul 04 21:15:45 volumiopi4 volumio[1072]: info: PeppyMeterBasic ---peppymeterbasic status play Jul 04 21:15:45 volumiopi4 volumio[1072]: info: PeppyMeterBasic ---peppymeterbasic status play Jul 04 21:15:45 volumiopi4 volumio[1072]: info: sendMpdCommand status took 91 milliseconds Jul 04 21:15:45 volumiopi4 volumio[1072]: info: sendMpdCommand status took 89 milliseconds Jul 04 21:15:45 volumiopi4 volumio[1072]: info: sendMpdCommand status took 88 milliseconds Jul 04 21:15:45 volumiopi4 volumio[1072]: info: sendMpdCommand status took 87 milliseconds Jul 04 21:15:45 volumiopi4 volumio[1072]: verbose: ControllerMpd::parseState Jul 04 21:15:45 volumiopi4 sudo[7479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jul 04 21:15:45 volumiopi4 sudo[7481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jul 04 21:15:45 volumiopi4 sudo[7481]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 21:15:45 volumiopi4 sudo[7479]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 21:15:45 volumiopi4 sudo[7481]: pam_unix(sudo:session): session closed for user root Jul 04 21:15:45 volumiopi4 sudo[7479]: pam_unix(sudo:session): session closed for user root Jul 04 21:15:45 volumiopi4 volumio[1072]: info: Command Router : Notfying DB Updatefalse Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CoreCommandRouter::Close All Modals sent Jul 04 21:15:45 volumiopi4 volumio[1072]: verbose: ControllerMpd::parseState Jul 04 21:15:45 volumiopi4 volumio[1072]: verbose: ControllerMpd::parseState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: Command Router : Notfying DB Updatefalse Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CoreCommandRouter::Close All Modals sent Jul 04 21:15:45 volumiopi4 volumio[1072]: verbose: ControllerMpd::parseState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: ControllerMpd::pushState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CoreCommandRouter::servicePushState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CoreStateMachine::pushState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioPushState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:15:45 volumiopi4 volumio[1072]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Jul 04 21:15:45 volumiopi4 volumio[1072]: info: ControllerMpd::pushState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CoreCommandRouter::servicePushState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CoreStateMachine::pushState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CoreCommandRouter::volumioPushState Jul 04 21:15:45 volumiopi4 volumio[1072]: info: CorePlayQueue::getTrack 6 Jul 04 21:15:45 volumiopi4 volumio[1072]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Jul 04 21:15:45 volumiopi4 volumio[1072]: info: ------------------------------ 373ms Jul 04 21:15:45 volumiopi4 volumio[1072]: info: ------------------------------ 369ms Jul 04 21:15:45 volumiopi4 volumio[1072]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Jul 04 21:15:45 volumiopi4 volumio[1072]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Jul 04 21:15:45 volumiopi4 volumio[1072]: info: PeppyMeterBasic ---peppymeterbasic status play Jul 04 21:15:45 volumiopi4 volumio[1072]: info: PeppyMeterBasic ---peppymeterbasic status play Jul 04 21:15:45 volumiopi4 sudo[7490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jul 04 21:15:45 volumiopi4 sudo[7490]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 21:15:45 volumiopi4 volumio[1072]: info: peppymeterbasic Daemon Started Jul 04 21:15:45 volumiopi4 sudo[7493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Jul 04 21:15:45 volumiopi4 sudo[7493]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 21:15:45 volumiopi4 sudo[7490]: pam_unix(sudo:session): session closed for user root Jul 04 21:15:45 volumiopi4 volumio[1072]: info: peppymeterbasic Daemon Started Jul 04 21:15:45 volumiopi4 sudo[7493]: pam_unix(sudo:session): session closed for user root Jul 04 21:15:45 volumiopi4 go-librespot[1305]: time="2025-07-04T21:15:45+04:00" level=trace msg="sent dealer ping" Jul 04 21:15:45 volumiopi4 volumio[1072]: info: peppymeterbasic Daemon Started Jul 04 21:15:45 volumiopi4 go-librespot[1305]: time="2025-07-04T21:15:45+04:00" level=trace msg="received dealer pong" Jul 04 21:15:46 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 04 21:15:47 volumiopi4 volumio[1072]: info: Enabling MyMusic plugin airplay_emulation Jul 04 21:15:47 volumiopi4 volumio[1072]: info: Enabling plugin airplay_emulation Jul 04 21:15:47 volumiopi4 volumio[1072]: info: Loading plugin "airplay_emulation"... Jul 04 21:15:47 volumiopi4 volumio[1072]: info: Starting Shairport Sync Jul 04 21:15:47 volumiopi4 volumio[1072]: info: PLUGIN START: airplay_emulation Jul 04 21:15:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 21:15:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 21:15:47 volumiopi4 volumio[1072]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 21:15:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 21:15:47 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 21:15:47 volumiopi4 volumio[1072]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 21:15:47 volumiopi4 volumio[1072]: info: Done. Jul 04 21:15:47 volumiopi4 volumio[1072]: info: Starting Shairport Sync Jul 04 21:15:47 volumiopi4 volumio[1072]: info: Starting Shairport Sync Jul 04 21:15:47 volumiopi4 sudo[7503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 21:15:47 volumiopi4 sudo[7503]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 21:15:47 volumiopi4 sudo[7506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 21:15:47 volumiopi4 sudo[7506]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 21:15:47 volumiopi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 21:15:47 volumiopi4 systemd[1]: shairport-sync.service: Succeeded. Jul 04 21:15:47 volumiopi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 21:15:47 volumiopi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 21:15:47 volumiopi4 sudo[7503]: pam_unix(sudo:session): session closed for user root Jul 04 21:15:47 volumiopi4 volumio[1072]: info: Shairport-Sync Started Jul 04 21:15:48 volumiopi4 volumio[1072]: Error adding Membership: Error: addMembership EINVAL Jul 04 21:15:48 volumiopi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 21:15:48 volumiopi4 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 04 21:15:48 volumiopi4 systemd[1]: shairport-sync.service: Succeeded. Jul 04 21:15:48 volumiopi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 21:15:48 volumiopi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 21:15:48 volumiopi4 sudo[7506]: pam_unix(sudo:session): session closed for user root Jul 04 21:15:48 volumiopi4 volumio[1072]: info: Shairport-Sync Started Jul 04 21:15:49 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jul 04 21:15:49 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jul 04 21:15:50 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 04 21:15:50 volumiopi4 go-librespot[1305]: time="2025-07-04T21:15:50+04:00" level=debug msg="fetched chunk 11/25, size: 524288" uri="spotify:track:2Ddfm2NQwTRsf7YVlt258S" Jul 04 21:15:54 volumiopi4 volumio[1072]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 04 21:15:54 volumiopi4 volumio[1072]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 21:15:55 volumiopi4 volumio[1072]: Error: dns service error: unknown Jul 04 21:15:55 volumiopi4 volumio[1072]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Jul 04 21:15:55 volumiopi4 volumio[1072]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jul 04 21:15:55 volumiopi4 volumio[1072]: errorCode: -65537 Jul 04 21:15:55 volumiopi4 volumio[1072]: } Jul 04 21:15:55 volumiopi4 volumio[1072]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 21:15:55 volumiopi4 sudo[7537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-04 21:14 Jul 04 21:15:55 volumiopi4 sudo[7537]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"