-- Logs begin at Thu 2025-04-24 17:23:27 AEST, end at Thu 2025-04-24 17:25:51 AEST. -- Apr 24 17:24:00 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:24:00 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:24:00 audiopi volumio[1276]: info: Discovery: Getting this device information Apr 24 17:24:00 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:24:00 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:00 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:24:01 audiopi sudo[1599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 24 17:24:01 audiopi sudo[1599]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:01 audiopi sudo[1599]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:01 audiopi sudo[1601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 24 17:24:01 audiopi sudo[1601]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:01 audiopi sudo[1601]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:01 audiopi sudo[1605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 24 17:24:01 audiopi sudo[1605]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:01 audiopi sudo[1605]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:01 audiopi sudo[1607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 24 17:24:01 audiopi sudo[1607]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:01 audiopi sudo[1607]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:02 audiopi volumio[1276]: verbose: New Socket.io Connection to 192.168.0.159 from 192.168.0.194 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Apr 24 17:24:02 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 24 17:24:02 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetVisibleSources Apr 24 17:24:02 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 17:24:02 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:24:02 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:02 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 24 17:24:02 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 24 17:24:02 audiopi volumio[1276]: info: Received Get System Info Apr 24 17:24:02 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:24:02 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:24:02 audiopi volumio[1276]: info: Discovery: Getting this device information Apr 24 17:24:02 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:24:02 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:02 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:24:02 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:24:02 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:02 audiopi volumio[1276]: info: Listing playlists Apr 24 17:24:02 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 24 17:24:02 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 24 17:24:02 audiopi volumio[1582]: .................................................................................................................++++ Apr 24 17:24:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 24 17:24:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 24 17:24:03 audiopi volumio[1276]: info: Received Get System Info Apr 24 17:24:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:24:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:24:03 audiopi volumio[1276]: info: Discovery: Getting this device information Apr 24 17:24:03 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:24:03 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:24:04 audiopi wpa_supplicant[1078]: RRM: Ignoring radio measurement request: Not RRM network Apr 24 17:24:06 audiopi volumio[1582]: .....................................................................................++++ Apr 24 17:24:06 audiopi volumio[1582]: e is 65537 (0x010001) Apr 24 17:24:06 audiopi volumio[1582]: writing RSA key Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 24 17:24:08 audiopi volumio[1276]: info: Adding plugin bluetooth to MyMusic Plugins Apr 24 17:24:08 audiopi volumio[1276]: info: Adding plugin multiroom to MyMusic Plugins Apr 24 17:24:08 audiopi volumio[1276]: info: Adding plugin metavolumio to MyMusic Plugins Apr 24 17:24:08 audiopi volumio[1276]: info: Adding plugin cd_controller to MyMusic Plugins Apr 24 17:24:08 audiopi volumio[1276]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 24 17:24:08 audiopi volumio[1276]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 24 17:24:08 audiopi volumio[1276]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 24 17:24:08 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:24:08 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:24:08 audiopi volumio[1276]: info: Starting MyVolumio Remote Streaming Endpoints Apr 24 17:24:08 audiopi volumio[1276]: info: MyVolumio login type: Token Apr 24 17:24:09 audiopi volumio[1276]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 24 17:24:09 audiopi volumio[1276]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 24 17:24:09 audiopi volumio[1276]: info: Starting Streaming Service Transparent Proxy Apr 24 17:24:09 audiopi volumio[1276]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 24 17:24:09 audiopi volumio[1276]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 24 17:24:09 audiopi volumio[1276]: info: Streaming services startup Apr 24 17:24:09 audiopi volumio[1276]: info: Starting Streaming Daemon Apr 24 17:24:09 audiopi sudo[1649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 24 17:24:09 audiopi sudo[1649]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:09 audiopi sudo[1649]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:09 audiopi volumio[1276]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 24 17:24:09 audiopi volumio[1276]: error: Cannot start Volumio Streaming Daemon Apr 24 17:24:09 audiopi volumio[1276]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 24 17:24:09 audiopi volumio[1276]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 24 17:24:09 audiopi volumio[1276]: STREAMING PROXY: Starting server on port 3245 Apr 24 17:24:09 audiopi volumio[1276]: Node JS runtime: 14 Apr 24 17:24:09 audiopi volumio[1276]: error: MyVolumio Custom Token format not valid, refreshing it Apr 24 17:24:10 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 24 17:24:10 audiopi volumio[1276]: info: Received Get System Info Apr 24 17:24:10 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:24:10 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:24:10 audiopi volumio[1276]: info: Discovery: Getting this device information Apr 24 17:24:10 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:24:10 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:10 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:24:11 audiopi volumio[1276]: info: MyVolumio login type: Token Apr 24 17:24:11 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 24 17:24:11 audiopi volumio[1276]: info: MyVolumio login type: Token Apr 24 17:24:12 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 17:24:12 audiopi volumio[1276]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 24 17:24:13 audiopi volumio[1276]: info: MyVolumio token set successfully Apr 24 17:24:13 audiopi volumio[1276]: info: MYVOLUMIO: Adding device Apr 24 17:24:13 audiopi volumio[1276]: info: MYVOLUMIO: Evaluating Server Apr 24 17:24:13 audiopi volumio[1276]: info: MyVolumio status changed Apr 24 17:24:13 audiopi volumio[1276]: info: Streaming services startup Apr 24 17:24:13 audiopi volumio[1276]: info: Starting Streaming Daemon Apr 24 17:24:13 audiopi volumio[1276]: info: Removing browser output: myVolumio user plan is not superstar Apr 24 17:24:13 audiopi volumio[1276]: info: Removing audio output: Apr 24 17:24:13 audiopi volumio[1276]: info: Stoppping Tunnel 1 Apr 24 17:24:13 audiopi sudo[1674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 24 17:24:13 audiopi sudo[1674]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:13 audiopi sudo[1677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 24 17:24:13 audiopi sudo[1677]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:13 audiopi sudo[1674]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:13 audiopi volumio[1276]: error: Cannot start Volumio Streaming Daemon Apr 24 17:24:13 audiopi volumio[1276]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 24 17:24:13 audiopi volumio[1276]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 24 17:24:13 audiopi sudo[1677]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:13 audiopi volumio[1276]: info: Remote SSH Stopped Apr 24 17:24:14 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:14 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:14 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:14 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:14 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:14 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:14 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Apr 24 17:24:14 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 29 Apr 24 17:24:14 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 30 Apr 24 17:24:14 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 24 17:24:14 audiopi sudo[1681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:14 audiopi sudo[1681]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:14 audiopi sudo[1681]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:14 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:14 audiopi wpa_supplicant[1078]: RRM: Ignoring radio measurement request: Not RRM network Apr 24 17:24:14 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:14 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:14 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:14 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:14 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:14 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:14 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 31 Apr 24 17:24:14 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 29 Apr 24 17:24:14 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 31 Apr 24 17:24:14 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 24 17:24:14 audiopi volumio[1276]: info: Setting Spotify Volume from Volumio: 31 Apr 24 17:24:14 audiopi sudo[1685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:14 audiopi sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:14 audiopi sudo[1685]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:14 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:14 audiopi volumio[1276]: info: Setting Geolocation for MyVolumio to au1 Apr 24 17:24:14 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:24:14 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:24:14 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:24:14 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 24 17:24:14 audiopi volumio[1276]: info: Received Get System Info Apr 24 17:24:14 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:24:14 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:24:14 audiopi volumio[1276]: info: Discovery: Getting this device information Apr 24 17:24:14 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:24:14 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:14 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:24:15 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:15 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:15 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:15 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:15 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:15 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:15 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Apr 24 17:24:15 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 31 Apr 24 17:24:15 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 32 Apr 24 17:24:15 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 24 17:24:15 audiopi sudo[1689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:15 audiopi sudo[1689]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:15 audiopi sudo[1689]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:15 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:15 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 24 17:24:15 audiopi volumio[1276]: info: Received Get System Info Apr 24 17:24:15 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:24:15 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:24:15 audiopi volumio[1276]: info: Discovery: Getting this device information Apr 24 17:24:15 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:24:15 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:15 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:24:15 audiopi volumio[1276]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 24 17:24:15 audiopi volumio[1276]: info: Updating MyVolumio device info Apr 24 17:24:15 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:24:15 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:24:15 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:24:16 audiopi volumio[1276]: SPOTIFY: SETTING SPOTIFY VOLUME 31 Apr 24 17:24:16 audiopi volumio[1276]: info: Sending Spotify command with payload to local API: /player/volume Apr 24 17:24:16 audiopi volumio[1276]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 24 17:24:17 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:17 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:17 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:17 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:17 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:17 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:17 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:17 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:17 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:17 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:17 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:17 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:17 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:17 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:17 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:17 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:17 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:17 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:17 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:17 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:17 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:17 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:17 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:17 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:17 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:17 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:17 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:17 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:17 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:17 audiopi sudo[1715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:17 audiopi sudo[1715]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:17 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:17 audiopi sudo[1718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:17 audiopi sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:17 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:17 audiopi sudo[1715]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:17 audiopi sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:17 audiopi sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:17 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:17 audiopi sudo[1724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:17 audiopi sudo[1724]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:17 audiopi sudo[1718]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:17 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:17 audiopi sudo[1727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:17 audiopi sudo[1727]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:17 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:17 audiopi sudo[1724]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:17 audiopi sudo[1721]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:17 audiopi sudo[1730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:17 audiopi sudo[1730]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:17 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:17 audiopi sudo[1732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:17 audiopi sudo[1732]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:17 audiopi sudo[1735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:17 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:17 audiopi sudo[1735]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:17 audiopi sudo[1727]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:17 audiopi sudo[1730]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:17 audiopi sudo[1735]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:17 audiopi sudo[1732]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 31 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 33 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 24 17:24:17 audiopi volumio[1276]: info: Setting Spotify Volume from Volumio: 33 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 33 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 41 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 24 17:24:17 audiopi volumio[1276]: info: Setting Spotify Volume from Volumio: 41 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 24 17:24:17 audiopi sudo[1739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:17 audiopi sudo[1739]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:17 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:17 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:17 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:17 audiopi sudo[1739]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:17 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:17 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:17 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:17 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:17 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:17 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:17 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:17 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:17 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 42 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 41 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 42 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 24 17:24:17 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:17 audiopi sudo[1743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:17 audiopi sudo[1743]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:17 audiopi sudo[1743]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:17 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:17 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume- Apr 24 17:24:17 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:17 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:17 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:17 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:17 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:17 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:17 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:17 audiopi sudo[1748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:17 audiopi sudo[1748]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 42 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 41 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 42 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 24 17:24:17 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:17 audiopi sudo[1751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:17 audiopi sudo[1751]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:17 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:17 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:17 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:17 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:17 audiopi sudo[1748]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 43 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 41 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 43 Apr 24 17:24:17 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 24 17:24:17 audiopi volumio[1276]: info: Setting Spotify Volume from Volumio: 43 Apr 24 17:24:17 audiopi sudo[1755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:17 audiopi sudo[1755]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:17 audiopi sudo[1751]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:17 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:17 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:17 audiopi sudo[1755]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:17 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:18 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 24 17:24:18 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:24:18 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 24 17:24:18 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:18 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:18 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:18 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:18 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:18 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:18 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Apr 24 17:24:18 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 43 Apr 24 17:24:18 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 44 Apr 24 17:24:18 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 24 17:24:18 audiopi sudo[1761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:18 audiopi sudo[1761]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:18 audiopi volumio-remote-updater[827]: No test mode Apr 24 17:24:18 audiopi volumio-remote-updater[827]: No alpha test mode Apr 24 17:24:18 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 24 17:24:18 audiopi sudo[1761]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:18 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: SETTING SPOTIFY VOLUME 43 Apr 24 17:24:19 audiopi volumio[1276]: info: Sending Spotify command with payload to local API: /player/volume Apr 24 17:24:19 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:19 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:19 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:19 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 43 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 45 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 24 17:24:19 audiopi volumio[1276]: info: Setting Spotify Volume from Volumio: 45 Apr 24 17:24:19 audiopi sudo[1766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:19 audiopi sudo[1766]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:19 audiopi sudo[1766]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:19 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:19 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:19 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:19 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:19 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 45 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 46 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 24 17:24:19 audiopi sudo[1770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:19 audiopi sudo[1770]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:19 audiopi sudo[1770]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:19 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:19 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:19 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:19 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:19 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 45 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 47 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 24 17:24:19 audiopi volumio[1276]: info: Setting Spotify Volume from Volumio: 47 Apr 24 17:24:19 audiopi sudo[1774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:19 audiopi sudo[1774]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:19 audiopi sudo[1774]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:19 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:19 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:19 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:19 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:19 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 47 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 48 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 24 17:24:19 audiopi sudo[1778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:19 audiopi sudo[1778]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:19 audiopi sudo[1778]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:19 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:19 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:19 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:19 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:19 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 47 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 49 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 24 17:24:19 audiopi volumio[1276]: info: Setting Spotify Volume from Volumio: 49 Apr 24 17:24:19 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:19 audiopi sudo[1782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:19 audiopi sudo[1782]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:19 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:19 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:19 audiopi sudo[1782]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:19 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 49 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 50 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 24 17:24:19 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:19 audiopi sudo[1786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:19 audiopi sudo[1786]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:19 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:19 audiopi sudo[1786]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:19 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:19 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:19 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 49 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 51 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 24 17:24:19 audiopi volumio[1276]: info: Setting Spotify Volume from Volumio: 51 Apr 24 17:24:19 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:19 audiopi sudo[1790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:19 audiopi sudo[1790]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:19 audiopi sudo[1790]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:19 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:19 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:19 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:19 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:19 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 51 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 52 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 24 17:24:19 audiopi sudo[1794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:19 audiopi sudo[1794]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:19 audiopi sudo[1794]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:19 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:19 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:19 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:19 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:19 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 51 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 53 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 24 17:24:19 audiopi volumio[1276]: info: Setting Spotify Volume from Volumio: 53 Apr 24 17:24:19 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:19 audiopi sudo[1798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:19 audiopi sudo[1798]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:19 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:19 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:19 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:19 audiopi sudo[1798]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 53 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 54 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 24 17:24:19 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:19 audiopi sudo[1802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:19 audiopi sudo[1802]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:19 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:19 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:19 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:19 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:19 audiopi sudo[1802]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 53 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 55 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 24 17:24:19 audiopi volumio[1276]: info: Setting Spotify Volume from Volumio: 55 Apr 24 17:24:19 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:19 audiopi sudo[1806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:19 audiopi sudo[1806]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:19 audiopi sudo[1806]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:19 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:19 audiopi volumio[1276]: info: MYVOLUMIO: Adding device Apr 24 17:24:19 audiopi volumio[1276]: info: MYVOLUMIO: Evaluating Server Apr 24 17:24:19 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:19 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:19 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:19 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 56 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 55 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 56 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 24 17:24:19 audiopi sudo[1810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:19 audiopi sudo[1810]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:19 audiopi sudo[1810]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:19 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:19 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:19 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:19 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:19 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 55 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 57 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 24 17:24:19 audiopi volumio[1276]: info: Setting Spotify Volume from Volumio: 57 Apr 24 17:24:19 audiopi sudo[1814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:19 audiopi sudo[1814]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:19 audiopi sudo[1814]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:19 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:19 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:19 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:19 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:19 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 58 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 57 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 58 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 24 17:24:19 audiopi sudo[1818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:19 audiopi sudo[1818]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:19 audiopi sudo[1818]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:19 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:19 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:19 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:19 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:19 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 59 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 57 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 59 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 24 17:24:19 audiopi volumio[1276]: info: Setting Spotify Volume from Volumio: 59 Apr 24 17:24:19 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:19 audiopi sudo[1822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:19 audiopi sudo[1822]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:19 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:19 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:19 audiopi sudo[1822]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:19 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 60 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 59 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 60 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 24 17:24:19 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:19 audiopi sudo[1826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:19 audiopi sudo[1826]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:19 audiopi sudo[1826]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:19 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:19 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:19 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:19 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:19 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:19 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 59 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 61 Apr 24 17:24:19 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 24 17:24:19 audiopi volumio[1276]: info: Setting Spotify Volume from Volumio: 61 Apr 24 17:24:19 audiopi sudo[1830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:19 audiopi sudo[1830]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:19 audiopi sudo[1830]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:19 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:20 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:20 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:20 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:20 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:20 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:20 audiopi volumio[1276]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 24 17:24:20 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 24 17:24:20 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume+ Apr 24 17:24:20 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:20 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:20 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:20 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:20 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:20 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:20 audiopi sudo[1850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:20 audiopi sudo[1850]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:20 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Apr 24 17:24:20 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 61 Apr 24 17:24:20 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 62 Apr 24 17:24:20 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 24 17:24:20 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Apr 24 17:24:20 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 61 Apr 24 17:24:20 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 63 Apr 24 17:24:20 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 24 17:24:20 audiopi volumio[1276]: info: Setting Spotify Volume from Volumio: 63 Apr 24 17:24:20 audiopi sudo[1853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:20 audiopi sudo[1853]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:20 audiopi sudo[1850]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:20 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:20 audiopi sudo[1853]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:20 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:21 audiopi systemd[1]: systemd-timedated.service: Succeeded. Apr 24 17:24:21 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:24:21 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:24:21 audiopi volumio[1276]: info: Discovery: Getting this device information Apr 24 17:24:21 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:24:21 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:21 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:24:21 audiopi volumio[1276]: info: Setting Geolocation for MyVolumio to au1 Apr 24 17:24:21 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:24:21 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:24:21 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:24:21 audiopi volumio[1276]: SPOTIFY: SETTING SPOTIFY VOLUME 63 Apr 24 17:24:21 audiopi volumio[1276]: info: Sending Spotify command with payload to local API: /player/volume Apr 24 17:24:22 audiopi volumio[1276]: info: Updating MyVolumio device info Apr 24 17:24:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:24:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 24 17:24:22 audiopi volumio[1276]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 24 17:24:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 24 17:24:22 audiopi volumio[1276]: info: Received Get System Version Apr 24 17:24:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 24 17:24:22 audiopi volumio[1276]: info: Received Get System Info Apr 24 17:24:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:24:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:24:22 audiopi volumio[1276]: info: Discovery: Getting this device information Apr 24 17:24:22 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:24:22 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:24:23 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 24 17:24:24 audiopi volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 24 17:24:24 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:24:24 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:24 audiopi volumio[1276]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 24 17:24:24 audiopi volumio[1276]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 24 17:24:28 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 17:24:28 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 17:24:28 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 24 17:24:28 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 24 17:24:28 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 24 17:24:28 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetBrowseSources Apr 24 17:24:28 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 24 17:24:29 audiopi volumio[1276]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 24 17:24:29 audiopi volumio[1276]: info: Completed starting MyVolumio Plugin Apr 24 17:24:29 audiopi volumio[1276]: [Metrics] CommandRouter: 43s 35.71ms Apr 24 17:24:29 audiopi volumio[1276]: info: CoreCommandRouter::volumiosetStartupVolume Apr 24 17:24:29 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 17:24:29 audiopi volumio[1276]: info: VolumeController:: Setting startup Volume 10 Apr 24 17:24:29 audiopi volumio[1276]: info: VolumeController::SetAlsaVolume10 Apr 24 17:24:29 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:24:29 audiopi volumio[1276]: info: CoreCommandRouter::Close All Modals sent Apr 24 17:24:29 audiopi volumio[1276]: info: CoreCommandRouter::Close All Modals sent Apr 24 17:24:29 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:29 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:29 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:29 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:29 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status stop Apr 24 17:24:29 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Apr 24 17:24:29 audiopi volumio[1276]: SPOTIFY: SPOTIFY VOLUME 63 Apr 24 17:24:29 audiopi volumio[1276]: SPOTIFY: VOLUMIO VOLUME 10 Apr 24 17:24:29 audiopi volumio[1276]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 24 17:24:29 audiopi volumio[1276]: info: Setting Spotify Volume from Volumio: 10 Apr 24 17:24:29 audiopi sudo[1916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:24:29 audiopi sudo[1916]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:29 audiopi sudo[1916]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:29 audiopi volumio[1276]: info: peppyspectrum Daemon Stop Apr 24 17:24:29 audiopi go-librespot[1534]: time="2025-04-24T17:24:29+10:00" level=debug msg="obtained new client token: AAAt3ITRgk7H/09iM35jyrDSRVWgbZaQYk20qtmDJ4cnNToayVLMu2F2brTF/z93nI4WOzx/pwZjHYoTNv+5Ylb9dQJM88MykkGrv3rFT0CN2vpFypAVS8F7NcSSGQdmwPsDeB956tTs1JCS8fqW4/HGd5HhZMq47MbjAfah9fWXwYoZhUObX0TDZqMd1jqjkvGH4E3nSxy1oqVHkFrRzeDCAFcqhnuaChg1r+pNpdyVY42lisS/CdPj9Q==" Apr 24 17:24:30 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 24 17:24:30 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 17:24:30 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 24 17:24:30 audiopi volumio[1276]: SPOTIFY: SETTING SPOTIFY VOLUME 10 Apr 24 17:24:30 audiopi volumio[1276]: info: Sending Spotify command with payload to local API: /player/volume Apr 24 17:24:31 audiopi go-librespot[1534]: time="2025-04-24T17:24:31+10:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Apr 24 17:24:31 audiopi go-librespot[1534]: time="2025-04-24T17:24:31+10:00" level=debug msg="completed keyexchange" Apr 24 17:24:31 audiopi go-librespot[1534]: time="2025-04-24T17:24:31+10:00" level=debug msg="completed challenge" Apr 24 17:24:31 audiopi go-librespot[1534]: time="2025-04-24T17:24:31+10:00" level=info msg="authenticated AP as 76kombi" Apr 24 17:24:33 audiopi ntpd[993]: Soliciting pool server 203.14.0.251 Apr 24 17:24:34 audiopi ntpd[993]: Soliciting pool server 129.250.35.251 Apr 24 17:24:34 audiopi go-librespot[1534]: time="2025-04-24T17:24:34+10:00" level=info msg="authenticated Login5 as 76kombi" Apr 24 17:24:34 audiopi go-librespot[1534]: time="2025-04-24T17:24:34+10:00" level=info msg="accepted zeroconf user 76kombi from iPhone" Apr 24 17:24:34 audiopi go-librespot[1534]: time="2025-04-24T17:24:34+10:00" level=debug msg="dealer connection opened" Apr 24 17:24:34 audiopi go-librespot[1534]: time="2025-04-24T17:24:34+10:00" level=trace msg="starting accesspoint recv loop" Apr 24 17:24:34 audiopi go-librespot[1534]: time="2025-04-24T17:24:34+10:00" level=trace msg="starting dealer recv loop" Apr 24 17:24:34 audiopi go-librespot[1534]: time="2025-04-24T17:24:34+10:00" level=trace msg="received accesspoint ping" Apr 24 17:24:34 audiopi go-librespot[1534]: time="2025-04-24T17:24:34+10:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Apr 24 17:24:34 audiopi go-librespot[1534]: time="2025-04-24T17:24:34+10:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Apr 24 17:24:34 audiopi go-librespot[1534]: time="2025-04-24T17:24:34+10:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Apr 24 17:24:34 audiopi go-librespot[1534]: time="2025-04-24T17:24:34+10:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Apr 24 17:24:34 audiopi go-librespot[1534]: time="2025-04-24T17:24:34+10:00" level=debug msg="received connection id: ODA1MDExNTMtYzRkOS00YmQ5LWIxYjMtMGJlZDVlY2VkNDc5K2RlYWxlcit0Y3A6Ly8wYWM5NDllNy5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArRjA5MkU2RTJFODA3M0MyNEY5MDc5NkQ3M0JDRjMxODcxNzc4MzdFMjA4NTIyMEQ5QzA4Qjg0RDYyMkY1NTFCMQ==" Apr 24 17:24:35 audiopi go-librespot[1534]: time="2025-04-24T17:24:35+10:00" level=debug msg="put connect state because NEW_DEVICE" Apr 24 17:24:35 audiopi ntpd[993]: Soliciting pool server 162.159.200.1 Apr 24 17:24:35 audiopi go-librespot[1534]: time="2025-04-24T17:24:35+10:00" level=debug msg="handling transfer player command from e647261098f9129d061fcebe0df4732d8f830a59" Apr 24 17:24:35 audiopi go-librespot[1534]: time="2025-04-24T17:24:35+10:00" level=debug msg="resolved context of track" uri="spotify:playlist:73Nwe6Yqg4inNSanrmlsSS" Apr 24 17:24:35 audiopi go-librespot[1534]: time="2025-04-24T17:24:35+10:00" level=trace msg="fetched new page 0 with 240 items (list: 240)" uri="spotify:playlist:73Nwe6Yqg4inNSanrmlsSS" Apr 24 17:24:35 audiopi go-librespot[1534]: time="2025-04-24T17:24:35+10:00" level=debug msg="shuffled context with seed 11047991164287114291 (len: 240, keep: 220)" uri="spotify:playlist:73Nwe6Yqg4inNSanrmlsSS" Apr 24 17:24:35 audiopi go-librespot[1534]: time="2025-04-24T17:24:35+10:00" level=debug msg="loading track (paused: false, position: 204902ms)" uri="spotify:track:4fl2UfRsA03QXWVnWdjh1Q" Apr 24 17:24:36 audiopi go-librespot[1534]: time="2025-04-24T17:24:36+10:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 17:24:36 audiopi go-librespot[1534]: time="2025-04-24T17:24:36+10:00" level=trace msg="emitting websocket event: will_play" Apr 24 17:24:36 audiopi volumio[1276]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4fl2UfRsA03QXWVnWdjh1Q","play_origin":"com.spotify.service.carplayv2"}} Apr 24 17:24:36 audiopi go-librespot[1534]: time="2025-04-24T17:24:36+10:00" level=debug msg="selected format OGG_VORBIS_320 (b1bddeb6c87e6b3b0795816c354208ec3b9ffa99)" uri="spotify:track:4fl2UfRsA03QXWVnWdjh1Q" Apr 24 17:24:36 audiopi go-librespot[1534]: time="2025-04-24T17:24:36+10:00" level=debug msg="requested aes key for file b1bddeb6c87e6b3b0795816c354208ec3b9ffa99, gid: 4fl2UfRsA03QXWVnWdjh1Q" Apr 24 17:24:36 audiopi volumio[1276]: info: BOOT COMPLETED Apr 24 17:24:36 audiopi ntpd[993]: Soliciting pool server 103.165.180.123 Apr 24 17:24:36 audiopi go-librespot[1534]: time="2025-04-24T17:24:36+10:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Apr 24 17:24:36 audiopi go-librespot[1534]: time="2025-04-24T17:24:36+10:00" level=trace msg="received accesspoint pong ack" Apr 24 17:24:36 audiopi go-librespot[1534]: time="2025-04-24T17:24:36+10:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 242" Apr 24 17:24:37 audiopi go-librespot[1534]: time="2025-04-24T17:24:37+10:00" level=debug msg="fetched first chunk of 16, total size is 8156616 bytes" uri="spotify:track:4fl2UfRsA03QXWVnWdjh1Q" Apr 24 17:24:37 audiopi go-librespot[1534]: time="2025-04-24T17:24:37+10:00" level=debug msg="fetched chunk 1/15, size: 524288" uri="spotify:track:4fl2UfRsA03QXWVnWdjh1Q" Apr 24 17:24:37 audiopi go-librespot[1534]: time="2025-04-24T17:24:37+10:00" level=debug msg="fetched chunk 3/15, size: 524288" uri="spotify:track:4fl2UfRsA03QXWVnWdjh1Q" Apr 24 17:24:37 audiopi go-librespot[1534]: time="2025-04-24T17:24:37+10:00" level=debug msg="fetched chunk 15/15, size: 292296" uri="spotify:track:4fl2UfRsA03QXWVnWdjh1Q" Apr 24 17:24:37 audiopi go-librespot[1534]: time="2025-04-24T17:24:37+10:00" level=trace msg="seek to 181542ms (diff: 1244ms, samples: 8006002, bytes: 8132642)" uri="spotify:track:4fl2UfRsA03QXWVnWdjh1Q" Apr 24 17:24:37 audiopi go-librespot[1534]: time="2025-04-24T17:24:37+10:00" level=debug msg="created new output device" Apr 24 17:24:37 audiopi go-librespot[1534]: time="2025-04-24T17:24:37+10:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Apr 24 17:24:37 audiopi go-librespot[1534]: time="2025-04-24T17:24:37+10:00" level=info msg="loaded track \"Le produit d'une époque\" (paused: false, position: 204902ms, duration: 181542ms, prefetched: false)" uri="spotify:track:4fl2UfRsA03QXWVnWdjh1Q" Apr 24 17:24:37 audiopi go-librespot[1534]: time="2025-04-24T17:24:37+10:00" level=debug msg="fetched chunk 2/15, size: 524288" uri="spotify:track:4fl2UfRsA03QXWVnWdjh1Q" Apr 24 17:24:38 audiopi go-librespot[1534]: time="2025-04-24T17:24:38+10:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 17:24:38 audiopi go-librespot[1534]: time="2025-04-24T17:24:38+10:00" level=debug msg="prefetching next track" uri="spotify:track:479zOq78k7LvIoYJ6eNIsh" Apr 24 17:24:38 audiopi go-librespot[1534]: time="2025-04-24T17:24:38+10:00" level=trace msg="emitting websocket event: metadata" Apr 24 17:24:38 audiopi go-librespot[1534]: time="2025-04-24T17:24:38+10:00" level=trace msg="emitting websocket event: active" Apr 24 17:24:38 audiopi go-librespot[1534]: time="2025-04-24T17:24:38+10:00" level=debug msg="sending successful reply for dealer request" Apr 24 17:24:38 audiopi go-librespot[1534]: time="2025-04-24T17:24:38+10:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Apr 24 17:24:38 audiopi volumio[1276]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4fl2UfRsA03QXWVnWdjh1Q","name":"Le produit d'une époque","artist_names":["Crystal Geometry"],"album_name":"This Is Technometal 2023 Vol. 1","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02dfe60a9e4c66809eca3d9d64","position":204902,"duration":181542,"release_date":"year:2023 month:12 day:15","track_number":4,"disc_number":1}} Apr 24 17:24:38 audiopi volumio[1276]: SPOTIFY: received: {"type":"active","data":null} Apr 24 17:24:38 audiopi volumio[1276]: info: Aligning Spotify Volume to Volumio Volume Apr 24 17:24:38 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:24:38 audiopi volumio[1276]: info: CorePlayQueue::getTrack 0 Apr 24 17:24:38 audiopi volumio[1276]: info: Setting Spotify Volume from Volumio: 10 Apr 24 17:24:38 audiopi go-librespot[1534]: time="2025-04-24T17:24:38+10:00" level=debug msg="selected format OGG_VORBIS_320 (8f78792ceee5066f4c7386179e698d5c07437d41)" uri="spotify:track:479zOq78k7LvIoYJ6eNIsh" Apr 24 17:24:38 audiopi go-librespot[1534]: time="2025-04-24T17:24:38+10:00" level=debug msg="requested aes key for file 8f78792ceee5066f4c7386179e698d5c07437d41, gid: 479zOq78k7LvIoYJ6eNIsh" Apr 24 17:24:38 audiopi go-librespot[1534]: time="2025-04-24T17:24:38+10:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 17:24:38 audiopi go-librespot[1534]: time="2025-04-24T17:24:38+10:00" level=trace msg="emitting websocket event: playing" Apr 24 17:24:38 audiopi go-librespot[1534]: time="2025-04-24T17:24:38+10:00" level=trace msg="emitting websocket event: not_playing" Apr 24 17:24:38 audiopi volumio[1276]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4fl2UfRsA03QXWVnWdjh1Q","play_origin":"com.spotify.service.carplayv2"}} Apr 24 17:24:38 audiopi go-librespot[1534]: time="2025-04-24T17:24:38+10:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:479zOq78k7LvIoYJ6eNIsh" Apr 24 17:24:38 audiopi volumio[1276]: info: Spotify is playing in volatile mode Apr 24 17:24:38 audiopi volumio[1276]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:24:38 audiopi volumio[1276]: SPOTIFY: UNSET VOLATILE Apr 24 17:24:38 audiopi volumio[1276]: SPOTIFY: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":10,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":null,"repeat":null,"repeatSingle":false,"updatedb":false,"consume":false} Apr 24 17:24:38 audiopi volumio[1276]: SPOTIFY: PUSH STATE SPOTIFY Apr 24 17:24:38 audiopi volumio[1276]: SPOTIFY: {"status":"play","service":"spop","title":"Le produit d'une époque","artist":"Crystal Geometry","album":"This Is Technometal 2023 Vol. 1","albumart":"https://i.scdn.co/image/ab67616d00001e02dfe60a9e4c66809eca3d9d64","uri":"spotify:track:4fl2UfRsA03QXWVnWdjh1Q","trackType":"spotify","seek":204902,"duration":181,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Apr 24 17:24:38 audiopi volumio[1276]: info: CoreCommandRouter::servicePushState Apr 24 17:24:38 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:38 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:24:38 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:38 audiopi volumio[1276]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:4fl2UfRsA03QXWVnWdjh1Q","play_origin":"com.spotify.service.carplayv2"}} Apr 24 17:24:38 audiopi volumio[1276]: error: Failed to decode event: not_playing Apr 24 17:24:38 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status play Apr 24 17:24:38 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Apr 24 17:24:38 audiopi sudo[1935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Apr 24 17:24:38 audiopi sudo[1935]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:38 audiopi systemd[1]: Started peppyspectrum Daemon. Apr 24 17:24:38 audiopi sudo[1935]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:38 audiopi volumio[1276]: info: peppyspectrum Daemon Started Apr 24 17:24:38 audiopi go-librespot[1534]: time="2025-04-24T17:24:38+10:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Apr 24 17:24:38 audiopi go-librespot[1534]: time="2025-04-24T17:24:38+10:00" level=trace msg="emitting websocket event: will_play" Apr 24 17:24:38 audiopi volumio[1276]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:479zOq78k7LvIoYJ6eNIsh","play_origin":"com.spotify.service.carplayv2"}} Apr 24 17:24:38 audiopi volumio[1276]: SPOTIFY: PUSH STATE SPOTIFY Apr 24 17:24:38 audiopi volumio[1276]: SPOTIFY: {"status":"play","service":"spop","title":"Le produit d'une époque","artist":"Crystal Geometry","album":"This Is Technometal 2023 Vol. 1","albumart":"https://i.scdn.co/image/ab67616d00001e02dfe60a9e4c66809eca3d9d64","uri":"spotify:track:4fl2UfRsA03QXWVnWdjh1Q","trackType":"spotify","seek":204902,"duration":181,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Apr 24 17:24:38 audiopi volumio[1276]: info: CoreCommandRouter::servicePushState Apr 24 17:24:38 audiopi volumio[1276]: info: CoreStateMachine::pushState Apr 24 17:24:38 audiopi volumio[1276]: info: CoreCommandRouter::volumioPushState Apr 24 17:24:38 audiopi volumio[1276]: info: PeppySpectrum ---peppyspectrum status play Apr 24 17:24:38 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Apr 24 17:24:38 audiopi sudo[1940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Apr 24 17:24:38 audiopi sudo[1940]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:24:38 audiopi sudo[1940]: pam_unix(sudo:session): session closed for user root Apr 24 17:24:38 audiopi volumio[1276]: info: peppyspectrum Daemon Started Apr 24 17:24:38 audiopi ntpd[993]: Soliciting pool server 220.158.215.21 Apr 24 17:24:38 audiopi volumio[1937]: pygame 1.9.4.post1 Apr 24 17:24:38 audiopi volumio[1937]: Hello from the pygame community. https://www.pygame.org/contribute.html Apr 24 17:24:38 audiopi volumio[1937]: Traceback (most recent call last): Apr 24 17:24:38 audiopi volumio[1937]: File "spectrum.py", line 715, in Apr 24 17:24:38 audiopi volumio[1937]: pm = Spectrum(None, True) Apr 24 17:24:38 audiopi volumio[1937]: File "spectrum.py", line 63, in __init__ Apr 24 17:24:38 audiopi volumio[1937]: self.init_display() Apr 24 17:24:38 audiopi volumio[1937]: File "spectrum.py", line 104, in init_display Apr 24 17:24:38 audiopi volumio[1937]: pygame.display.init() Apr 24 17:24:38 audiopi volumio[1937]: pygame.error: Unable to open /dev/fb1 Apr 24 17:24:38 audiopi systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Apr 24 17:24:38 audiopi systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Apr 24 17:24:39 audiopi volumio[1276]: SPOTIFY: SETTING SPOTIFY VOLUME 10 Apr 24 17:24:39 audiopi volumio[1276]: info: Sending Spotify command with payload to local API: /player/volume Apr 24 17:24:40 audiopi go-librespot[1534]: time="2025-04-24T17:24:40+10:00" level=debug msg="selected format OGG_VORBIS_320 (8f78792ceee5066f4c7386179e698d5c07437d41)" uri="spotify:track:479zOq78k7LvIoYJ6eNIsh" Apr 24 17:24:40 audiopi go-librespot[1534]: time="2025-04-24T17:24:40+10:00" level=debug msg="requested aes key for file 8f78792ceee5066f4c7386179e698d5c07437d41, gid: 479zOq78k7LvIoYJ6eNIsh" Apr 24 17:24:45 audiopi ntpd[993]: receive: Unexpected origin timestamp 0xebb465ba.80259427 does not match aorg 0000000000.00000000 from server@203.14.0.251 xmt 0xebb465bd.3d8c05b2 Apr 24 17:24:46 audiopi go-librespot[1534]: time="2025-04-24T17:24:46+10:00" level=debug msg="fetched first chunk of 28, total size is 14527715 bytes" uri="spotify:track:479zOq78k7LvIoYJ6eNIsh" Apr 24 17:24:46 audiopi go-librespot[1534]: time="2025-04-24T17:24:46+10:00" level=debug msg="fetched first chunk of 28, total size is 14527715 bytes" uri="spotify:track:479zOq78k7LvIoYJ6eNIsh" Apr 24 17:24:46 audiopi go-librespot[1534]: time="2025-04-24T17:24:46+10:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Apr 24 17:24:46 audiopi go-librespot[1534]: time="2025-04-24T17:24:46+10:00" level=info msg="prefetched track \"Chernobyl - Antigone Remix\" (duration: 322271ms)" uri="spotify:track:479zOq78k7LvIoYJ6eNIsh" Apr 24 17:24:46 audiopi go-librespot[1534]: time="2025-04-24T17:24:46+10:00" level=info msg="loaded track \"Chernobyl - Antigone Remix\" (paused: false, position: 0ms, duration: 322271ms, prefetched: false)" uri="spotify:track:479zOq78k7LvIoYJ6eNIsh" Apr 24 17:24:46 audiopi go-librespot[1534]: go-librespot: pcm_meter.c:1216: snd_pcm_scope_s16_get_channel_buffer: Assertion `s16->buf_areas' failed. Apr 24 17:24:46 audiopi go-librespot[1534]: Aborted Apr 24 17:24:46 audiopi systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Apr 24 17:24:46 audiopi systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 24 17:24:46 audiopi volumio[1276]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Apr 24 17:24:46 audiopi volumio[1276]: info: Connection to go-librespot Websocket closed Apr 24 17:24:49 audiopi volumio[1276]: info: Initializing connection to go-librespot Websocket Apr 24 17:24:49 audiopi volumio[1276]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 24 17:24:49 audiopi systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 24 17:24:49 audiopi systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Apr 24 17:24:49 audiopi systemd[1]: Stopped go-librespot Daemon. Apr 24 17:24:49 audiopi systemd[1]: Started go-librespot Daemon. Apr 24 17:24:49 audiopi go-librespot[1944]: go-librespot daemon starting... Apr 24 17:24:49 audiopi go-librespot[1944]: time="2025-04-24T17:24:49+10:00" level=info msg="running go-librespot 0.2.0" Apr 24 17:24:49 audiopi go-librespot[1944]: time="2025-04-24T17:24:49+10:00" level=debug msg="app state loaded" Apr 24 17:24:49 audiopi go-librespot[1944]: time="2025-04-24T17:24:49+10:00" level=debug msg="stored credentials not found" Apr 24 17:24:49 audiopi go-librespot[1944]: time="2025-04-24T17:24:49+10:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 24 17:24:49 audiopi go-librespot[1944]: time="2025-04-24T17:24:49+10:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 24 17:24:49 audiopi go-librespot[1944]: time="2025-04-24T17:24:49+10:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Apr 24 17:24:49 audiopi go-librespot[1944]: time="2025-04-24T17:24:49+10:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Apr 24 17:24:49 audiopi go-librespot[1944]: time="2025-04-24T17:24:49+10:00" level=info msg="zeroconf server listening on port 34281" Apr 24 17:24:52 audiopi volumio[1276]: info: Initializing connection to go-librespot Websocket Apr 24 17:24:52 audiopi go-librespot[1944]: time="2025-04-24T17:24:52+10:00" level=debug msg="new websocket client" Apr 24 17:24:52 audiopi volumio[1276]: info: Connection to go-librespot Websocket established Apr 24 17:24:55 audiopi volumio[1276]: info: Getting Spotify volume Apr 24 17:24:55 audiopi volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 24 17:24:55 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:24:55 audiopi volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Apr 24 17:25:01 audiopi ntpd[993]: Soliciting pool server 139.180.160.82 Apr 24 17:25:01 audiopi volumio[1276]: verbose: New Socket.io Connection to 192.168.0.161 from 192.168.0.194 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Apr 24 17:25:01 audiopi volumio[1276]: info: Received Get System Info Apr 24 17:25:01 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:25:01 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:25:01 audiopi volumio[1276]: info: Discovery: Getting this device information Apr 24 17:25:01 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:25:01 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:25:01 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:25:01 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 24 17:25:01 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetVisibleSources Apr 24 17:25:01 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 17:25:01 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:25:01 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 24 17:25:01 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 24 17:25:01 audiopi volumio[1276]: info: Received Get System Info Apr 24 17:25:01 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:25:01 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:25:01 audiopi volumio[1276]: info: Discovery: Getting this device information Apr 24 17:25:01 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:25:01 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:25:01 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:25:01 audiopi volumio[1276]: info: Listing playlists Apr 24 17:25:01 audiopi ntpd[993]: Soliciting pool server 194.195.249.28 Apr 24 17:25:01 audiopi ntpd[993]: Soliciting pool server 162.159.200.123 Apr 24 17:25:01 audiopi volumio[1276]: verbose: New Socket.io Connection to 192.168.0.159 from 192.168.0.194 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Apr 24 17:25:01 audiopi volumio[1276]: verbose: New Socket.io Connection to 192.168.0.161:3000 from 192.168.0.194 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 24 17:25:01 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 24 17:25:02 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:25:02 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:25:02 audiopi volumio[1276]: info: Discovery: Getting this device information Apr 24 17:25:02 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:25:02 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:25:02 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:25:02 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:25:02 audiopi volumio[1276]: info: Discovery: Getting this device information Apr 24 17:25:02 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:25:02 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:25:03 audiopi volumio[1276]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 17:25:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 24 17:25:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:25:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 17:25:03 audiopi volumio[1276]: info: Getting Alsa Cards List without I2S DAC Apr 24 17:25:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 24 17:25:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:25:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 17:25:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 17:25:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 17:25:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 17:25:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 17:25:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 17:25:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 17:25:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 24 17:25:03 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 24 17:25:11 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 17:25:22 audiopi volumio[1276]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 17:25:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 24 17:25:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:25:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 17:25:22 audiopi volumio[1276]: info: Getting Alsa Cards List without I2S DAC Apr 24 17:25:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 24 17:25:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:25:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 17:25:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 17:25:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 17:25:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 17:25:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 17:25:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 17:25:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 17:25:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 24 17:25:22 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 24 17:25:24 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:25:24 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:25:24 audiopi volumio[1276]: info: Discovery: Getting this device information Apr 24 17:25:24 audiopi volumio[1276]: info: CoreCommandRouter::volumioGetState Apr 24 17:25:24 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:25:28 audiopi volumio[1276]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 17:25:28 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 24 17:25:29 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 24 17:25:29 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 24 17:25:29 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Apr 24 17:25:29 audiopi volumio[1276]: info: Received Get System Version Apr 24 17:25:29 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 24 17:25:38 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 24 17:25:38 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 24 17:25:44 audiopi volumio[1276]: info: Starting Uninstall of plugin user_interface - peppyspectrum Apr 24 17:25:44 audiopi volumio[1276]: info: Uninstalling plugin peppyspectrum Apr 24 17:25:44 audiopi volumio[1276]: info: Stopping peppyspectrum service Apr 24 17:25:44 audiopi volumio[1276]: info: CoreStateMachine::stop Apr 24 17:25:44 audiopi volumio[1276]: info: CoreStateMachine::serviceStop Apr 24 17:25:44 audiopi volumio[1276]: info: CoreCommandRouter::serviceStop Apr 24 17:25:44 audiopi volumio[1276]: info: Spotify Stop Apr 24 17:25:44 audiopi volumio[1276]: SPOTIFY: SPOTIFY STOP Apr 24 17:25:44 audiopi volumio[1276]: SPOTIFY: {"status":"play","title":"Le produit d'une époque","artist":"Crystal Geometry","album":"This Is Technometal 2023 Vol. 1","albumart":"https://i.scdn.co/image/ab67616d00001e02dfe60a9e4c66809eca3d9d64","uri":"spotify:track:4fl2UfRsA03QXWVnWdjh1Q","trackType":"spotify","codec":"ogg","seek":218902,"duration":181,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":10,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Apr 24 17:25:44 audiopi volumio[1276]: info: Sending Spotify command to local API: /player/pause Apr 24 17:25:44 audiopi volumio[1276]: info: Disabling plugin peppyspectrum Apr 24 17:25:44 audiopi volumio[1276]: info: Preparing to generate the ALSA configuration file Apr 24 17:25:44 audiopi sudo[2115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 24 17:25:44 audiopi sudo[2115]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:25:44 audiopi volumio[1276]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 24 17:25:44 audiopi volumio[1276]: info: Reading ALSA contributions from plugins. Apr 24 17:25:44 audiopi sudo[2115]: pam_unix(sudo:session): session closed for user root Apr 24 17:25:44 audiopi volumio[1276]: info: Asound.conf file written Apr 24 17:25:44 audiopi sudo[2118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 24 17:25:44 audiopi sudo[2118]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:25:44 audiopi sudo[2118]: pam_unix(sudo:session): session closed for user root Apr 24 17:25:44 audiopi volumio[1276]: info: Output device has changed, restarting MPD Apr 24 17:25:44 audiopi sudo[2123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 24 17:25:44 audiopi sudo[2123]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:25:44 audiopi volumio[1276]: info: Output device has changed, restarting Shairport Sync Apr 24 17:25:44 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 17:25:44 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:25:44 audiopi sudo[2123]: pam_unix(sudo:session): session closed for user root Apr 24 17:25:44 audiopi sudo[2126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 24 17:25:44 audiopi sudo[2126]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:25:44 audiopi systemd[1]: Stopping Music Player Daemon... Apr 24 17:25:44 audiopi volumio[1276]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 24 17:25:44 audiopi systemd[1]: mpd.service: Succeeded. Apr 24 17:25:44 audiopi systemd[1]: Stopped Music Player Daemon. Apr 24 17:25:44 audiopi volumio[1276]: info: Checking if uninstall.sh is present Apr 24 17:25:44 audiopi volumio[1276]: info: Executing uninstall.sh Apr 24 17:25:44 audiopi volumio[1276]: info: MPD Permissions set Apr 24 17:25:44 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 17:25:44 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 17:25:44 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 17:25:44 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 17:25:44 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 17:25:44 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 17:25:44 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 17:25:44 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 17:25:44 audiopi systemd[1]: Starting Music Player Daemon... Apr 24 17:25:44 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 17:25:44 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 17:25:44 audiopi volumio[1276]: info: Starting Shairport Sync Apr 24 17:25:44 audiopi sudo[2141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 24 17:25:44 audiopi sudo[2141]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:25:44 audiopi sudo[2131]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 24 17:25:44 audiopi sudo[2131]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:25:44 audiopi sudo[2131]: pam_unix(sudo:session): session closed for user root Apr 24 17:25:44 audiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 24 17:25:44 audiopi systemd[1]: shairport-sync.service: Succeeded. Apr 24 17:25:44 audiopi sudo[2134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/peppyspectrum/uninstall.sh Apr 24 17:25:44 audiopi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 24 17:25:44 audiopi sudo[2134]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:25:44 audiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 24 17:25:44 audiopi sudo[2141]: pam_unix(sudo:session): session closed for user root Apr 24 17:25:44 audiopi sudo[2147]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -Rf /data/plugins/user_interface/peppyspectrum/PeppySpectrum/__pycache__ Apr 24 17:25:44 audiopi sudo[2147]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:25:44 audiopi sudo[2147]: pam_unix(sudo:session): session closed for user root Apr 24 17:25:44 audiopi volumio[1276]: info: Shairport-Sync Started Apr 24 17:25:44 audiopi sudo[2149]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /etc/systemd/system/peppyspectrum.service Apr 24 17:25:44 audiopi sudo[2149]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:25:44 audiopi sudo[2149]: pam_unix(sudo:session): session closed for user root Apr 24 17:25:44 audiopi sudo[2134]: pam_unix(sudo:session): session closed for user root Apr 24 17:25:44 audiopi volumio[1276]: info: Uninstall script completed Apr 24 17:25:44 audiopi volumio[1276]: info: Removing plugin peppyspectrum from configuration Apr 24 17:25:44 audiopi volumio[1276]: info: Successfully removed peppyspectrum configuration files Apr 24 17:25:44 audiopi volumio[1276]: info: Plugin folders cleanup Apr 24 17:25:44 audiopi volumio[1276]: info: Scanning into folder /volumio/app/plugins/ Apr 24 17:25:44 audiopi volumio[1276]: info: Scanning category audio_interface Apr 24 17:25:44 audiopi volumio[1276]: info: Scanning category miscellanea Apr 24 17:25:44 audiopi volumio[1276]: info: Scanning category music_service Apr 24 17:25:44 audiopi volumio[1276]: info: Scanning category plugins.json Apr 24 17:25:44 audiopi volumio[1276]: info: Scanning category system_controller Apr 24 17:25:44 audiopi volumio[1276]: info: Scanning category user_interface Apr 24 17:25:44 audiopi volumio[1276]: info: Scanning into folder /data/plugins/ Apr 24 17:25:44 audiopi volumio[1276]: info: Scanning category music_service Apr 24 17:25:44 audiopi volumio[1276]: info: Scanning category user_interface Apr 24 17:25:44 audiopi volumio[1276]: info: Cleaning folder for peppyspectrum Apr 24 17:25:44 audiopi volumio[1276]: info: Plugin folders cleanup completed Apr 24 17:25:44 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 24 17:25:44 audiopi volumio[1276]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.4","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.5.1","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":false}] Apr 24 17:25:44 audiopi volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 24 17:25:44 audiopi mpd[2144]: Apr 24 17:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 24 17:25:44 audiopi systemd[1]: Started Music Player Daemon. Apr 24 17:25:44 audiopi sudo[2126]: pam_unix(sudo:session): session closed for user root Apr 24 17:25:44 audiopi volumio[1276]: error: updateQueue error: null Apr 24 17:25:51 audiopi volumio[1276]: info: Starting Uninstall of plugin user_interface - touch_display Apr 24 17:25:51 audiopi volumio[1276]: info: Uninstalling plugin touch_display Apr 24 17:25:51 audiopi sudo[2177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Apr 24 17:25:51 audiopi sudo[2177]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:25:51 audiopi sudo[2177]: pam_unix(sudo:session): session closed for user root Apr 24 17:25:51 audiopi sudo[2180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Apr 24 17:25:51 audiopi sudo[2180]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:25:51 audiopi sudo[2183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Apr 24 17:25:51 audiopi sudo[2183]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:25:51 audiopi volumio[1276]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Apr 24 17:25:51 audiopi systemd[1]: Created slice system-getty.slice. Apr 24 17:25:51 audiopi volumio[1276]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 17:25:51 audiopi volumio[1276]: TypeError: Cannot read property 'split' of undefined Apr 24 17:25:51 audiopi volumio[1276]: at /data/plugins/user_interface/touch_display/index.js:1258:37 Apr 24 17:25:51 audiopi volumio[1276]: at ChildProcess.exithandler (child_process.js:299:7) Apr 24 17:25:51 audiopi volumio[1276]: at ChildProcess.emit (events.js:315:20) Apr 24 17:25:51 audiopi volumio[1276]: at maybeClose (internal/child_process.js:1048:16) Apr 24 17:25:51 audiopi volumio[1276]: at Socket. (internal/child_process.js:439:11) Apr 24 17:25:51 audiopi volumio[1276]: at Socket.emit (events.js:315:20) Apr 24 17:25:51 audiopi volumio[1276]: at Pipe. (net.js:673:12) Apr 24 17:25:51 audiopi volumio[1276]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 17:25:51 audiopi sudo[2186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Apr 24 17:25:51 audiopi sudo[2186]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 17:25:51 audiopi systemd[1]: Started Getty on tty1. Apr 24 17:25:51 audiopi sudo[2180]: pam_unix(sudo:session): session closed for user root Apr 24 17:25:51 audiopi sudo[2186]: pam_unix(sudo:session): session closed for user root Apr 24 17:25:51 audiopi systemd[1]: Reloading. Apr 24 17:25:51 audiopi sudo[2211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-24 17:24 Apr 24 17:25:51 audiopi sudo[2211]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"