-- Logs begin at Wed 2025-04-23 16:50:26 CEST, end at Wed 2025-04-23 17:02:54 CEST. -- Apr 23 17:01:03 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 0 Apr 23 17:01:03 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:01:03 volumiostue volumio[1060]: info: Prefetching next song Apr 23 17:01:03 volumiostue volumio[1060]: info: [1745420463086] ControllerSpotify::prefetch Apr 23 17:01:03 volumiostue volumio[1060]: info: Sending Spotify command with payload to local API: /player/add_to_queue Apr 23 17:01:03 volumiostue go-librespot[3540]: time="2025-04-23T17:01:03+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 23 17:01:07 volumiostue volumio[1060]: info: CoreStateMachine::startPlaybackTimer Apr 23 17:01:07 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:01:08 volumiostue volumio[1060]: info: CoreStateMachine::pushState Apr 23 17:01:08 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:01:08 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 17:01:08 volumiostue volumio[1060]: info: CoreCommandRouter::volumioPushState Apr 23 17:01:08 volumiostue volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Apr 23 17:01:08 volumiostue volumio[1060]: info: MRS: Pushing multiroomSync output Apr 23 17:01:08 volumiostue volumio[1060]: info: PeppySpectrum ---peppyspectrum status play Apr 23 17:01:08 volumiostue sudo[4367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Apr 23 17:01:08 volumiostue sudo[4367]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:01:08 volumiostue sudo[4367]: pam_unix(sudo:session): session closed for user root Apr 23 17:01:08 volumiostue volumio[1060]: info: peppyspectrum Daemon Started Apr 23 17:01:25 volumiostue go-librespot[3540]: time="2025-04-23T17:01:25+02:00" level=trace msg="sent dealer ping" Apr 23 17:01:25 volumiostue go-librespot[3540]: time="2025-04-23T17:01:25+02:00" level=trace msg="received dealer pong" Apr 23 17:01:28 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 17:01:28 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 17:01:28 volumiostue volumio[1060]: info: Discovery: Getting this device information Apr 23 17:01:28 volumiostue volumio[1060]: info: CoreCommandRouter::volumioGetState Apr 23 17:01:28 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:01:28 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 17:01:28 volumiostue volumio[1060]: verbose: New Socket.io Connection to 192.168.1.199:3000 from 192.168.1.190 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 23 17:01:28 volumiostue volumio[1060]: info: CoreCommandRouter::volumioGetState Apr 23 17:01:28 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:01:28 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 23 17:01:28 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 23 17:01:28 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 17:01:28 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 17:01:28 volumiostue volumio[1060]: info: Discovery: Getting this device information Apr 23 17:01:28 volumiostue volumio[1060]: info: CoreCommandRouter::volumioGetState Apr 23 17:01:28 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:01:28 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 17:01:35 volumiostue sudo[4450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 23 17:01:35 volumiostue sudo[4450]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:01:35 volumiostue sudo[4450]: pam_unix(sudo:session): session closed for user root Apr 23 17:01:35 volumiostue sudo[4453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 23 17:01:35 volumiostue sudo[4453]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:01:35 volumiostue sudo[4453]: pam_unix(sudo:session): session closed for user root Apr 23 17:01:36 volumiostue volumio[1060]: verbose: New Socket.io Connection to 192.168.1.199 from 192.168.1.190 UA: Mozilla/5.0 (Linux; Android 14; SM-A256B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 23 17:01:37 volumiostue sudo[4470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 23 17:01:37 volumiostue sudo[4470]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:01:37 volumiostue sudo[4470]: pam_unix(sudo:session): session closed for user root Apr 23 17:01:37 volumiostue sudo[4473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 23 17:01:37 volumiostue sudo[4473]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:01:37 volumiostue sudo[4473]: pam_unix(sudo:session): session closed for user root Apr 23 17:01:37 volumiostue volumio[1060]: verbose: New Socket.io Connection to 192.168.1.199 from 192.168.1.190 UA: Mozilla/5.0 (Linux; Android 14; SM-A256B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Apr 23 17:01:38 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 23 17:01:38 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 23 17:01:38 volumiostue volumio[1060]: info: CoreCommandRouter::volumioGetVisibleSources Apr 23 17:01:38 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 23 17:01:38 volumiostue volumio[1060]: info: CoreCommandRouter::volumioGetState Apr 23 17:01:38 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:01:38 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 23 17:01:38 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 23 17:01:38 volumiostue volumio[1060]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 23 17:01:38 volumiostue volumio[1060]: info: Received Get System Info Apr 23 17:01:38 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 17:01:38 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 17:01:38 volumiostue volumio[1060]: info: Discovery: Getting this device information Apr 23 17:01:38 volumiostue volumio[1060]: info: CoreCommandRouter::volumioGetState Apr 23 17:01:38 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:01:38 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 17:01:38 volumiostue volumio[1060]: info: CoreCommandRouter::volumioGetState Apr 23 17:01:38 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:01:38 volumiostue volumio[1060]: info: Listing playlists Apr 23 17:01:38 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 23 17:01:38 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 23 17:01:38 volumiostue volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 23 17:01:38 volumiostue volumio[1060]: info: FusionDsp - no preset used Apr 23 17:01:38 volumiostue volumio[1060]: info: FusionDsp - undefined Apr 23 17:01:39 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 23 17:01:39 volumiostue volumio[1060]: info: Received Get System Info Apr 23 17:01:39 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 17:01:39 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 17:01:39 volumiostue volumio[1060]: info: Discovery: Getting this device information Apr 23 17:01:39 volumiostue volumio[1060]: info: CoreCommandRouter::volumioGetState Apr 23 17:01:39 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:01:39 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 17:01:40 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 23 17:01:40 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 23 17:01:40 volumiostue volumio[1060]: info: Received Get System Info Apr 23 17:01:40 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 17:01:40 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 17:01:40 volumiostue volumio[1060]: info: Discovery: Getting this device information Apr 23 17:01:40 volumiostue volumio[1060]: info: CoreCommandRouter::volumioGetState Apr 23 17:01:40 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:01:40 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 17:01:42 volumiostue volumio[1060]: info: CoreCommandRouter::volumioGetQueue Apr 23 17:01:42 volumiostue volumio[1060]: info: CoreStateMachine::getQueue Apr 23 17:01:42 volumiostue volumio[1060]: info: CorePlayQueue::getQueue Apr 23 17:01:42 volumiostue volumio[1060]: info: Executing endpoint metavolumio Apr 23 17:01:42 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 23 17:01:42 volumiostue volumio[1060]: info: Executing endpoint metavolumio Apr 23 17:01:42 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 23 17:01:44 volumiostue volumio[1060]: error: Failed request for metavolumio API Apr 23 17:01:47 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Apr 23 17:01:47 volumiostue volumio[1060]: info: In handleBrowseUri, curUri=spotify Apr 23 17:01:47 volumiostue volumio[1060]: info: Preload queue cleared Apr 23 17:01:47 volumiostue volumio[1060]: info: Preload queue cleared Apr 23 17:01:47 volumiostue volumio[1060]: info: Preload queue cleared Apr 23 17:01:47 volumiostue volumio[1060]: info: Preload queue cleared Apr 23 17:01:47 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 23 17:01:49 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Apr 23 17:01:49 volumiostue volumio[1060]: info: In handleBrowseUri, curUri=spotify/myalbums Apr 23 17:01:55 volumiostue go-librespot[3540]: time="2025-04-23T17:01:55+02:00" level=trace msg="sent dealer ping" Apr 23 17:01:55 volumiostue go-librespot[3540]: time="2025-04-23T17:01:55+02:00" level=trace msg="received dealer pong" Apr 23 17:01:55 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 23 17:01:55 volumiostue volumio[1060]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 23 17:01:55 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 23 17:01:55 volumiostue volumio[1060]: info: Received Get System Version Apr 23 17:01:55 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 23 17:01:55 volumiostue volumio[1060]: info: Received Get System Info Apr 23 17:01:55 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 17:01:55 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 17:01:55 volumiostue volumio[1060]: info: Discovery: Getting this device information Apr 23 17:01:55 volumiostue volumio[1060]: info: CoreCommandRouter::volumioGetState Apr 23 17:01:55 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:01:55 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 17:01:56 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 17:01:56 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 17:01:56 volumiostue volumio[1060]: info: Discovery: Getting this device information Apr 23 17:01:56 volumiostue volumio[1060]: info: CoreCommandRouter::volumioGetState Apr 23 17:01:56 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:01:56 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 17:02:03 volumiostue volumio[1060]: info: Preload queue cleared Apr 23 17:02:07 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Apr 23 17:02:07 volumiostue volumio[1060]: info: In handleBrowseUri, curUri=spotify:album:2ygWIY688V8MRWYMAWetkk Apr 23 17:02:07 volumiostue volumio[1060]: info: Preload queue cleared Apr 23 17:02:08 volumiostue volumio[1060]: info: Executing endpoint getSimilarAlbums Apr 23 17:02:08 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Apr 23 17:02:08 volumiostue volumio[1060]: info: Executing endpoint metavolumio Apr 23 17:02:08 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 23 17:02:08 volumiostue volumio[1060]: info: Executing endpoint metavolumio Apr 23 17:02:08 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 23 17:02:10 volumiostue volumio[1060]: info: Preload queue cleared Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreStateMachine::ClearQueue Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreStateMachine::stop Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreStateMachine::stPlaybackTimer Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreStateMachine::updateTrackBlock Apr 23 17:02:10 volumiostue volumio[1060]: info: CorePlayQueue::getTrackBlock Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreStateMachine::pushState Apr 23 17:02:10 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreCommandRouter::volumioPushState Apr 23 17:02:10 volumiostue volumio[1060]: info: MRS: Pushing multiroomSync output update for this device Apr 23 17:02:10 volumiostue volumio[1060]: info: MRS: Pushing multiroomSync output Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreStateMachine::serviceStop Apr 23 17:02:10 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreCommandRouter::serviceStop Apr 23 17:02:10 volumiostue volumio[1060]: info: Spotify Stop Apr 23 17:02:10 volumiostue volumio[1060]: SPOTIFY: SPOTIFY STOP Apr 23 17:02:10 volumiostue volumio[1060]: SPOTIFY: {"status":"play","position":1,"title":"Part Two","artist":"iskwē","album":"nīna","albumart":"https://i.scdn.co/image/ab67616d0000b273a3fc716ae550bd2f61626001","uri":"spotify:track:5sgggSQ6W2RNkBlqHRuDYM","trackType":"spotify","codec":"ogg","seek":251,"duration":254,"samplerate":"320 kbps","bitdepth":"16 bit","random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":true,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Apr 23 17:02:10 volumiostue volumio[1060]: info: Sending Spotify command to local API: /player/pause Apr 23 17:02:10 volumiostue volumio[1060]: info: CorePlayQueue::clearPlayQueue Apr 23 17:02:10 volumiostue volumio[1060]: info: CorePlayQueue::saveQueue Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreCommandRouter::volumioPushQueue Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreStateMachine::addQueueItems Apr 23 17:02:10 volumiostue volumio[1060]: info: CorePlayQueue::addQueueItems Apr 23 17:02:10 volumiostue volumio[1060]: info: Preload queue cleared Apr 23 17:02:10 volumiostue volumio[1060]: info: Adding Item to queue: spotify:album:2ygWIY688V8MRWYMAWetkk Apr 23 17:02:10 volumiostue volumio[1060]: info: Using cached record of: spotify:album:2ygWIY688V8MRWYMAWetkk Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreCommandRouter::volumioPushQueue Apr 23 17:02:10 volumiostue volumio[1060]: info: CorePlayQueue::saveQueue Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreStateMachine::updateTrackBlock Apr 23 17:02:10 volumiostue volumio[1060]: info: CorePlayQueue::getTrackBlock Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreCommandRouter::volumioPlay Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreStateMachine::play index 0 Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreStateMachine::stop Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreStateMachine::play index undefined Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 17:02:10 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 0 Apr 23 17:02:10 volumiostue volumio[1060]: info: CoreStateMachine::startPlaybackTimer Apr 23 17:02:10 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 0 Apr 23 17:02:10 volumiostue volumio[1060]: info: [1745420530951] ControllerSpotify::clearAddPlayTrack Apr 23 17:02:10 volumiostue volumio[1060]: info: Sending Spotify command with payload to local API: /player/play Apr 23 17:02:10 volumiostue volumio[1060]: info: PeppySpectrum ---peppyspectrum status stop Apr 23 17:02:10 volumiostue sudo[4528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 23 17:02:10 volumiostue sudo[4528]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 23 17:02:10 volumiostue systemd[1]: Stopping peppyspectrum Daemon... Apr 23 17:02:10 volumiostue systemd[1]: peppyspectrum.service: Main process exited, code=killed, status=15/TERM Apr 23 17:02:10 volumiostue volumio[1060]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 23 17:02:11 volumiostue systemd[1]: peppyspectrum.service: Succeeded. Apr 23 17:02:11 volumiostue systemd[1]: Stopped peppyspectrum Daemon. Apr 23 17:02:11 volumiostue sudo[4528]: pam_unix(sudo:session): session closed for user root Apr 23 17:02:11 volumiostue volumio[1060]: info: peppyspectrum Daemon Stop Apr 23 17:02:15 volumiostue volumio[1060]: info: Preload queue cleared Apr 23 17:02:15 volumiostue volumio[1060]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 23 17:02:15 volumiostue volumio[1060]: info: CoreStateMachine::ClearQueue Apr 23 17:02:15 volumiostue volumio[1060]: info: CoreStateMachine::stop Apr 23 17:02:15 volumiostue volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 17:02:15 volumiostue volumio[1060]: info: CorePlayQueue::clearPlayQueue Apr 23 17:02:15 volumiostue volumio[1060]: info: CorePlayQueue::saveQueue Apr 23 17:02:15 volumiostue volumio[1060]: info: CoreCommandRouter::volumioPushQueue Apr 23 17:02:15 volumiostue volumio[1060]: info: CoreStateMachine::addQueueItems Apr 23 17:02:15 volumiostue volumio[1060]: info: CorePlayQueue::addQueueItems Apr 23 17:02:15 volumiostue volumio[1060]: info: Preload queue cleared Apr 23 17:02:15 volumiostue volumio[1060]: info: Adding Item to queue: spotify:album:2ygWIY688V8MRWYMAWetkk Apr 23 17:02:15 volumiostue volumio[1060]: info: Using cached record of: spotify:album:2ygWIY688V8MRWYMAWetkk Apr 23 17:02:15 volumiostue volumio[1060]: info: CoreCommandRouter::volumioPushQueue Apr 23 17:02:15 volumiostue volumio[1060]: info: CorePlayQueue::saveQueue Apr 23 17:02:15 volumiostue volumio[1060]: info: CoreStateMachine::updateTrackBlock Apr 23 17:02:15 volumiostue volumio[1060]: info: CorePlayQueue::getTrackBlock Apr 23 17:02:15 volumiostue volumio[1060]: info: CoreCommandRouter::volumioPlay Apr 23 17:02:15 volumiostue volumio[1060]: info: CoreStateMachine::play index 0 Apr 23 17:02:15 volumiostue volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 17:02:15 volumiostue volumio[1060]: info: CoreStateMachine::stop Apr 23 17:02:15 volumiostue volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 17:02:15 volumiostue volumio[1060]: info: CoreStateMachine::play index undefined Apr 23 17:02:15 volumiostue volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 17:02:15 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 0 Apr 23 17:02:15 volumiostue volumio[1060]: info: CoreStateMachine::startPlaybackTimer Apr 23 17:02:15 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 0 Apr 23 17:02:15 volumiostue volumio[1060]: info: [1745420535568] ControllerSpotify::clearAddPlayTrack Apr 23 17:02:15 volumiostue volumio[1060]: info: Sending Spotify command with payload to local API: /player/play Apr 23 17:02:18 volumiostue volumio[1060]: info: CoreCommandRouter::volumioPlay Apr 23 17:02:18 volumiostue volumio[1060]: info: CoreStateMachine::play index undefined Apr 23 17:02:18 volumiostue volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 17:02:18 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 0 Apr 23 17:02:18 volumiostue volumio[1060]: info: CoreStateMachine::startPlaybackTimer Apr 23 17:02:18 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 0 Apr 23 17:02:18 volumiostue volumio[1060]: info: [1745420538157] ControllerSpotify::clearAddPlayTrack Apr 23 17:02:18 volumiostue volumio[1060]: info: Sending Spotify command with payload to local API: /player/play Apr 23 17:02:20 volumiostue volumio[1060]: info: CoreCommandRouter::volumioPlay Apr 23 17:02:20 volumiostue volumio[1060]: info: CoreStateMachine::play index undefined Apr 23 17:02:20 volumiostue volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 17:02:20 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 0 Apr 23 17:02:20 volumiostue volumio[1060]: info: CoreStateMachine::startPlaybackTimer Apr 23 17:02:20 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 0 Apr 23 17:02:20 volumiostue volumio[1060]: info: [1745420540204] ControllerSpotify::clearAddPlayTrack Apr 23 17:02:20 volumiostue volumio[1060]: info: Sending Spotify command with payload to local API: /player/play Apr 23 17:02:22 volumiostue volumio[1060]: info: CoreCommandRouter::volumioPlay Apr 23 17:02:22 volumiostue volumio[1060]: info: CoreStateMachine::play index undefined Apr 23 17:02:22 volumiostue volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 17:02:22 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 0 Apr 23 17:02:22 volumiostue volumio[1060]: info: CoreStateMachine::startPlaybackTimer Apr 23 17:02:22 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 0 Apr 23 17:02:22 volumiostue volumio[1060]: info: [1745420542057] ControllerSpotify::clearAddPlayTrack Apr 23 17:02:22 volumiostue volumio[1060]: info: Sending Spotify command with payload to local API: /player/play Apr 23 17:02:22 volumiostue volumio[1060]: info: CoreCommandRouter::volumioPlay Apr 23 17:02:22 volumiostue volumio[1060]: info: CoreStateMachine::play index undefined Apr 23 17:02:22 volumiostue volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 17:02:22 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 0 Apr 23 17:02:22 volumiostue volumio[1060]: info: CoreStateMachine::startPlaybackTimer Apr 23 17:02:22 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 0 Apr 23 17:02:22 volumiostue volumio[1060]: info: [1745420542411] ControllerSpotify::clearAddPlayTrack Apr 23 17:02:22 volumiostue volumio[1060]: info: Sending Spotify command with payload to local API: /player/play Apr 23 17:02:24 volumiostue go-librespot[3540]: time="2025-04-23T17:02:24+02:00" level=trace msg="received accesspoint ping" Apr 23 17:02:24 volumiostue go-librespot[3540]: time="2025-04-23T17:02:24+02:00" level=trace msg="received accesspoint pong ack" Apr 23 17:02:25 volumiostue go-librespot[3540]: time="2025-04-23T17:02:25+02:00" level=trace msg="sent dealer ping" Apr 23 17:02:25 volumiostue go-librespot[3540]: time="2025-04-23T17:02:25+02:00" level=trace msg="received dealer pong" Apr 23 17:02:26 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 23 17:02:26 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 23 17:02:26 volumiostue volumio[1060]: info: Discovery: Getting this device information Apr 23 17:02:26 volumiostue volumio[1060]: info: CoreCommandRouter::volumioGetState Apr 23 17:02:26 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 0 Apr 23 17:02:26 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 23 17:02:29 volumiostue volumio[1060]: info: Executing endpoint metavolumio Apr 23 17:02:29 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 23 17:02:29 volumiostue volumio[1060]: info: Executing endpoint metavolumio Apr 23 17:02:29 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 23 17:02:31 volumiostue volumio[1060]: info: Preload queue cleared Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreStateMachine::ClearQueue Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreStateMachine::stop Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 17:02:31 volumiostue volumio[1060]: info: CorePlayQueue::clearPlayQueue Apr 23 17:02:31 volumiostue volumio[1060]: info: CorePlayQueue::saveQueue Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreCommandRouter::volumioPushQueue Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreStateMachine::addQueueItems Apr 23 17:02:31 volumiostue volumio[1060]: info: CorePlayQueue::addQueueItems Apr 23 17:02:31 volumiostue volumio[1060]: info: Preload queue cleared Apr 23 17:02:31 volumiostue volumio[1060]: info: Adding Item to queue: spotify:track:7CjQQQyNLHwXRTYpDCGPPv Apr 23 17:02:31 volumiostue volumio[1060]: info: Using cached record of: spotify:track:7CjQQQyNLHwXRTYpDCGPPv Apr 23 17:02:31 volumiostue volumio[1060]: info: Adding Item to queue: spotify:track:5sgggSQ6W2RNkBlqHRuDYM Apr 23 17:02:31 volumiostue volumio[1060]: info: Using cached record of: spotify:track:5sgggSQ6W2RNkBlqHRuDYM Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreCommandRouter::volumioPushQueue Apr 23 17:02:31 volumiostue volumio[1060]: info: CorePlayQueue::saveQueue Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreStateMachine::updateTrackBlock Apr 23 17:02:31 volumiostue volumio[1060]: info: CorePlayQueue::getTrackBlock Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreCommandRouter::volumioPlay Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreStateMachine::play index 1 Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreStateMachine::addQueueItems Apr 23 17:02:31 volumiostue volumio[1060]: info: CorePlayQueue::addQueueItems Apr 23 17:02:31 volumiostue volumio[1060]: info: Preload queue cleared Apr 23 17:02:31 volumiostue volumio[1060]: info: Adding Item to queue: spotify:track:16EKi2PlYNs2aRo5xURkMQ Apr 23 17:02:31 volumiostue volumio[1060]: info: Using cached record of: spotify:track:16EKi2PlYNs2aRo5xURkMQ Apr 23 17:02:31 volumiostue volumio[1060]: info: Adding Item to queue: spotify:track:27ZziAnjKakYBRpVAWWllB Apr 23 17:02:31 volumiostue volumio[1060]: info: Using cached record of: spotify:track:27ZziAnjKakYBRpVAWWllB Apr 23 17:02:31 volumiostue volumio[1060]: info: Adding Item to queue: spotify:track:12IrBIcPheoImJ8xDcIBp8 Apr 23 17:02:31 volumiostue volumio[1060]: info: Using cached record of: spotify:track:12IrBIcPheoImJ8xDcIBp8 Apr 23 17:02:31 volumiostue volumio[1060]: info: Adding Item to queue: spotify:track:2IERoGGpKV9SB3lYZJcpOp Apr 23 17:02:31 volumiostue volumio[1060]: info: Using cached record of: spotify:track:2IERoGGpKV9SB3lYZJcpOp Apr 23 17:02:31 volumiostue volumio[1060]: info: Adding Item to queue: spotify:track:39XSIFaF4GzKhbSN7Hvm4o Apr 23 17:02:31 volumiostue volumio[1060]: info: Using cached record of: spotify:track:39XSIFaF4GzKhbSN7Hvm4o Apr 23 17:02:31 volumiostue volumio[1060]: info: Adding Item to queue: spotify:track:5dISwYarNd1vZTimJm9zb9 Apr 23 17:02:31 volumiostue volumio[1060]: info: Using cached record of: spotify:track:5dISwYarNd1vZTimJm9zb9 Apr 23 17:02:31 volumiostue volumio[1060]: info: Adding Item to queue: spotify:track:54wmsc7INyootVxiXJZhUC Apr 23 17:02:31 volumiostue volumio[1060]: info: Using cached record of: spotify:track:54wmsc7INyootVxiXJZhUC Apr 23 17:02:31 volumiostue volumio[1060]: info: Adding Item to queue: spotify:track:6qvtMXQ1Rs4ogjttAf2Ft7 Apr 23 17:02:31 volumiostue volumio[1060]: info: Using cached record of: spotify:track:6qvtMXQ1Rs4ogjttAf2Ft7 Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreStateMachine::stop Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreCommandRouter::volumioPushQueue Apr 23 17:02:31 volumiostue volumio[1060]: info: CorePlayQueue::saveQueue Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreStateMachine::play index undefined Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreStateMachine::updateTrackBlock Apr 23 17:02:31 volumiostue volumio[1060]: info: CorePlayQueue::getTrackBlock Apr 23 17:02:31 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:02:31 volumiostue volumio[1060]: info: CoreStateMachine::startPlaybackTimer Apr 23 17:02:31 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:02:31 volumiostue volumio[1060]: info: [1745420551777] ControllerSpotify::clearAddPlayTrack Apr 23 17:02:31 volumiostue volumio[1060]: info: Sending Spotify command with payload to local API: /player/play Apr 23 17:02:35 volumiostue volumio[1060]: info: CoreCommandRouter::volumioPlay Apr 23 17:02:35 volumiostue volumio[1060]: info: CoreStateMachine::play index undefined Apr 23 17:02:35 volumiostue volumio[1060]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 23 17:02:35 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:02:35 volumiostue volumio[1060]: info: CoreStateMachine::startPlaybackTimer Apr 23 17:02:35 volumiostue volumio[1060]: info: CorePlayQueue::getTrack 1 Apr 23 17:02:35 volumiostue volumio[1060]: info: [1745420555275] ControllerSpotify::clearAddPlayTrack Apr 23 17:02:35 volumiostue volumio[1060]: info: Sending Spotify command with payload to local API: /player/play Apr 23 17:02:40 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 23 17:02:40 volumiostue volumio[1060]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 23 17:02:40 volumiostue volumio[1060]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 23 17:02:40 volumiostue volumio[1060]: info: FusionDsp - no preset used Apr 23 17:02:41 volumiostue volumio[1060]: info: FusionDsp - undefined Apr 23 17:02:54 volumiostue volumio[1060]: info: Starting Uninstall of plugin audio_interface - fusiondsp Apr 23 17:02:54 volumiostue volumio[1060]: info: Uninstalling plugin fusiondsp Apr 23 17:02:54 volumiostue volumio[1060]: info: FusionDsp - Stopping FusionDsp service Apr 23 17:02:54 volumiostue volumio[1060]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 17:02:54 volumiostue volumio[1060]: TypeError: Cannot read property 'stop' of null Apr 23 17:02:54 volumiostue volumio[1060]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Apr 23 17:02:54 volumiostue volumio[1060]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Apr 23 17:02:54 volumiostue volumio[1060]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Apr 23 17:02:54 volumiostue volumio[1060]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Apr 23 17:02:54 volumiostue volumio[1060]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) Apr 23 17:02:54 volumiostue volumio[1060]: at Socket.emit (events.js:400:28) Apr 23 17:02:54 volumiostue volumio[1060]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 23 17:02:54 volumiostue volumio[1060]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 23 17:02:54 volumiostue volumio[1060]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 23 17:02:54 volumiostue sudo[4650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-23 17:01 Apr 23 17:02:54 volumiostue sudo[4650]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"