-- 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"