-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Tue 2024-11-26 06:41:45 UTC. -- Nov 26 06:40:00 volumio sudo[2132]: pam_unix(sudo:session): session closed for user root Nov 26 06:40:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Nov 26 06:40:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Nov 26 06:40:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 26 06:40:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 06:40:04 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 26 06:40:05 volumio sudo[2153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 06:40:05 volumio sudo[2155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 06:40:05 volumio sudo[2153]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:40:05 volumio sudo[2155]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:40:05 volumio sudo[2153]: pam_unix(sudo:session): session closed for user root Nov 26 06:40:05 volumio sudo[2155]: pam_unix(sudo:session): session closed for user root Nov 26 06:40:05 volumio sudo[2158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 26 06:40:05 volumio sudo[2158]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:40:05 volumio sudo[2158]: pam_unix(sudo:session): session closed for user root Nov 26 06:40:05 volumio volumio[1058]: info: Upmpdcli Daemon Started Nov 26 06:40:09 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 26 06:40:09 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 06:40:09 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 26 06:40:09 volumio volumio[1058]: error: Could not retrieve plugin system_controller my_volumio Nov 26 06:40:10 volumio systemd[1]: systemd-timedated.service: Succeeded. Nov 26 06:40:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Nov 26 06:40:21 volumio volumio[1058]: info: Preparing to save Alsa Options, stopping services first Nov 26 06:40:21 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Nov 26 06:40:21 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Nov 26 06:40:21 volumio volumio[1058]: info: CoreCommandRouter::volumioPause Nov 26 06:40:21 volumio volumio[1058]: info: CoreStateMachine::pause Nov 26 06:40:21 volumio volumio[1058]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"0","label":"AML-AUGESOUND-515"},"disallowPush":true} Nov 26 06:40:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 26 06:40:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 26 06:40:21 volumio volumio[1058]: info: Found match in Cards Database: setting mixer for card AML-AUGESOUND-515 Nov 26 06:40:21 volumio volumio[1058]: info: Setting default mixerSoftMaster for Softvolume device Nov 26 06:40:21 volumio volumio[1058]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 26 06:40:21 volumio volumio[1058]: info: Updating Volume Controller Parameters: Device: 0 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 26 06:40:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 26 06:40:21 volumio volumio[1058]: info: Disabling external Volume Control Nov 26 06:40:21 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 26 06:40:21 volumio volumio[1058]: info: Enable softmixer device for audio device undefined Nov 26 06:40:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Nov 26 06:40:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Nov 26 06:40:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Nov 26 06:40:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Nov 26 06:40:22 volumio sudo[2246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 26 06:40:22 volumio sudo[2246]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:40:22 volumio volumio[1058]: info: CoreStateMachine::pushState Nov 26 06:40:22 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Nov 26 06:40:22 volumio kernel: [dhd] [wlan0] wl_escan_set_scan : LEGACY_SCAN Nov 26 06:40:22 volumio volumio[1058]: info: Software Volume ALSA configuration written Nov 26 06:40:22 volumio volumio[1058]: info: Preparing to generate the ALSA configuration file Nov 26 06:40:22 volumio sudo[2248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 26 06:40:22 volumio sudo[2248]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:40:22 volumio sudo[2248]: pam_unix(sudo:session): session closed for user root Nov 26 06:40:22 volumio kernel: [dhd] [wlan0] AEXT-ERROR) wl_ext_check_scan : escan scanning... Nov 26 06:40:22 volumio kernel: [dhd] CFG80211-ERROR) wl_escan_handler : escan is not ready. drv_scan_status 0x0 e_type 69 e_status 8 Nov 26 06:40:22 volumio kernel: [dhd] CFG80211-ERROR) wl_escan_handler : [Repeats 8 times] Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 26 06:40:22 volumio volumio[1058]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 26 06:40:22 volumio volumio[1058]: wlan0 Interface doesn't support scanning : Device or resource busy Nov 26 06:40:22 volumio volumio[1058]: info: Cannot use regular scanning, forcing with ap-force Nov 26 06:40:22 volumio sudo[2256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 26 06:40:22 volumio sudo[2256]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:40:22 volumio sudo[2256]: pam_unix(sudo:session): session closed for user root Nov 26 06:40:22 volumio kernel: [dhd] CFG80211-ERROR) update_roam_cache : Failed to get roamscan channels, error = -23 Nov 26 06:40:22 volumio kernel: [dhd] CFG80211-ERROR) update_roam_cache : Failed to get roamscan channels, error = -23 Nov 26 06:40:22 volumio volumio[1058]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 26 06:40:22 volumio volumio[1058]: info: Reading ALSA contributions from plugins. Nov 26 06:40:22 volumio volumio[1058]: info: Asound.conf file unchanged, so no further update is needed Nov 26 06:40:22 volumio volumio[1058]: info: Output device has changed, restarting MPD Nov 26 06:40:22 volumio volumio[1058]: info: Output device has changed, restarting Shairport Sync Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 06:40:22 volumio sudo[2246]: pam_unix(sudo:session): session closed for user root Nov 26 06:40:22 volumio sudo[2259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 06:40:22 volumio sudo[2259]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:40:22 volumio sudo[2261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 06:40:22 volumio sudo[2261]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:40:22 volumio volumio[1058]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:40:22 volumio sudo[2259]: pam_unix(sudo:session): session closed for user root Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:40:22 volumio systemd[1]: Stopping Music Player Daemon... Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 26 06:40:22 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:40:22 volumio volumio[1058]: info: MPD Permissions set Nov 26 06:40:22 volumio volumio[1058]: info: Starting Shairport Sync Nov 26 06:40:22 volumio sudo[2274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 26 06:40:22 volumio sudo[2274]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:40:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 26 06:40:22 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 26 06:40:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 26 06:40:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 26 06:40:22 volumio sudo[2274]: pam_unix(sudo:session): session closed for user root Nov 26 06:40:22 volumio volumio[1058]: info: Shairport-Sync Started Nov 26 06:40:23 volumio systemd[1]: mpd.service: Succeeded. Nov 26 06:40:23 volumio systemd[1]: Stopped Music Player Daemon. Nov 26 06:40:23 volumio systemd[1]: Starting Music Player Daemon... Nov 26 06:40:23 volumio sudo[2279]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 06:40:23 volumio sudo[2279]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:40:23 volumio sudo[2279]: pam_unix(sudo:session): session closed for user root Nov 26 06:40:24 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Nov 26 06:40:24 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Nov 26 06:40:24 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Nov 26 06:40:24 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Nov 26 06:40:24 volumio sudo[2283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 26 06:40:24 volumio sudo[2283]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:40:24 volumio kernel: [dhd] [wlan0] wl_escan_set_scan : LEGACY_SCAN Nov 26 06:40:24 volumio sudo[2286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 26 06:40:24 volumio sudo[2286]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:40:24 volumio kernel: [dhd] [wlan0] AEXT-ERROR) wl_ext_check_scan : escan scanning... Nov 26 06:40:24 volumio sudo[2286]: pam_unix(sudo:session): session closed for user root Nov 26 06:40:24 volumio volumio[1058]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 26 06:40:24 volumio volumio[1058]: wlan0 Interface doesn't support scanning : Device or resource busy Nov 26 06:40:24 volumio volumio[1058]: info: Cannot use regular scanning, forcing with ap-force Nov 26 06:40:24 volumio sudo[2289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 26 06:40:24 volumio sudo[2289]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:40:24 volumio kernel: [dhd] CFG80211-ERROR) update_roam_cache : Failed to get roamscan channels, error = -23 Nov 26 06:40:24 volumio kernel: [dhd] CFG80211-ERROR) update_roam_cache : Failed to get roamscan channels, error = -23 Nov 26 06:40:24 volumio sudo[2289]: pam_unix(sudo:session): session closed for user root Nov 26 06:40:24 volumio sudo[2283]: pam_unix(sudo:session): session closed for user root Nov 26 06:40:25 volumio volumio[1058]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Nov 26 06:40:25 volumio volumio[1058]: info: CoreStateMachine::getcurrentVolume Nov 26 06:40:25 volumio volumio[1058]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 06:40:25 volumio volumio[1058]: info: CoreStateMachine::pushState Nov 26 06:40:25 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Nov 26 06:40:25 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Nov 26 06:40:25 volumio volumio[1058]: info: CoreStateMachine::updateTrackBlock Nov 26 06:40:25 volumio volumio[1058]: info: CorePlayQueue::getTrackBlock Nov 26 06:40:25 volumio volumio[1058]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 06:40:26 volumio volumio[1058]: info: CoreStateMachine::pushState Nov 26 06:40:26 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Nov 26 06:40:26 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Nov 26 06:40:26 volumio mpd[2281]: Nov 26 06:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 26 06:40:26 volumio systemd[1]: Started Music Player Daemon. Nov 26 06:40:26 volumio sudo[2261]: pam_unix(sudo:session): session closed for user root Nov 26 06:40:26 volumio volumio[1058]: error: updateQueue error: null Nov 26 06:40:26 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Nov 26 06:40:26 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Nov 26 06:40:26 volumio volumio[1058]: error: Could not retrieve plugin system_controller my_volumio Nov 26 06:40:26 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Nov 26 06:40:26 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Nov 26 06:40:26 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Nov 26 06:40:26 volumio volumio[1058]: error: Could not retrieve plugin system_controller my_volumio Nov 26 06:40:26 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Nov 26 06:40:26 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Nov 26 06:40:26 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Nov 26 06:40:26 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Nov 26 06:40:26 volumio sudo[2320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 26 06:40:26 volumio sudo[2320]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:40:26 volumio kernel: [dhd] [wlan0] wl_escan_set_scan : LEGACY_SCAN Nov 26 06:40:26 volumio sudo[2322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 26 06:40:26 volumio sudo[2322]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:40:26 volumio kernel: [dhd] [wlan0] AEXT-ERROR) wl_ext_check_scan : escan scanning... Nov 26 06:40:26 volumio sudo[2322]: pam_unix(sudo:session): session closed for user root Nov 26 06:40:26 volumio volumio[1058]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Nov 26 06:40:26 volumio volumio[1058]: wlan0 Interface doesn't support scanning : Device or resource busy Nov 26 06:40:26 volumio volumio[1058]: info: Cannot use regular scanning, forcing with ap-force Nov 26 06:40:26 volumio sudo[2326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Nov 26 06:40:26 volumio sudo[2326]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:40:26 volumio kernel: [dhd] CFG80211-ERROR) wl_run_escan : Escan set error (-16) Nov 26 06:40:26 volumio kernel: [dhd] CFG80211-ERROR) wl_run_escan : scan error (-16) Nov 26 06:40:26 volumio kernel: [dhd] CFG80211-ERROR) wl_cfgscan_handle_scanbusy : Scan err = (-16), busy?-16 Nov 26 06:40:26 volumio kernel: [dhd] CFG80211-ERROR) wl_escan_handler : escan is not ready. drv_scan_status 0x0 e_type 69 e_status 9 Nov 26 06:40:26 volumio kernel: [dhd] CFG80211-ERROR) wl_escan_handler : [Repeats 31 times] Nov 26 06:40:27 volumio sudo[2320]: pam_unix(sudo:session): session closed for user root Nov 26 06:40:27 volumio sudo[2326]: pam_unix(sudo:session): session closed for user root Nov 26 06:40:27 volumio kernel: [dhd] CFG80211-ERROR) wl_cfg80211_scan : scan error (-16) Nov 26 06:40:27 volumio volumio[1058]: command failed: Device or resource busy (-16) Nov 26 06:40:27 volumio volumio[1058]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Nov 26 06:40:27 volumio volumio[1058]: command failed: Device or resource busy (-16) Nov 26 06:40:27 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Nov 26 06:40:27 volumio volumio[1058]: info: CoreCommandRouter::Reload Ui Nov 26 06:40:27 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Nov 26 06:40:27 volumio volumio[1058]: info: Wizard terminated Successfully Nov 26 06:40:27 volumio volumio[1058]: info: CoreCommandRouter::volumiosetStartupVolume Nov 26 06:40:27 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:40:29 volumio volumio[1058]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 3 Nov 26 06:40:30 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Nov 26 06:40:30 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Nov 26 06:40:30 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 26 06:40:30 volumio volumio[1058]: error: Could not retrieve plugin audio_interface multiroom Nov 26 06:40:30 volumio volumio[1058]: info: CoreCommandRouter::volumioGetVisibleSources Nov 26 06:40:30 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 06:40:30 volumio volumio[1058]: info: Listing playlists Nov 26 06:40:30 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 26 06:40:30 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 26 06:40:30 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 26 06:40:30 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 26 06:40:34 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 26 06:40:34 volumio volumio[1058]: error: Could not retrieve plugin music_service streaming_services Nov 26 06:40:34 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 26 06:40:34 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 26 06:40:34 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 26 06:40:34 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 26 06:40:34 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:40:34 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:40:34 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:40:34 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:40:34 volumio volumio[1058]: info: CoreCommandRouter::volumioGetBrowseSources Nov 26 06:40:34 volumio volumio[1058]: info: CoreCommandRouter::volumioGetBrowseSources Nov 26 06:40:34 volumio volumio[1058]: info: CoreCommandRouter::volumioGetBrowseSources Nov 26 06:40:35 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 26 06:40:35 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 26 06:40:37 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 26 06:40:37 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 26 06:40:37 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 06:40:37 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 26 06:40:37 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:40:37 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:40:37 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:40:37 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:40:37 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:40:37 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:40:37 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:40:37 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 26 06:40:37 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 26 06:40:49 volumio login[905]: pam_unix(login:session): session opened for user volumio by LOGIN(uid=0) Nov 26 06:40:49 volumio systemd[1]: Created slice User Slice of UID 1000. Nov 26 06:40:49 volumio systemd[1]: Starting User Runtime Directory /run/user/1000... Nov 26 06:40:49 volumio systemd-logind[710]: New session 1 of user volumio. Nov 26 06:40:49 volumio systemd[1]: Started User Runtime Directory /run/user/1000. Nov 26 06:40:49 volumio systemd[1]: Starting User Manager for UID 1000... Nov 26 06:40:49 volumio systemd[2394]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Nov 26 06:40:50 volumio systemd[2394]: Reached target Timers. Nov 26 06:40:50 volumio systemd[2394]: Listening on GnuPG cryptographic agent and passphrase cache. Nov 26 06:40:50 volumio systemd[2394]: Listening on GnuPG network certificate management daemon. Nov 26 06:40:50 volumio systemd[2394]: Starting D-Bus User Message Bus Socket. Nov 26 06:40:50 volumio systemd[2394]: Reached target Paths. Nov 26 06:40:50 volumio systemd[2394]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Nov 26 06:40:50 volumio systemd[2394]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Nov 26 06:40:50 volumio systemd[2394]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Nov 26 06:40:50 volumio systemd[2394]: Listening on D-Bus User Message Bus Socket. Nov 26 06:40:50 volumio systemd[2394]: Reached target Sockets. Nov 26 06:40:50 volumio systemd[2394]: Reached target Basic System. Nov 26 06:40:50 volumio systemd[2394]: Reached target Default. Nov 26 06:40:50 volumio systemd[2394]: Startup finished in 294ms. Nov 26 06:40:50 volumio systemd[1]: Started User Manager for UID 1000. Nov 26 06:40:50 volumio systemd[1]: Started Session 1 of user volumio. Nov 26 06:40:50 volumio ntpd[1497]: Soliciting pool server 2001:ac8:a:23:0:1:0:14 Nov 26 06:40:57 volumio kernel: usb 1-1.1: USB disconnect, device number 3 Nov 26 06:41:01 volumio kernel: xhci-hcd-meson xhci-hcd-meson.0.auto: aml_xhci_alloc_dev, slot=2 Nov 26 06:41:01 volumio kernel: usb 1-1.1: new high-speed USB device number 4 using xhci-hcd-meson Nov 26 06:41:01 volumio kernel: usb 1-1.1: device descriptor read/64, error -71 Nov 26 06:41:02 volumio kernel: usb 1-1.1: device descriptor read/64, error -71 Nov 26 06:41:02 volumio kernel: xhci-hcd-meson xhci-hcd-meson.0.auto: aml_xhci_alloc_dev, slot=2 Nov 26 06:41:02 volumio kernel: usb 1-1.1: new high-speed USB device number 5 using xhci-hcd-meson Nov 26 06:41:02 volumio kernel: usb 1-1.1: New USB device found, idVendor=3353, idProduct=a002, bcdDevice= 1.00 Nov 26 06:41:02 volumio kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Nov 26 06:41:02 volumio kernel: usb 1-1.1: Product: Tone2 Pro Nov 26 06:41:02 volumio kernel: usb 1-1.1: Manufacturer: Khadas Nov 26 06:41:02 volumio kernel: usb 1-1.1: SerialNumber: 00000000 Nov 26 06:41:02 volumio kernel: hid-generic 0003:3353:A002.0003: hiddev96,hidraw0: USB HID v1.10 Device [Khadas Tone2 Pro] on usb-xhci-hcd-meson.0.auto-1.1/input4 Nov 26 06:41:02 volumio kernel: input: Khadas Tone2 Pro as /devices/platform/ff500000.dwc3/xhci-hcd-meson.0.auto/usb1/1-1/1-1.1/1-1.1:1.5/0003:3353:A002.0004/input/input16 Nov 26 06:41:02 volumio kernel: hid-generic 0003:3353:A002.0004: input,hidraw1: USB HID v1.10 Device [Khadas Tone2 Pro] on usb-xhci-hcd-meson.0.auto-1.1/input5 Nov 26 06:41:02 volumio kernel: usb 1-1.1: 1:3 : unsupported format bits 0x100000000 Nov 26 06:41:03 volumio kernel: usbcore: registered new interface driver snd-usb-audio Nov 26 06:41:03 volumio systemd-udevd[2468]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 26 06:41:03 volumio systemd-udevd[2467]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 1' failed with exit code 99. Nov 26 06:41:10 volumio sudo[2507]: volumio : TTY=ttyS0 ; PWD=/home/volumio ; USER=root ; COMMAND=/sbin/ifconfig Nov 26 06:41:10 volumio sudo[2507]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Nov 26 06:41:10 volumio sudo[2507]: pam_unix(sudo:session): session closed for user root Nov 26 06:41:18 volumio sudo[2546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 06:41:18 volumio sudo[2546]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:41:18 volumio sudo[2546]: pam_unix(sudo:session): session closed for user root Nov 26 06:41:18 volumio sudo[2548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 06:41:18 volumio sudo[2548]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:41:18 volumio sudo[2548]: pam_unix(sudo:session): session closed for user root Nov 26 06:41:18 volumio volumio[1058]: verbose: New Socket.io Connection to 192.168.31.162 from 192.168.31.232 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 26 06:41:18 volumio volumio[1058]: info: CoreCommandRouter::volumioGetVisibleSources Nov 26 06:41:18 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 06:41:18 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 26 06:41:18 volumio volumio[1058]: error: Could not retrieve plugin audio_interface multiroom Nov 26 06:41:18 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Nov 26 06:41:18 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Nov 26 06:41:18 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 26 06:41:18 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 26 06:41:18 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 26 06:41:18 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 26 06:41:19 volumio sudo[2552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 26 06:41:19 volumio sudo[2552]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:41:19 volumio sudo[2552]: pam_unix(sudo:session): session closed for user root Nov 26 06:41:19 volumio sudo[2554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 26 06:41:19 volumio sudo[2554]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:41:19 volumio sudo[2554]: pam_unix(sudo:session): session closed for user root Nov 26 06:41:19 volumio volumio[1058]: verbose: New Socket.io Connection to 192.168.31.162 from 192.168.31.232 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 26 06:41:19 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Nov 26 06:41:19 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Nov 26 06:41:19 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 26 06:41:19 volumio volumio[1058]: error: Could not retrieve plugin audio_interface multiroom Nov 26 06:41:19 volumio volumio[1058]: info: CoreCommandRouter::volumioGetVisibleSources Nov 26 06:41:19 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 26 06:41:19 volumio volumio[1058]: info: Listing playlists Nov 26 06:41:19 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 26 06:41:19 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 26 06:41:19 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 26 06:41:19 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 26 06:41:21 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 26 06:41:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 26 06:41:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 06:41:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 26 06:41:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:41:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:41:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:41:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:41:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:41:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:41:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:41:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 26 06:41:21 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 26 06:41:23 volumio volumio[1058]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Nov 26 06:41:23 volumio volumio[1058]: info: Preparing to save Alsa Options, stopping services first Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::volumioGetState Nov 26 06:41:23 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::volumioPause Nov 26 06:41:23 volumio volumio[1058]: info: CoreStateMachine::pause Nov 26 06:41:23 volumio volumio[1058]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"Tone2 Pro"}} Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 26 06:41:23 volumio volumio[1058]: info: Found match in Cards Database: setting mixer for card Tone2 Pro Nov 26 06:41:23 volumio volumio[1058]: info: Setting default mixerSoftMaster for Softvolume device Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 26 06:41:23 volumio volumio[1058]: info: Updating Volume Controller Parameters: Device: 1 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 26 06:41:23 volumio volumio[1058]: info: Disabling external Volume Control Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 26 06:41:23 volumio volumio[1058]: info: Enable softmixer device for audio device undefined Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 26 06:41:23 volumio volumio[1058]: info: Software Volume ALSA configuration written Nov 26 06:41:23 volumio volumio[1058]: info: Preparing to generate the ALSA configuration file Nov 26 06:41:23 volumio volumio[1058]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Nov 26 06:41:23 volumio volumio[1058]: info: Reading ALSA contributions from plugins. Nov 26 06:41:23 volumio volumio[1058]: info: Asound.conf file written Nov 26 06:41:23 volumio sudo[2630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 26 06:41:23 volumio sudo[2630]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:41:23 volumio sudo[2630]: pam_unix(sudo:session): session closed for user root Nov 26 06:41:23 volumio volumio[1058]: No state is present for card AMLAUGESOUND515 Nov 26 06:41:23 volumio volumio[1058]: Found hardware: "AML-AUGESOUND-5" "" "" "" "" Nov 26 06:41:23 volumio volumio[1058]: Hardware is initialized using a generic method Nov 26 06:41:23 volumio volumio[1058]: No state is present for card AMLAUGESOUND515 Nov 26 06:41:23 volumio volumio[1058]: No state is present for card Pro Nov 26 06:41:23 volumio volumio[1058]: Found hardware: "USB-Audio" "USB Mixer" "USB3353:a002" "" "" Nov 26 06:41:23 volumio volumio[1058]: Hardware is initialized using a generic method Nov 26 06:41:23 volumio volumio[1058]: No state is present for card Pro Nov 26 06:41:23 volumio volumio[1058]: info: Output device has changed, restarting MPD Nov 26 06:41:23 volumio volumio[1058]: info: Output device has changed, restarting Shairport Sync Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 06:41:23 volumio sudo[2635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 06:41:23 volumio sudo[2635]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:41:23 volumio sudo[2635]: pam_unix(sudo:session): session closed for user root Nov 26 06:41:23 volumio sudo[2637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 06:41:23 volumio sudo[2637]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:41:23 volumio volumio[1058]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 06:41:23 volumio volumio[1058]: info: Output device has changed, restarting MPD Nov 26 06:41:23 volumio systemd[1]: Stopping Music Player Daemon... Nov 26 06:41:23 volumio sudo[2645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 26 06:41:23 volumio sudo[2645]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:41:23 volumio volumio[1058]: info: Output device has changed, restarting Shairport Sync Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 06:41:23 volumio sudo[2647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 26 06:41:23 volumio sudo[2645]: pam_unix(sudo:session): session closed for user root Nov 26 06:41:23 volumio sudo[2647]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:41:23 volumio volumio[1058]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 26 06:41:23 volumio volumio[1058]: info: MPD Permissions set Nov 26 06:41:23 volumio volumio[1058]: info: MPD Permissions set Nov 26 06:41:23 volumio volumio[1058]: info: CoreStateMachine::pushState Nov 26 06:41:23 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 26 06:41:23 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 26 06:41:23 volumio volumio[1058]: info: Starting Shairport Sync Nov 26 06:41:23 volumio volumio[1058]: info: Starting Shairport Sync Nov 26 06:41:23 volumio systemd[1]: mpd.service: Succeeded. Nov 26 06:41:23 volumio systemd[1]: Stopped Music Player Daemon. Nov 26 06:41:23 volumio systemd[1]: Starting Music Player Daemon... Nov 26 06:41:23 volumio sudo[2666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 26 06:41:23 volumio sudo[2666]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:41:23 volumio sudo[2669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 26 06:41:23 volumio sudo[2669]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:41:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 26 06:41:23 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 26 06:41:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 26 06:41:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 26 06:41:23 volumio sudo[2666]: pam_unix(sudo:session): session closed for user root Nov 26 06:41:23 volumio volumio[1058]: info: Shairport-Sync Started Nov 26 06:41:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 26 06:41:23 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 26 06:41:23 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 26 06:41:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 26 06:41:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 26 06:41:24 volumio sudo[2669]: pam_unix(sudo:session): session closed for user root Nov 26 06:41:24 volumio volumio[1058]: info: Shairport-Sync Started Nov 26 06:41:24 volumio sudo[2668]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 26 06:41:24 volumio sudo[2668]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 26 06:41:24 volumio sudo[2668]: pam_unix(sudo:session): session closed for user root Nov 26 06:41:25 volumio mpd[2675]: Nov 26 06:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 26 06:41:25 volumio systemd[1]: Started Music Player Daemon. Nov 26 06:41:25 volumio sudo[2647]: pam_unix(sudo:session): session closed for user root Nov 26 06:41:25 volumio sudo[2637]: pam_unix(sudo:session): session closed for user root Nov 26 06:41:25 volumio volumio[1058]: error: MPD error: The expression evaluated to a falsy value: Nov 26 06:41:25 volumio volumio[1058]: assert.ok(self.idling) Nov 26 06:41:25 volumio volumio[1058]: error: The expression evaluated to a falsy value: Nov 26 06:41:25 volumio volumio[1058]: assert.ok(self.idling) Nov 26 06:41:25 volumio volumio[1058]: error: updateQueue error: null Nov 26 06:41:27 volumio volumio[1058]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Nov 26 06:41:27 volumio volumio[1058]: info: CoreStateMachine::getcurrentVolume Nov 26 06:41:27 volumio volumio[1058]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 06:41:27 volumio volumio[1058]: info: CoreStateMachine::pushState Nov 26 06:41:27 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Nov 26 06:41:27 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Nov 26 06:41:27 volumio volumio[1058]: info: CoreStateMachine::updateTrackBlock Nov 26 06:41:27 volumio volumio[1058]: info: CorePlayQueue::getTrackBlock Nov 26 06:41:27 volumio volumio[1058]: info: CoreCommandRouter::volumioRetrievevolume Nov 26 06:41:27 volumio volumio[1058]: info: CoreStateMachine::pushState Nov 26 06:41:27 volumio volumio[1058]: info: CorePlayQueue::getTrack 0 Nov 26 06:41:27 volumio volumio[1058]: info: CoreCommandRouter::volumioPushState Nov 26 06:41:38 volumio volumio[1058]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 26 06:41:38 volumio volumio[1058]: error: Could not retrieve plugin music_service streaming_services Nov 26 06:41:38 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 26 06:41:38 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 26 06:41:38 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 26 06:41:38 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 26 06:41:38 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:41:38 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:41:38 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:41:38 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 26 06:41:38 volumio volumio[1058]: info: CoreCommandRouter::volumioGetBrowseSources Nov 26 06:41:38 volumio volumio[1058]: info: CoreCommandRouter::volumioGetBrowseSources Nov 26 06:41:38 volumio volumio[1058]: info: CoreCommandRouter::volumioGetBrowseSources Nov 26 06:41:38 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 26 06:41:38 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 26 06:41:39 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Nov 26 06:41:39 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Nov 26 06:41:42 volumio volumio[1058]: info: Error browsing device WeDocs for shares : Error: Command failed: echo volumio | smbclient -L WeDocs.local. Nov 26 06:41:42 volumio volumio[1058]: Unable to initialize messaging context Nov 26 06:41:42 volumio volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 26 06:41:45 volumio volumio[1058]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 06:41:45 volumio volumio[1058]: Error: dns service error: unknown Nov 26 06:41:45 volumio volumio[1058]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Nov 26 06:41:45 volumio volumio[1058]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Nov 26 06:41:45 volumio volumio[1058]: errorCode: -65537 Nov 26 06:41:45 volumio volumio[1058]: } Nov 26 06:41:45 volumio volumio[1058]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 06:41:45 volumio sudo[2804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 06:40 Nov 26 06:41:45 volumio sudo[2804]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Nov 25 05:13:21 AM UTC 2024" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="vim3l" VOLUMIO_DEVICENAME="Khadas VIM3L" VOLUMIO_HASH="074917d1ca9e9870e4f3627f241e04c9"