-- Logs begin at Sun 2024-04-07 23:18:25 UTC, end at Sun 2024-04-07 23:23:51 UTC. -- Apr 07 23:22:01 volumio ntpd[1309]: Soliciting pool server 91.236.251.129 Apr 07 23:22:05 volumio volumio[727]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 5 Apr 07 23:22:05 volumio volumio[727]: info: CoreCommandRouter::volumioGetVisibleSources Apr 07 23:22:05 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 23:22:05 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Apr 07 23:22:05 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Apr 07 23:22:05 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Apr 07 23:22:05 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Apr 07 23:22:05 volumio volumio[727]: info: Received Get System Info Apr 07 23:22:05 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 07 23:22:05 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 07 23:22:05 volumio volumio[727]: info: Discovery: Getting this device information Apr 07 23:22:05 volumio volumio[727]: info: CoreCommandRouter::volumioGetState Apr 07 23:22:05 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:22:05 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 07 23:22:05 volumio volumio-remote-updater[519]: No test mode Apr 07 23:22:05 volumio volumio-remote-updater[519]: No alpha test mode Apr 07 23:22:05 volumio volumio[727]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 07 23:22:05 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 07 23:22:07 volumio ntpd[1309]: Soliciting pool server 91.236.251.34 Apr 07 23:22:08 volumio ntpd[1309]: Soliciting pool server 193.106.144.13 Apr 07 23:22:10 volumio ntpd[1309]: Soliciting pool server 2606:4700:f1::123 Apr 07 23:22:19 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Apr 07 23:22:19 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Apr 07 23:22:19 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 07 23:22:19 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 23:22:19 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 07 23:22:27 volumio volumio[727]: info: Found match in Cards Database: setting mixer for card HDMI Apr 07 23:22:27 volumio volumio[727]: info: Setting mixer Master for card HDMI Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 07 23:22:27 volumio volumio[727]: info: Updating Volume Controller Parameters: Device: 0,3 Name: HDMI Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 07 23:22:27 volumio volumio[727]: info: Disabling external Volume Control Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 07 23:22:27 volumio volumio[727]: info: Preparing to generate the ALSA configuration file Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 07 23:22:27 volumio volumio[727]: info: Retrieving Cloud Streaming UI Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 07 23:22:27 volumio volumio[727]: info: VolumeController:: Volume=51 Mute =false Apr 07 23:22:27 volumio volumio[727]: info: CoreStateMachine::pushState Apr 07 23:22:27 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Apr 07 23:22:27 volumio volumio[727]: info: Asound.conf file written Apr 07 23:22:27 volumio sudo[1744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 07 23:22:27 volumio sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 23:22:27 volumio sudo[1744]: pam_unix(sudo:session): session closed for user root Apr 07 23:22:27 volumio volumio[727]: info: Output device has changed, restarting MPD Apr 07 23:22:27 volumio sudo[1749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 07 23:22:27 volumio sudo[1749]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 23:22:27 volumio volumio[727]: info: Output device has changed, restarting Shairport Sync Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 23:22:27 volumio sudo[1749]: pam_unix(sudo:session): session closed for user root Apr 07 23:22:27 volumio sudo[1751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 07 23:22:27 volumio sudo[1751]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 23:22:27 volumio systemd[1]: Stopping Music Player Daemon... Apr 07 23:22:27 volumio volumio[727]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 23:22:27 volumio volumio[727]: info: Output device has changed, restarting MPD Apr 07 23:22:27 volumio sudo[1758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 07 23:22:27 volumio sudo[1758]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 23:22:27 volumio sudo[1758]: pam_unix(sudo:session): session closed for user root Apr 07 23:22:27 volumio volumio[727]: info: Output device has changed, restarting Shairport Sync Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 23:22:27 volumio sudo[1761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 07 23:22:27 volumio sudo[1761]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 23:22:27 volumio systemd[1]: mpd.service: Succeeded. Apr 07 23:22:27 volumio systemd[1]: Stopped Music Player Daemon. Apr 07 23:22:27 volumio systemd[1]: Starting Music Player Daemon... Apr 07 23:22:27 volumio volumio[727]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 23:22:27 volumio volumio[727]: info: Getting Tidal Cloud Configuration Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 07 23:22:27 volumio volumio[727]: info: Getting Qobuz Cloud Configuration Apr 07 23:22:27 volumio volumio[727]: info: Asking plugin for UI Config Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 07 23:22:27 volumio volumio[727]: info: Getting Spotify Cloud Configuration Apr 07 23:22:27 volumio volumio[727]: info: Asking plugin for UI Config Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 07 23:22:27 volumio volumio[727]: info: Saving Spotify Acccount Apr 07 23:22:27 volumio volumio[727]: info: Got Tidal Cloud Configuration Apr 07 23:22:27 volumio volumio[727]: info: Got it Apr 07 23:22:27 volumio volumio[727]: info: Got it Apr 07 23:22:27 volumio volumio[727]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 07 23:22:27 volumio volumio[727]: info: MPD Permissions set Apr 07 23:22:27 volumio volumio[727]: info: MPD Permissions set Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 23:22:27 volumio volumio[727]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 07 23:22:27 volumio volumio[727]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 07 23:22:27 volumio volumio[727]: info: Starting Shairport Sync Apr 07 23:22:27 volumio volumio[727]: info: Starting Shairport Sync Apr 07 23:22:27 volumio sudo[1780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 07 23:22:27 volumio sudo[1780]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 23:22:28 volumio sudo[1782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 07 23:22:28 volumio sudo[1782]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 23:22:28 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 07 23:22:28 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 07 23:22:28 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 07 23:22:28 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 07 23:22:28 volumio sudo[1780]: pam_unix(sudo:session): session closed for user root Apr 07 23:22:28 volumio volumio[727]: info: Shairport-Sync Started Apr 07 23:22:28 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 07 23:22:28 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 07 23:22:28 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 07 23:22:28 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 07 23:22:28 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 07 23:22:28 volumio sudo[1782]: pam_unix(sudo:session): session closed for user root Apr 07 23:22:28 volumio volumio[727]: info: Shairport-Sync Started Apr 07 23:22:28 volumio mpd[1764]: Apr 07 23:22 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 07 23:22:28 volumio systemd[1]: Started Music Player Daemon. Apr 07 23:22:28 volumio sudo[1761]: pam_unix(sudo:session): session closed for user root Apr 07 23:22:28 volumio sudo[1751]: pam_unix(sudo:session): session closed for user root Apr 07 23:22:28 volumio volumio[727]: error: MPD error: The expression evaluated to a falsy value: Apr 07 23:22:28 volumio volumio[727]: assert.ok(self.idling) Apr 07 23:22:28 volumio volumio[727]: error: The expression evaluated to a falsy value: Apr 07 23:22:28 volumio volumio[727]: assert.ok(self.idling) Apr 07 23:22:28 volumio volumio[727]: error: updateQueue error: null Apr 07 23:22:31 volumio volumio[727]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 07 23:22:31 volumio volumio[727]: info: CoreStateMachine::getcurrentVolume Apr 07 23:22:31 volumio volumio[727]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 23:22:31 volumio volumio[727]: info: VolumeController:: Volume=41 Mute =false Apr 07 23:22:31 volumio volumio[727]: info: CoreStateMachine::pushState Apr 07 23:22:31 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:22:31 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 23:22:31 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Apr 07 23:22:31 volumio volumio[727]: info: CoreStateMachine::updateTrackBlock Apr 07 23:22:31 volumio volumio[727]: info: CorePlayQueue::getTrackBlock Apr 07 23:22:31 volumio volumio[727]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 23:22:31 volumio volumio[727]: info: VolumeController:: Volume=41 Mute =false Apr 07 23:22:31 volumio volumio[727]: info: CoreStateMachine::pushState Apr 07 23:22:31 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:22:31 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Apr 07 23:22:33 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Apr 07 23:22:33 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Apr 07 23:22:33 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Apr 07 23:22:33 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Apr 07 23:22:33 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Apr 07 23:22:42 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Apr 07 23:22:42 volumio volumio[727]: info: CoreCommandRouter::Reload Ui Apr 07 23:22:42 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Apr 07 23:22:42 volumio volumio[727]: info: Wizard terminated Successfully Apr 07 23:22:42 volumio volumio[727]: info: CoreCommandRouter::volumiosetStartupVolume Apr 07 23:22:42 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 23:22:44 volumio volumio[727]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 4 Apr 07 23:22:46 volumio volumio[727]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 5 Apr 07 23:22:48 volumio volumio[727]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 6 Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::volumioGetVisibleSources Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::volumioGetState Apr 07 23:22:48 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 07 23:22:48 volumio volumio[727]: info: Received Get System Info Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 07 23:22:48 volumio volumio[727]: info: Discovery: Getting this device information Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::volumioGetState Apr 07 23:22:48 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::volumioGetState Apr 07 23:22:48 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:22:48 volumio volumio[727]: info: Listing playlists Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::volumioGetState Apr 07 23:22:48 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 07 23:22:48 volumio volumio[727]: info: Received Get System Info Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 07 23:22:48 volumio volumio[727]: info: Discovery: Getting this device information Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::volumioGetState Apr 07 23:22:48 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::volumioGetVisibleSources Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::volumioGetState Apr 07 23:22:48 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::volumioGetState Apr 07 23:22:48 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 07 23:22:48 volumio volumio[727]: info: Received Get System Info Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 07 23:22:48 volumio volumio[727]: info: Discovery: Getting this device information Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::volumioGetState Apr 07 23:22:48 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 07 23:22:48 volumio volumio[727]: info: CoreCommandRouter::volumioGetState Apr 07 23:22:48 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:22:48 volumio volumio[727]: info: Listing playlists Apr 07 23:23:04 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 07 23:23:04 volumio volumio[727]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 07 23:23:04 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 07 23:23:04 volumio volumio[727]: info: Received Get System Version Apr 07 23:23:04 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 23:23:04 volumio volumio[727]: info: Received Get System Info Apr 07 23:23:04 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 07 23:23:04 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 07 23:23:04 volumio volumio[727]: info: Discovery: Getting this device information Apr 07 23:23:04 volumio volumio[727]: info: CoreCommandRouter::volumioGetState Apr 07 23:23:04 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:23:04 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 07 23:23:06 volumio ntpd[1309]: Soliciting pool server 194.54.80.29 Apr 07 23:23:07 volumio volumio[727]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 07 23:23:07 volumio volumio[727]: info: Retrieving Cloud Streaming UI Apr 07 23:23:07 volumio volumio[727]: info: Getting Tidal Cloud Configuration Apr 07 23:23:07 volumio volumio[727]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 07 23:23:07 volumio volumio[727]: info: Getting Qobuz Cloud Configuration Apr 07 23:23:07 volumio volumio[727]: info: Asking plugin for UI Config Apr 07 23:23:07 volumio volumio[727]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 07 23:23:07 volumio volumio[727]: info: Getting Spotify Cloud Configuration Apr 07 23:23:07 volumio volumio[727]: info: Asking plugin for UI Config Apr 07 23:23:07 volumio volumio[727]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 07 23:23:07 volumio volumio[727]: info: Saving Spotify Acccount Apr 07 23:23:07 volumio volumio[727]: info: Got Tidal Cloud Configuration Apr 07 23:23:07 volumio volumio[727]: info: Got it Apr 07 23:23:07 volumio volumio[727]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 07 23:23:07 volumio volumio[727]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 07 23:23:07 volumio volumio[727]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 07 23:23:07 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 07 23:23:07 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 07 23:23:07 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 07 23:23:07 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 07 23:23:07 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 23:23:07 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 23:23:07 volumio volumio[727]: info: CoreCommandRouter::volumioGetBrowseSources Apr 07 23:23:07 volumio volumio[727]: info: CoreCommandRouter::volumioGetBrowseSources Apr 07 23:23:07 volumio volumio[727]: info: CoreCommandRouter::volumioGetBrowseSources Apr 07 23:23:08 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 07 23:23:08 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 07 23:23:10 volumio ntpd[1309]: Soliciting pool server 91.236.251.35 Apr 07 23:23:12 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 07 23:23:14 volumio ntpd[1309]: Soliciting pool server 162.159.200.1 Apr 07 23:23:15 volumio ntpd[1309]: Soliciting pool server 193.106.144.7 Apr 07 23:23:16 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 07 23:23:17 volumio ntpd[1309]: Soliciting pool server 2001:67c:440:824:91:236:251:24 Apr 07 23:23:20 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 07 23:23:24 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 07 23:23:28 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 07 23:23:32 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 07 23:23:36 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 07 23:23:39 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 07 23:23:39 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand rescan Apr 07 23:23:39 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Apr 07 23:23:39 volumio volumio[727]: info: Apr 07 23:23:39 volumio volumio[727]: ---------------------------- MPD announces state update: update Apr 07 23:23:39 volumio volumio[727]: info: sendMpdCommand rescan took 13 milliseconds Apr 07 23:23:39 volumio volumio[727]: info: ControllerMpd::getState Apr 07 23:23:39 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Apr 07 23:23:39 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Apr 07 23:23:39 volumio volumio[727]: info: Apr 07 23:23:39 volumio volumio[727]: ---------------------------- MPD announces state update: update Apr 07 23:23:39 volumio volumio[727]: info: ControllerMpd::getState Apr 07 23:23:39 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Apr 07 23:23:39 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Apr 07 23:23:39 volumio volumio[727]: info: Apr 07 23:23:39 volumio volumio[727]: ---------------------------- MPD announces state update: update Apr 07 23:23:39 volumio volumio[727]: info: sendMpdCommand status took 38 milliseconds Apr 07 23:23:39 volumio volumio[727]: info: sendMpdCommand status took 29 milliseconds Apr 07 23:23:39 volumio volumio[727]: info: sendMpdCommand status took 29 milliseconds Apr 07 23:23:39 volumio volumio[727]: info: sendMpdCommand status took 28 milliseconds Apr 07 23:23:39 volumio volumio[727]: info: ControllerMpd::getState Apr 07 23:23:39 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Apr 07 23:23:39 volumio volumio[727]: verbose: ControllerMpd::parseState Apr 07 23:23:39 volumio volumio[727]: info: Command Router : Notfying DB Updatetrue Apr 07 23:23:39 volumio volumio[727]: verbose: ControllerMpd::parseState Apr 07 23:23:39 volumio volumio[727]: verbose: ControllerMpd::parseState Apr 07 23:23:40 volumio volumio[727]: info: Command Router : Notfying DB Updatetrue Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::Close All Modals sent Apr 07 23:23:40 volumio volumio[727]: verbose: ControllerMpd::parseState Apr 07 23:23:40 volumio volumio[727]: info: ControllerMpd::pushState Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::servicePushState Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::pushState Apr 07 23:23:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Apr 07 23:23:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:23:40 volumio volumio[727]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 07 23:23:40 volumio volumio[727]: verbose: CURRENT POSITION 0 Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::syncState stateService stop Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus stop Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::pushState Apr 07 23:23:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Apr 07 23:23:40 volumio volumio[727]: info: No code Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::pushState Apr 07 23:23:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Apr 07 23:23:40 volumio volumio[727]: info: ControllerMpd::pushState Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::servicePushState Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::pushState Apr 07 23:23:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Apr 07 23:23:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:23:40 volumio volumio[727]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 07 23:23:40 volumio volumio[727]: verbose: CURRENT POSITION 0 Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::syncState stateService stop Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus stop Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::pushState Apr 07 23:23:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Apr 07 23:23:40 volumio volumio[727]: info: No code Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::pushState Apr 07 23:23:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Apr 07 23:23:40 volumio volumio[727]: info: ------------------------------ 158ms Apr 07 23:23:40 volumio volumio[727]: info: ------------------------------ 148ms Apr 07 23:23:40 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Apr 07 23:23:40 volumio volumio[727]: info: Apr 07 23:23:40 volumio volumio[727]: ---------------------------- MPD announces state update: update Apr 07 23:23:40 volumio volumio[727]: info: ControllerMpd::getState Apr 07 23:23:40 volumio volumio[727]: verbose: ControllerMpd::sendMpdCommand status Apr 07 23:23:40 volumio volumio[727]: info: sendMpdCommand status took 128 milliseconds Apr 07 23:23:40 volumio volumio[727]: info: sendMpdCommand status took 123 milliseconds Apr 07 23:23:40 volumio volumio[727]: info: sendMpdCommand status took 4 milliseconds Apr 07 23:23:40 volumio volumio[727]: info: sendMpdCommand status took 3 milliseconds Apr 07 23:23:40 volumio volumio[727]: verbose: ControllerMpd::parseState Apr 07 23:23:40 volumio volumio[727]: info: Command Router : Notfying DB Updatefalse Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::Close All Modals sent Apr 07 23:23:40 volumio volumio[727]: verbose: ControllerMpd::parseState Apr 07 23:23:40 volumio volumio[727]: verbose: ControllerMpd::parseState Apr 07 23:23:40 volumio volumio[727]: info: Command Router : Notfying DB Updatefalse Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::Close All Modals sent Apr 07 23:23:40 volumio volumio[727]: verbose: ControllerMpd::parseState Apr 07 23:23:40 volumio volumio[727]: info: ControllerMpd::pushState Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::servicePushState Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::pushState Apr 07 23:23:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Apr 07 23:23:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:23:40 volumio volumio[727]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 07 23:23:40 volumio volumio[727]: verbose: CURRENT POSITION 0 Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::syncState stateService stop Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus stop Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::pushState Apr 07 23:23:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Apr 07 23:23:40 volumio volumio[727]: info: No code Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::pushState Apr 07 23:23:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Apr 07 23:23:40 volumio volumio[727]: info: ControllerMpd::pushState Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::servicePushState Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::pushState Apr 07 23:23:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Apr 07 23:23:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:23:40 volumio volumio[727]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 07 23:23:40 volumio volumio[727]: verbose: CURRENT POSITION 0 Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::syncState stateService stop Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::syncState currentStatus stop Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::pushState Apr 07 23:23:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Apr 07 23:23:40 volumio volumio[727]: info: No code Apr 07 23:23:40 volumio volumio[727]: info: CoreStateMachine::pushState Apr 07 23:23:40 volumio volumio[727]: info: CorePlayQueue::getTrack 0 Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::volumioPushState Apr 07 23:23:40 volumio volumio[727]: info: ------------------------------ 273ms Apr 07 23:23:40 volumio volumio[727]: info: ------------------------------ 149ms Apr 07 23:23:40 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 07 23:23:44 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 07 23:23:48 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 07 23:23:51 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 07 23:23:51 volumio volumio[727]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 07 23:23:51 volumio volumio[727]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 07 23:23:51 volumio volumio[727]: Error: getaddrinfo -3007 Apr 07 23:23:51 volumio volumio[727]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Apr 07 23:23:51 volumio volumio[727]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Apr 07 23:23:51 volumio volumio[727]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Apr 07 23:23:51 volumio volumio[727]: code: -3007, Apr 07 23:23:51 volumio volumio[727]: errno: -3007, Apr 07 23:23:51 volumio volumio[727]: syscall: 'getaddrinfo' Apr 07 23:23:51 volumio volumio[727]: } Apr 07 23:23:51 volumio volumio[727]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 07 23:23:51 volumio sudo[1918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-07 23:22 Apr 07 23:23:51 volumio sudo[1918]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET" VOLUMIO_VERSION="3.634" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"