-- Logs begin at Thu 2019-02-14 05:11:58 EST, end at Sun 2024-10-27 01:50:50 EDT. -- Oct 27 01:49:02 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 27 01:49:02 basement-volumio volumio[1189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Oct 27 01:49:02 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:49:02 basement-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 27 01:49:03 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:49:03 basement-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 27 01:49:05 basement-volumio volumio[1189]: info: Executing endpoint metavolumio Oct 27 01:49:05 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 01:49:05 basement-volumio volumio[1189]: info: Executing endpoint metavolumio Oct 27 01:49:05 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 01:49:05 basement-volumio volumio[1189]: info: Executing endpoint metavolumio Oct 27 01:49:05 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:49:07 basement-volumio volumio[1189]: info: Retrieving Cloud Streaming UI Oct 27 01:49:07 basement-volumio volumio[1189]: info: Getting Tidal Cloud Configuration Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:49:07 basement-volumio volumio[1189]: info: Getting Qobuz Cloud Configuration Oct 27 01:49:07 basement-volumio volumio[1189]: info: Asking plugin for UI Config Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:49:07 basement-volumio volumio[1189]: info: Getting Spotify Cloud Configuration Oct 27 01:49:07 basement-volumio volumio[1189]: info: Asking plugin for UI Config Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:49:07 basement-volumio volumio[1189]: info: Saving Spotify Acccount Oct 27 01:49:07 basement-volumio volumio[1189]: info: Got it Oct 27 01:49:07 basement-volumio volumio[1189]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 27 01:49:07 basement-volumio volumio[1189]: info: Got Tidal Cloud Configuration Oct 27 01:49:07 basement-volumio volumio[1189]: info: Got it Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetBrowseSources Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetBrowseSources Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetBrowseSources Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetBrowseSources Oct 27 01:49:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 01:49:11 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 27 01:49:15 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 27 01:49:19 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 27 01:49:23 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:49:23 basement-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 27 01:49:25 basement-volumio volumio[1189]: info: Executing endpoint metavolumio Oct 27 01:49:25 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 01:49:25 basement-volumio volumio[1189]: info: Executing endpoint metavolumio Oct 27 01:49:25 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 01:49:25 basement-volumio volumio[1189]: info: Executing endpoint metavolumio Oct 27 01:49:25 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 01:49:25 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 01:49:25 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 27 01:49:31 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:49:48 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 01:49:48 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 01:49:48 basement-volumio volumio[1189]: info: Discovery: Getting this device information Oct 27 01:49:48 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:49:48 basement-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 27 01:49:48 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 01:49:48 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 01:49:48 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 01:49:48 basement-volumio volumio[1189]: info: Discovery: Getting this device information Oct 27 01:49:48 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:49:48 basement-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 27 01:49:48 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 01:49:49 basement-volumio volumio[1189]: verbose: New Socket.io Connection to 192.168.50.250 from 192.168.50.18 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 16 Oct 27 01:49:49 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 27 01:49:49 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetVisibleSources Oct 27 01:49:49 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 01:49:49 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:49:49 basement-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 27 01:49:49 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 27 01:49:49 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetQueue Oct 27 01:49:49 basement-volumio volumio[1189]: info: CoreStateMachine::getQueue Oct 27 01:49:49 basement-volumio volumio[1189]: info: CorePlayQueue::getQueue Oct 27 01:49:49 basement-volumio volumio[1189]: info: Listing playlists Oct 27 01:49:49 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 27 01:49:49 basement-volumio volumio[1189]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 27 01:49:49 basement-volumio volumio[1189]: info: Received Get System Info Oct 27 01:49:49 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 01:49:49 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 01:49:49 basement-volumio volumio[1189]: info: Discovery: Getting this device information Oct 27 01:49:49 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:49:49 basement-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 27 01:49:49 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 01:49:49 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:49:49 basement-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 27 01:49:49 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 27 01:49:49 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 27 01:49:49 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 27 01:49:49 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:49:49 basement-volumio volumio[1189]: verbose: New Socket.io Connection to 192.168.50.250:3000 from 192.168.50.18 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 17 Oct 27 01:49:51 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 27 01:49:51 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 01:49:51 basement-volumio volumio[1189]: info: Received Get System Info Oct 27 01:49:51 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 01:49:51 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 01:49:51 basement-volumio volumio[1189]: info: Discovery: Getting this device information Oct 27 01:49:51 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:49:51 basement-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 27 01:49:51 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 01:49:52 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 01:49:52 basement-volumio volumio[1189]: info: Received Get System Info Oct 27 01:49:52 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 01:49:52 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 01:49:52 basement-volumio volumio[1189]: info: Discovery: Getting this device information Oct 27 01:49:52 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:49:52 basement-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 27 01:49:52 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 01:49:57 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 01:49:57 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 27 01:49:57 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:49:59 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 27 01:50:04 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMixcloud Oct 27 01:50:04 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 01:50:04 basement-volumio volumio[1189]: Cannot find translation for source SoundCloud Oct 27 01:50:04 basement-volumio volumio[1189]: Cannot find translation for source YouTube2 Oct 27 01:50:04 basement-volumio volumio[1189]: Cannot find translation for source YouTube Music Oct 27 01:50:04 basement-volumio volumio[1189]: Cannot find translation for source Mother Earth Radio Oct 27 01:50:04 basement-volumio volumio[1189]: Cannot find translation for source Pandora Radio Oct 27 01:50:04 basement-volumio volumio[1189]: Cannot find translation for source Podcast Oct 27 01:50:04 basement-volumio volumio[1189]: Cannot find translation for source Radio Paradise Oct 27 01:50:04 basement-volumio volumio[1189]: Cannot find translation for source LastFM Oct 27 01:50:04 basement-volumio volumio[1189]: info: Disabling plugin mixcloud Oct 27 01:50:04 basement-volumio volumio[1189]: info: Done. Oct 27 01:50:04 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:50:06 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMother Earth Radio Oct 27 01:50:06 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 01:50:06 basement-volumio volumio[1189]: Cannot find translation for source SoundCloud Oct 27 01:50:06 basement-volumio volumio[1189]: Cannot find translation for source YouTube2 Oct 27 01:50:06 basement-volumio volumio[1189]: Cannot find translation for source YouTube Music Oct 27 01:50:06 basement-volumio volumio[1189]: Cannot find translation for source Pandora Radio Oct 27 01:50:06 basement-volumio volumio[1189]: Cannot find translation for source Podcast Oct 27 01:50:06 basement-volumio volumio[1189]: Cannot find translation for source Radio Paradise Oct 27 01:50:06 basement-volumio volumio[1189]: Cannot find translation for source LastFM Oct 27 01:50:06 basement-volumio volumio[1189]: info: Disabling plugin motherearthradio Oct 27 01:50:06 basement-volumio volumio[1189]: info: Done. Oct 27 01:50:06 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:50:07 basement-volumio volumio[1189]: info: [1730008207079] [Pandora] ExpireOldTracks::stop: Stopping. Oct 27 01:50:07 basement-volumio volumio[1189]: info: [1730008207080] [Pandora] PreventAuthTimeout::stop: Stopping. Oct 27 01:50:07 basement-volumio volumio[1189]: info: [1730008207080] ControllerPandora::flushPandora Oct 27 01:50:07 basement-volumio volumio[1189]: info: CorePlayQueue::getQueue Oct 27 01:50:07 basement-volumio volumio[1189]: info: CorePlayQueue::clearAddPlayQueue Oct 27 01:50:07 basement-volumio volumio[1189]: info: CorePlayQueue::saveQueue Oct 27 01:50:07 basement-volumio volumio[1189]: info: CoreCommandRouter::serviceClearAddPlayTracks Oct 27 01:50:07 basement-volumio volumio[1189]: error: WARNING: No clearAddPlayTracks method for service motherearthradio Oct 27 01:50:07 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioPushQueue Oct 27 01:50:07 basement-volumio volumio[1189]: info: [1730008207088] ControllerPandora::stop Oct 27 01:50:07 basement-volumio volumio[1189]: info: ControllerMpd::stop Oct 27 01:50:07 basement-volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 01:50:07 basement-volumio volumio[1189]: info: sendMpdCommand stop took 3 milliseconds Oct 27 01:50:07 basement-volumio volumio[1189]: info: ControllerMpd::clear Oct 27 01:50:07 basement-volumio volumio[1189]: verbose: ControllerMpd::sendMpdCommand clear Oct 27 01:50:07 basement-volumio volumio[1189]: info: Oct 27 01:50:07 basement-volumio volumio[1189]: ---------------------------- MPD announces system playlist update Oct 27 01:50:07 basement-volumio volumio[1189]: info: Ignoring MPD Status Update Oct 27 01:50:07 basement-volumio volumio[1189]: info: sendMpdCommand clear took 1 milliseconds Oct 27 01:50:07 basement-volumio volumio[1189]: info: [1730008207092] ControllerPandora::pushState Oct 27 01:50:07 basement-volumio volumio[1189]: info: CoreCommandRouter::servicePushState Oct 27 01:50:07 basement-volumio volumio[1189]: info: CoreStateMachine::pushState Oct 27 01:50:07 basement-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 27 01:50:07 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Oct 27 01:50:07 basement-volumio volumio[1189]: info: MRS: Pushing multiroomSync output update for this device Oct 27 01:50:07 basement-volumio volumio[1189]: info: MRS: Pushing multiroomSync output Oct 27 01:50:07 basement-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 27 01:50:07 basement-volumio volumio[1189]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received pandora Oct 27 01:50:07 basement-volumio volumio[1189]: info: CoreStateMachine::setConsumeUpdateService pandora Oct 27 01:50:07 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesPandora Radio Oct 27 01:50:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 01:50:07 basement-volumio volumio[1189]: Cannot find translation for source SoundCloud Oct 27 01:50:07 basement-volumio volumio[1189]: Cannot find translation for source YouTube2 Oct 27 01:50:07 basement-volumio volumio[1189]: Cannot find translation for source YouTube Music Oct 27 01:50:07 basement-volumio volumio[1189]: Cannot find translation for source Podcast Oct 27 01:50:07 basement-volumio volumio[1189]: Cannot find translation for source Radio Paradise Oct 27 01:50:07 basement-volumio volumio[1189]: Cannot find translation for source LastFM Oct 27 01:50:07 basement-volumio volumio[1189]: info: Disabling plugin pandora Oct 27 01:50:07 basement-volumio volumio[1189]: info: Done. Oct 27 01:50:07 basement-volumio volumio[1189]: error: updateQueue error: null Oct 27 01:50:07 basement-volumio volumio[1189]: info: ------------------------------ 29ms Oct 27 01:50:07 basement-volumio volumio[1189]: info: PeppySpectrum ---peppyspectrum status pause Oct 27 01:50:07 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:50:07 basement-volumio sudo[2490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 27 01:50:07 basement-volumio sudo[2490]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 01:50:07 basement-volumio sudo[2490]: pam_unix(sudo:session): session closed for user root Oct 27 01:50:07 basement-volumio volumio[1189]: info: peppyspectrum Daemon Stop Oct 27 01:50:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 27 01:50:07 basement-volumio volumio[1189]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 27 01:50:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 27 01:50:07 basement-volumio volumio[1189]: info: Received Get System Version Oct 27 01:50:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 01:50:07 basement-volumio volumio[1189]: info: Received Get System Info Oct 27 01:50:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 01:50:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 01:50:07 basement-volumio volumio[1189]: info: Discovery: Getting this device information Oct 27 01:50:07 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:50:07 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 01:50:11 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesRadio Paradise Oct 27 01:50:11 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 01:50:11 basement-volumio volumio[1189]: Cannot find translation for source SoundCloud Oct 27 01:50:11 basement-volumio volumio[1189]: Cannot find translation for source YouTube2 Oct 27 01:50:11 basement-volumio volumio[1189]: Cannot find translation for source YouTube Music Oct 27 01:50:11 basement-volumio volumio[1189]: Cannot find translation for source Podcast Oct 27 01:50:11 basement-volumio volumio[1189]: Cannot find translation for source LastFM Oct 27 01:50:11 basement-volumio volumio[1189]: info: Disabling plugin radio_paradise Oct 27 01:50:11 basement-volumio volumio[1189]: info: Done. Oct 27 01:50:11 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:50:12 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSoundCloud Oct 27 01:50:12 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 01:50:12 basement-volumio volumio[1189]: Cannot find translation for source YouTube2 Oct 27 01:50:12 basement-volumio volumio[1189]: Cannot find translation for source YouTube Music Oct 27 01:50:12 basement-volumio volumio[1189]: Cannot find translation for source Podcast Oct 27 01:50:12 basement-volumio volumio[1189]: Cannot find translation for source LastFM Oct 27 01:50:12 basement-volumio volumio[1189]: info: Disabling plugin soundcloud Oct 27 01:50:12 basement-volumio volumio[1189]: info: Done. Oct 27 01:50:12 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:50:15 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesYouTube2 Oct 27 01:50:15 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 01:50:15 basement-volumio volumio[1189]: Cannot find translation for source YouTube Music Oct 27 01:50:15 basement-volumio volumio[1189]: Cannot find translation for source Podcast Oct 27 01:50:15 basement-volumio volumio[1189]: Cannot find translation for source LastFM Oct 27 01:50:15 basement-volumio volumio[1189]: info: Disabling plugin youtube2 Oct 27 01:50:15 basement-volumio volumio[1189]: info: Done. Oct 27 01:50:15 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:50:17 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesYouTube Music Oct 27 01:50:17 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 01:50:17 basement-volumio volumio[1189]: Cannot find translation for source Podcast Oct 27 01:50:17 basement-volumio volumio[1189]: Cannot find translation for source LastFM Oct 27 01:50:17 basement-volumio volumio[1189]: info: Disabling plugin ytmusic Oct 27 01:50:17 basement-volumio volumio[1189]: info: Done. Oct 27 01:50:17 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:50:17 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 01:50:17 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 01:50:17 basement-volumio volumio[1189]: info: Discovery: Getting this device information Oct 27 01:50:17 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:50:17 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 01:50:20 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:50:24 basement-volumio volumio[1189]: info: Received OAUTH Data Oct 27 01:50:24 basement-volumio volumio[1189]: info: Executing Spotify Oauth Login Oct 27 01:50:24 basement-volumio volumio[1189]: info: Saving Spotify Refresh Token Oct 27 01:50:24 basement-volumio sudo[2499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 27 01:50:24 basement-volumio sudo[2499]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 01:50:24 basement-volumio sudo[2499]: pam_unix(sudo:session): session closed for user root Oct 27 01:50:24 basement-volumio sudo[2502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 27 01:50:24 basement-volumio sudo[2502]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 01:50:24 basement-volumio sudo[2502]: pam_unix(sudo:session): session closed for user root Oct 27 01:50:24 basement-volumio volumio[1189]: verbose: New Socket.io Connection to 192.168.50.250 from 192.168.50.18 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 17 Oct 27 01:50:24 basement-volumio volumio[1189]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 27 01:50:24 basement-volumio volumio[1189]: SPOTIFY: BQCk8khCKxt_U_lBbxjuqC3gE8gvUwwtHtqMLkjVGaIlQS8Q3vIfJrUAaaE4stG1gd-LKQhUxO5UwMi6aNFlmN2cGN0-saOTzWFmmFlMIQ0FwWyuPzE9hlMoSFhwk8Y-FspSNZPQY9qIG8L1XaZzVeynlSOjp-AYdSfXEYelt9QxtIhUjAvhXhxWIFa8ImQqsgOsRtI6LAcH1J1HeZfB2MQdOvyCY5cWRL9-324VLoaq8lWU-dbE1g Oct 27 01:50:24 basement-volumio volumio[1189]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 27 01:50:24 basement-volumio volumio[1189]: info: New Spotify access token = BQCk8khCKxt_U_lBbxjuqC3gE8gvUwwtHtqMLkjVGaIlQS8Q3vIfJrUAaaE4stG1gd-LKQhUxO5UwMi6aNFlmN2cGN0-saOTzWFmmFlMIQ0FwWyuPzE9hlMoSFhwk8Y-FspSNZPQY9qIG8L1XaZzVeynlSOjp-AYdSfXEYelt9QxtIhUjAvhXhxWIFa8ImQqsgOsRtI6LAcH1J1HeZfB2MQdOvyCY5cWRL9-324VLoaq8lWU-dbE1g Oct 27 01:50:24 basement-volumio volumio[1189]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 27 01:50:24 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 27 01:50:24 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetVisibleSources Oct 27 01:50:24 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 01:50:24 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:50:24 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 27 01:50:24 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:50:24 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 27 01:50:24 basement-volumio volumio[1189]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 27 01:50:24 basement-volumio volumio[1189]: info: Received Get System Info Oct 27 01:50:24 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 01:50:24 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 01:50:24 basement-volumio volumio[1189]: info: Discovery: Getting this device information Oct 27 01:50:24 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:50:24 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 01:50:24 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:50:24 basement-volumio volumio[1189]: info: Listing playlists Oct 27 01:50:24 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 27 01:50:24 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 27 01:50:24 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:50:24 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 27 01:50:25 basement-volumio volumio[1189]: SPOTIFY: User informations: {"country":"US","display_name":"$drama","email":"djramachandran@me.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/$drama"},"followers":{"href":null,"total":14},"href":"https://api.spotify.com/v1/users/$drama","id":"$drama","images":[],"product":"premium","type":"user","uri":"spotify:user:%24drama"} Oct 27 01:50:25 basement-volumio volumio[1189]: info: Creating Spotify config file Oct 27 01:50:25 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 01:50:25 basement-volumio volumio[1189]: info: Spotify config file written Oct 27 01:50:25 basement-volumio sudo[2505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 27 01:50:25 basement-volumio sudo[2505]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 01:50:25 basement-volumio systemd[1]: Stopping go-librespot Daemon... Oct 27 01:50:25 basement-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Oct 27 01:50:25 basement-volumio systemd[1]: go-librespot-daemon.service: Succeeded. Oct 27 01:50:25 basement-volumio systemd[1]: Stopped go-librespot Daemon. Oct 27 01:50:25 basement-volumio volumio[1189]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 27 01:50:25 basement-volumio volumio[1189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 27 01:50:25 basement-volumio volumio[1189]: info: Connection to go-librespot Websocket closed Oct 27 01:50:25 basement-volumio systemd[1]: Started go-librespot Daemon. Oct 27 01:50:25 basement-volumio go-librespot[2507]: Librespot-go daemon starting... Oct 27 01:50:25 basement-volumio sudo[2505]: pam_unix(sudo:session): session closed for user root Oct 27 01:50:25 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:25-04:00" level=info msg="generated new device id: 91f49e6aedeed4b69dd78db51d9d9526bede9ae5" Oct 27 01:50:25 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:25-04:00" level=debug msg="stored credentials found for $drama" Oct 27 01:50:25 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:25-04:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 01:50:25 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:25-04:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 27 01:50:25 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:25-04:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 27 01:50:25 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:25-04:00" level=debug msg="zeroconf server listening on port 39153" Oct 27 01:50:25 basement-volumio volumio[1189]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 27 01:50:25 basement-volumio volumio[1189]: SPOTIFY: BQA0tqq9Pzpq4dbGUUrZe3_TwP-40siD3jHV1hFCBnOmJDoJ5mQTMSKck_dgA198dSHyXilgtcZIsJLLX2faR5_6qxfiP107KZiOebD7ml7xCtmOgPUQqcrQdSmHfG0VCb51VadZ5lOOE_bYbRT-kJhgS7XLXRsVAM9_jFcmCu_sYm7GS1cSNvOXz1KePQgFOci8lH0-fxGIPNTsWUYo-Vjs_D3v12uxr6XwGIhNxV2v94ns52NRRw Oct 27 01:50:25 basement-volumio volumio[1189]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 27 01:50:25 basement-volumio volumio[1189]: info: New Spotify access token = BQA0tqq9Pzpq4dbGUUrZe3_TwP-40siD3jHV1hFCBnOmJDoJ5mQTMSKck_dgA198dSHyXilgtcZIsJLLX2faR5_6qxfiP107KZiOebD7ml7xCtmOgPUQqcrQdSmHfG0VCb51VadZ5lOOE_bYbRT-kJhgS7XLXRsVAM9_jFcmCu_sYm7GS1cSNvOXz1KePQgFOci8lH0-fxGIPNTsWUYo-Vjs_D3v12uxr6XwGIhNxV2v94ns52NRRw Oct 27 01:50:25 basement-volumio volumio[1189]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 27 01:50:25 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:25-04:00" level=debug msg="obtained new client token: AABkJnGR4J88UQnZe1h+rQFyc32Tq87tEgTlalvIL0OiR0Y5liDlH1IIKC0nj3d0wyJKaHrTHVo4nKSxh5KMsWq3o5aItNKCfwu7kx8k/OAxpR8/EJT9q1S8R+zfvzWmnWb4GSkA6EjI2n6ooeHwWuT2qQaQ+nLdxPveZ+CaIy5RZsCrZ8VmvlLdm+aFatP2jtWz1eKMVRPXl0u01EBglAJ151jjXIBWLj6WLhzCOHaUDBSLc5Bbil78JduAXw==" Oct 27 01:50:25 basement-volumio volumio[1189]: SPOTIFY: User informations: {"country":"US","display_name":"$drama","email":"djramachandran@me.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/$drama"},"followers":{"href":null,"total":14},"href":"https://api.spotify.com/v1/users/$drama","id":"$drama","images":[],"product":"premium","type":"user","uri":"spotify:user:%24drama"} Oct 27 01:50:25 basement-volumio volumio[1189]: info: Spotify Successfully logged in Oct 27 01:50:25 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 27 01:50:25 basement-volumio volumio[1189]: info: [1730008225368] CoreMusicLibrary::Adding element Spotify Oct 27 01:50:25 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 01:50:25 basement-volumio volumio[1189]: Cannot find translation for source Podcast Oct 27 01:50:25 basement-volumio volumio[1189]: Cannot find translation for source LastFM Oct 27 01:50:25 basement-volumio volumio[1189]: Cannot find translation for source Spotify Oct 27 01:50:25 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:25-04:00" level=info msg="connected to ap-guc3.spotify.com:4070" Oct 27 01:50:25 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:25-04:00" level=debug msg="completed keyexchange" Oct 27 01:50:25 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:25-04:00" level=debug msg="completed challenge" Oct 27 01:50:26 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:26-04:00" level=debug msg="authenticated as $drama" Oct 27 01:50:26 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:26-04:00" level=debug msg="authenticated as $drama" Oct 27 01:50:26 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:26-04:00" level=debug msg="dealer connection opened" Oct 27 01:50:26 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:26-04:00" level=debug msg="initializing zeroconf session, username: $drama" Oct 27 01:50:26 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:26-04:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 27 01:50:26 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:26-04:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 27 01:50:26 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:26-04:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 27 01:50:26 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:26-04:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 27 01:50:26 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:26-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 505" Oct 27 01:50:26 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:26-04:00" level=debug msg="autoplay enabled: false" Oct 27 01:50:26 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:26-04:00" level=debug msg="received connection id: MmMzNzY5ZTYtZWM4OS00YWZiLTk4YWItYzg5NTViMmRmNTgxK2RlYWxlcit0Y3A6Ly8wYWNiZDg0Ny5pcC5ndWMzLnNwb3RpZnkubmV0OjU3MDArNUQ1ODNDQzBFMEQzQjRBQjhDQ0E2QkEyNkJFQTlEMUQxQkQyQTQ2NjU3RTRBMEExMjUyRjM5RkIxMDlDQ0I4MA==" Oct 27 01:50:26 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:26-04:00" level=debug msg="put connect state because NEW_DEVICE" Oct 27 01:50:26 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 27 01:50:26 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 01:50:26 basement-volumio volumio[1189]: info: Received Get System Info Oct 27 01:50:26 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 01:50:26 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 01:50:26 basement-volumio volumio[1189]: info: Discovery: Getting this device information Oct 27 01:50:26 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:50:26 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 01:50:27 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 01:50:27 basement-volumio volumio[1189]: info: Received Get System Info Oct 27 01:50:27 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 01:50:27 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 01:50:27 basement-volumio volumio[1189]: info: Discovery: Getting this device information Oct 27 01:50:27 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:50:27 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 01:50:28 basement-volumio volumio[1189]: info: Initializing connection to go-librespot Websocket Oct 27 01:50:28 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:28-04:00" level=debug msg="new websocket client" Oct 27 01:50:28 basement-volumio volumio[1189]: info: Connection to go-librespot Websocket established Oct 27 01:50:28 basement-volumio volumio[1189]: info: go-librespot daemon successfully initialized Oct 27 01:50:31 basement-volumio volumio[1189]: info: Getting Spotify volume Oct 27 01:50:31 basement-volumio volumio[1189]: info: Spotify volume: 100 Oct 27 01:50:31 basement-volumio volumio[1189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Oct 27 01:50:31 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:50:31 basement-volumio volumio[1189]: info: Initializing connection to go-librespot Websocket Oct 27 01:50:31 basement-volumio go-librespot[2507]: time="2024-10-27T01:50:31-04:00" level=debug msg="new websocket client" Oct 27 01:50:31 basement-volumio volumio[1189]: info: Connection to go-librespot Websocket established Oct 27 01:50:34 basement-volumio volumio[1189]: info: Getting Spotify volume Oct 27 01:50:34 basement-volumio volumio[1189]: info: Spotify volume: 100 Oct 27 01:50:34 basement-volumio volumio[1189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Oct 27 01:50:34 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:50:34 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 27 01:50:35 basement-volumio volumio[1189]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Oct 27 01:50:35 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Oct 27 01:50:35 basement-volumio volumio[1189]: info: Creating Spotify config file Oct 27 01:50:35 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 01:50:35 basement-volumio volumio[1189]: info: Spotify config file written Oct 27 01:50:35 basement-volumio sudo[2555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 27 01:50:35 basement-volumio sudo[2555]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 01:50:35 basement-volumio systemd[1]: Stopping go-librespot Daemon... Oct 27 01:50:35 basement-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Oct 27 01:50:35 basement-volumio systemd[1]: go-librespot-daemon.service: Succeeded. Oct 27 01:50:35 basement-volumio systemd[1]: Stopped go-librespot Daemon. Oct 27 01:50:35 basement-volumio volumio[1189]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 27 01:50:35 basement-volumio volumio[1189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 27 01:50:35 basement-volumio volumio[1189]: info: Connection to go-librespot Websocket closed Oct 27 01:50:35 basement-volumio volumio[1189]: info: Connection to go-librespot Websocket closed Oct 27 01:50:35 basement-volumio systemd[1]: Started go-librespot Daemon. Oct 27 01:50:35 basement-volumio go-librespot[2557]: Librespot-go daemon starting... Oct 27 01:50:35 basement-volumio sudo[2555]: pam_unix(sudo:session): session closed for user root Oct 27 01:50:35 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:35-04:00" level=info msg="generated new device id: 97ae39e775a2dd09a182d5052c780cc4a9b2069e" Oct 27 01:50:35 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:35-04:00" level=debug msg="stored credentials found for $drama" Oct 27 01:50:35 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:35-04:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 01:50:35 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:35-04:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 27 01:50:35 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:35-04:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 27 01:50:35 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:35-04:00" level=debug msg="zeroconf server listening on port 43821" Oct 27 01:50:36 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:36-04:00" level=debug msg="obtained new client token: AADNWxsKr08jPfdFWdQXNoM1QiqIDuBs+aLyu6v1tjggpj8ADmyS9FF4nXVDLnooUONnt9p9W1RfM4VHngtM5boKpUvNbocP1YqXcvdDYvkd8oBs6w22+YfYNPI5kPxRxo4rlTlQvUe2VWlnpOCd4ITusmKaGORskClFgVEytq+Z7+ex+iEiPUQvidkut17FKMcEHXSvpQ2ScfXaxB4cAGFkP2ZkzU0v4NbVOlE7v4aTSKc+VaG9l2pIZlI=" Oct 27 01:50:36 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:36-04:00" level=info msg="connected to ap-guc3.spotify.com:4070" Oct 27 01:50:36 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:36-04:00" level=debug msg="completed keyexchange" Oct 27 01:50:36 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:36-04:00" level=debug msg="completed challenge" Oct 27 01:50:36 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:36-04:00" level=debug msg="authenticated as $drama" Oct 27 01:50:36 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:36-04:00" level=debug msg="authenticated as $drama" Oct 27 01:50:36 basement-volumio volumio[1189]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Oct 27 01:50:36 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Oct 27 01:50:36 basement-volumio volumio[1189]: info: Creating Spotify config file Oct 27 01:50:36 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 01:50:36 basement-volumio volumio[1189]: info: Spotify config file written Oct 27 01:50:36 basement-volumio sudo[2567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 27 01:50:36 basement-volumio sudo[2567]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 01:50:36 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:36-04:00" level=debug msg="dealer connection opened" Oct 27 01:50:36 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:36-04:00" level=debug msg="initializing zeroconf session, username: $drama" Oct 27 01:50:36 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:36-04:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 27 01:50:36 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:36-04:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 27 01:50:36 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:36-04:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 27 01:50:36 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:36-04:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 27 01:50:36 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:36-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 495" Oct 27 01:50:36 basement-volumio go-librespot[2557]: time="2024-10-27T01:50:36-04:00" level=debug msg="autoplay enabled: false" Oct 27 01:50:36 basement-volumio systemd[1]: Stopping go-librespot Daemon... Oct 27 01:50:36 basement-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Oct 27 01:50:36 basement-volumio systemd[1]: go-librespot-daemon.service: Succeeded. Oct 27 01:50:36 basement-volumio systemd[1]: Stopped go-librespot Daemon. Oct 27 01:50:36 basement-volumio volumio[1189]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 27 01:50:36 basement-volumio volumio[1189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 27 01:50:36 basement-volumio systemd[1]: Started go-librespot Daemon. Oct 27 01:50:36 basement-volumio go-librespot[2569]: Librespot-go daemon starting... Oct 27 01:50:36 basement-volumio sudo[2567]: pam_unix(sudo:session): session closed for user root Oct 27 01:50:36 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:36-04:00" level=info msg="generated new device id: 46dbef47de812da8d74e93b05925b3a01cf958f5" Oct 27 01:50:36 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:36-04:00" level=debug msg="stored credentials found for $drama" Oct 27 01:50:37 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:37-04:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 01:50:37 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:37-04:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 27 01:50:37 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:37-04:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 27 01:50:37 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:37-04:00" level=debug msg="zeroconf server listening on port 36915" Oct 27 01:50:37 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:37-04:00" level=debug msg="obtained new client token: AADHDgHR5wbv3RrMVfT9hHOUuLWcdVDt9V91gADVOkkGT0O49DJ1jF/Ig5HRY3q9pJ2sIHlTpxQ8fx9jEPzHm3jTfYTzfq6C1rHAE+lCfRonkl4JmDVaZ5JckexI29nb0EvV65FkMCW5OcE37AXlCGiOMFUATkSvpr82g575FNVZTYmmWg4zE83PZQYfPNa3HhExAwWOQpjcRdEHaekVsnRn62G6qFLlUicXVZ/wRP9W9ttT3y7bPSUo8QM=" Oct 27 01:50:37 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:37-04:00" level=info msg="connected to ap-guc3.spotify.com:4070" Oct 27 01:50:37 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:37-04:00" level=debug msg="completed keyexchange" Oct 27 01:50:37 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:37-04:00" level=debug msg="completed challenge" Oct 27 01:50:37 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:37-04:00" level=debug msg="authenticated as $drama" Oct 27 01:50:37 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:37-04:00" level=debug msg="authenticated as $drama" Oct 27 01:50:38 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:38-04:00" level=debug msg="dealer connection opened" Oct 27 01:50:38 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:38-04:00" level=debug msg="initializing zeroconf session, username: $drama" Oct 27 01:50:38 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:38-04:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 27 01:50:38 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:38-04:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 27 01:50:38 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:38-04:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 27 01:50:38 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:38-04:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 27 01:50:38 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:38-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 505" Oct 27 01:50:38 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:38-04:00" level=debug msg="autoplay enabled: false" Oct 27 01:50:38 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:38-04:00" level=debug msg="received connection id: YmQwY2ZkODUtNTkwOS00ZGY2LTkwNmYtNjJjOWU5YjAwNTYwK2RlYWxlcit0Y3A6Ly8wYWNiZDkxNy5pcC5ndWMzLnNwb3RpZnkubmV0OjU3MDArNDIwNkY3MDBFNUMyNkY1Q0Q3OTE4NjMyMkFEM0YzMzc0OEQzRDFBRUQzMThFMkFGMUI1RDRCQzk4MkY5MUU2NQ==" Oct 27 01:50:38 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:38-04:00" level=debug msg="put connect state because NEW_DEVICE" Oct 27 01:50:38 basement-volumio volumio[1189]: info: Initializing connection to go-librespot Websocket Oct 27 01:50:38 basement-volumio go-librespot[2569]: time="2024-10-27T01:50:38-04:00" level=debug msg="new websocket client" Oct 27 01:50:38 basement-volumio volumio[1189]: info: Connection to go-librespot Websocket established Oct 27 01:50:38 basement-volumio volumio[1189]: info: go-librespot daemon successfully initialized Oct 27 01:50:39 basement-volumio volumio[1189]: info: go-librespot daemon successfully initialized Oct 27 01:50:39 basement-volumio volumio[1189]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Oct 27 01:50:39 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Oct 27 01:50:39 basement-volumio volumio[1189]: info: Creating Spotify config file Oct 27 01:50:39 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 27 01:50:39 basement-volumio volumio[1189]: info: Spotify config file written Oct 27 01:50:39 basement-volumio sudo[2578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 27 01:50:39 basement-volumio sudo[2578]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 27 01:50:39 basement-volumio systemd[1]: Stopping go-librespot Daemon... Oct 27 01:50:40 basement-volumio volumio[1189]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 27 01:50:40 basement-volumio volumio[1189]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 27 01:50:40 basement-volumio volumio[1189]: info: Connection to go-librespot Websocket closed Oct 27 01:50:39 basement-volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Oct 27 01:50:39 basement-volumio systemd[1]: go-librespot-daemon.service: Succeeded. Oct 27 01:50:39 basement-volumio systemd[1]: Stopped go-librespot Daemon. Oct 27 01:50:40 basement-volumio systemd[1]: Started go-librespot Daemon. Oct 27 01:50:40 basement-volumio go-librespot[2580]: Librespot-go daemon starting... Oct 27 01:50:40 basement-volumio sudo[2578]: pam_unix(sudo:session): session closed for user root Oct 27 01:50:40 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:40-04:00" level=info msg="generated new device id: 9caf0411bb007b9c908b6795188e00b528445e9c" Oct 27 01:50:40 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:40-04:00" level=debug msg="stored credentials found for $drama" Oct 27 01:50:40 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:40-04:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 27 01:50:40 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:40-04:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 27 01:50:40 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:40-04:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 27 01:50:40 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:40-04:00" level=debug msg="zeroconf server listening on port 42557" Oct 27 01:50:40 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:40-04:00" level=debug msg="obtained new client token: AABp9boph0i52jWzZT8KwH6t7FoRUQzh6ssXZafRe95U4RnfVz+b7Z8qhKrDVraVHi28nWaLoHiXz2LicqCRH2e0iu17xpNhxjYq5u4GaUGw1vUBbATZZH1YCoZCwfVhduIJNhAnfhNausWDlA13/aopxUOcKd22q1yUN6yX/FN7slZRuzByBEoNbMh2FcUEiBqeWTRPMiBkKNoJXPx00QD02SykkSnTZsVQT9Z3ybZhBR8PQwGQ4stZD/4=" Oct 27 01:50:40 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:40-04:00" level=info msg="connected to ap-guc3.spotify.com:4070" Oct 27 01:50:40 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:40-04:00" level=debug msg="completed keyexchange" Oct 27 01:50:40 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:40-04:00" level=debug msg="completed challenge" Oct 27 01:50:40 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:40-04:00" level=debug msg="authenticated as $drama" Oct 27 01:50:41 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:41-04:00" level=debug msg="authenticated as $drama" Oct 27 01:50:41 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:41-04:00" level=debug msg="dealer connection opened" Oct 27 01:50:41 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:41-04:00" level=debug msg="initializing zeroconf session, username: $drama" Oct 27 01:50:41 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:41-04:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 27 01:50:41 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:41-04:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 27 01:50:41 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:41-04:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 27 01:50:41 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:41-04:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 27 01:50:41 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:41-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 505" Oct 27 01:50:41 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:41-04:00" level=debug msg="autoplay enabled: false" Oct 27 01:50:41 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:41-04:00" level=debug msg="received connection id: MjkxMTRkNjQtNjIzNy00MDUxLWI1MzQtYTdjM2I2MTA1MjRmK2RlYWxlcit0Y3A6Ly8wYWNiZDg3Yy5pcC5ndWMzLnNwb3RpZnkubmV0OjU3MDArMkRDQTI3QTdDRkE5OTg3M0M1NjIyNDMzMzA4MEVGOENCQUQwMkU5MEMxQzVCOThFRTFDMzM0RjczNTIxRTQ3OA==" Oct 27 01:50:41 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:41-04:00" level=debug msg="put connect state because NEW_DEVICE" Oct 27 01:50:41 basement-volumio volumio[1189]: info: Getting Spotify volume Oct 27 01:50:41 basement-volumio volumio[1189]: info: Spotify volume: 100 Oct 27 01:50:41 basement-volumio volumio[1189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Oct 27 01:50:41 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:50:41 basement-volumio volumio[1189]: info: Initializing connection to go-librespot Websocket Oct 27 01:50:41 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:41-04:00" level=debug msg="new websocket client" Oct 27 01:50:41 basement-volumio volumio[1189]: info: Connection to go-librespot Websocket established Oct 27 01:50:42 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 27 01:50:42 basement-volumio volumio[1189]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 27 01:50:42 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 27 01:50:42 basement-volumio volumio[1189]: info: Received Get System Version Oct 27 01:50:42 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 01:50:42 basement-volumio volumio[1189]: info: Received Get System Info Oct 27 01:50:42 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 01:50:42 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 01:50:42 basement-volumio volumio[1189]: info: Discovery: Getting this device information Oct 27 01:50:42 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:50:42 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 01:50:42 basement-volumio volumio[1189]: info: Initializing connection to go-librespot Websocket Oct 27 01:50:42 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:42-04:00" level=debug msg="new websocket client" Oct 27 01:50:42 basement-volumio volumio[1189]: info: Connection to go-librespot Websocket established Oct 27 01:50:42 basement-volumio volumio[1189]: info: Initializing connection to go-librespot Websocket Oct 27 01:50:42 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:42-04:00" level=debug msg="new websocket client" Oct 27 01:50:43 basement-volumio volumio[1189]: info: Connection to go-librespot Websocket established Oct 27 01:50:43 basement-volumio volumio[1189]: info: go-librespot daemon successfully initialized Oct 27 01:50:44 basement-volumio volumio[1189]: info: Getting Spotify volume Oct 27 01:50:44 basement-volumio volumio[1189]: info: Spotify volume: 100 Oct 27 01:50:44 basement-volumio volumio[1189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Oct 27 01:50:44 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:50:45 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 01:50:45 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 27 01:50:45 basement-volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 27 01:50:45 basement-volumio volumio[1189]: info: Getting Spotify volume Oct 27 01:50:45 basement-volumio volumio[1189]: info: Spotify volume: 100 Oct 27 01:50:45 basement-volumio volumio[1189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Oct 27 01:50:45 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:50:46 basement-volumio volumio[1189]: info: Getting Spotify volume Oct 27 01:50:46 basement-volumio volumio[1189]: info: Spotify volume: 100 Oct 27 01:50:46 basement-volumio volumio[1189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Oct 27 01:50:46 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:50:46 basement-volumio volumio[1189]: info: Initializing connection to go-librespot Websocket Oct 27 01:50:46 basement-volumio go-librespot[2580]: time="2024-10-27T01:50:46-04:00" level=debug msg="new websocket client" Oct 27 01:50:46 basement-volumio volumio[1189]: info: Connection to go-librespot Websocket established Oct 27 01:50:47 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 01:50:47 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 01:50:47 basement-volumio volumio[1189]: info: Discovery: Getting this device information Oct 27 01:50:47 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:50:47 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 01:50:49 basement-volumio volumio[1189]: info: Getting Spotify volume Oct 27 01:50:49 basement-volumio volumio[1189]: info: Spotify volume: 100 Oct 27 01:50:49 basement-volumio volumio[1189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Oct 27 01:50:49 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Oct 27 01:50:49 basement-volumio volumio[1189]: info: Stopping peppyspectrum service Oct 27 01:50:49 basement-volumio volumio[1189]: info: CoreStateMachine::stop Oct 27 01:50:49 basement-volumio volumio[1189]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 01:50:49 basement-volumio volumio[1189]: info: CoreStateMachine::updateTrackBlock Oct 27 01:50:49 basement-volumio volumio[1189]: info: CorePlayQueue::getTrackBlock Oct 27 01:50:49 basement-volumio volumio[1189]: info: CoreStateMachine::stPlaybackTimer Oct 27 01:50:49 basement-volumio volumio[1189]: info: CoreStateMachine::pushState Oct 27 01:50:49 basement-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 27 01:50:49 basement-volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 01:50:49 basement-volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Oct 27 01:50:49 basement-volumio volumio[1189]: info: MRS: Pushing multiroomSync output update for this device Oct 27 01:50:49 basement-volumio volumio[1189]: info: MRS: Pushing multiroomSync output Oct 27 01:50:49 basement-volumio volumio[1189]: info: CoreStateMachine::serviceStop Oct 27 01:50:49 basement-volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Oct 27 01:50:49 basement-volumio volumio[1189]: info: CoreCommandRouter::serviceStop Oct 27 01:50:49 basement-volumio volumio[1189]: error: WARNING: No stop method for service motherearthradio Oct 27 01:50:49 basement-volumio volumio[1189]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 01:50:49 basement-volumio volumio[1189]: TypeError: Cannot read property 'then' of undefined Oct 27 01:50:49 basement-volumio volumio[1189]: at peppyspectrum.onStop (/data/plugins/user_interface/peppyspectrum/index.js:60:43) Oct 27 01:50:49 basement-volumio volumio[1189]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Oct 27 01:50:49 basement-volumio volumio[1189]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Oct 27 01:50:49 basement-volumio volumio[1189]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1537:29) Oct 27 01:50:49 basement-volumio volumio[1189]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1387:47) Oct 27 01:50:49 basement-volumio volumio[1189]: at Socket.emit (events.js:315:20) Oct 27 01:50:49 basement-volumio volumio[1189]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 27 01:50:49 basement-volumio volumio[1189]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 27 01:50:49 basement-volumio volumio[1189]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 01:50:50 basement-volumio sudo[2599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-27 01:49 Oct 27 01:50:50 basement-volumio sudo[2599]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"