-- Logs begin at Sun 2025-05-18 09:28:09 CST, end at Sun 2025-05-18 10:55:11 CST. -- May 18 10:54:31 volumio sudo[32418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 18 10:54:31 volumio sudo[32418]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:54:31 volumio sudo[32418]: pam_unix(sudo:session): session closed for user root May 18 10:54:31 volumio sudo[32421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 10:54:31 volumio sudo[32421]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:54:31 volumio sudo[32421]: pam_unix(sudo:session): session closed for user root May 18 10:54:31 volumio volumio[597]: verbose: New Socket.io Connection to 192.168.3.161 from 192.168.3.74 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Total Clients: 7 May 18 10:54:31 volumio sudo[32426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 18 10:54:31 volumio sudo[32426]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:54:31 volumio sudo[32426]: pam_unix(sudo:session): session closed for user root May 18 10:54:31 volumio sudo[32429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 10:54:31 volumio sudo[32429]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:54:31 volumio sudo[32429]: pam_unix(sudo:session): session closed for user root May 18 10:54:31 volumio volumio[597]: verbose: New Socket.io Connection to 192.168.3.161 from 192.168.3.74 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Edg/136.0.0.0 Total Clients: 8 May 18 10:54:31 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 18 10:54:31 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 18 10:54:31 volumio volumio[597]: info: CoreCommandRouter::volumioGetVisibleSources May 18 10:54:31 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 10:54:31 volumio volumio[597]: info: CoreCommandRouter::volumioGetState May 18 10:54:31 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 18 10:54:31 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 18 10:54:31 volumio volumio[597]: info: Received Get System Info May 18 10:54:31 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 10:54:31 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 10:54:31 volumio volumio[597]: info: Discovery: Getting this device information May 18 10:54:31 volumio volumio[597]: info: CoreCommandRouter::volumioGetState May 18 10:54:31 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 10:54:31 volumio volumio[597]: info: CoreCommandRouter::volumioGetState May 18 10:54:31 volumio volumio[597]: info: Listing playlists May 18 10:54:31 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 18 10:54:31 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 18 10:54:32 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 18 10:54:32 volumio volumio[597]: info: CoreCommandRouter::volumioGetState May 18 10:54:33 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 10:54:33 volumio volumio[597]: info: Received Get System Info May 18 10:54:33 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 10:54:33 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 10:54:33 volumio volumio[597]: info: Discovery: Getting this device information May 18 10:54:33 volumio volumio[597]: info: CoreCommandRouter::volumioGetState May 18 10:54:33 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 10:54:39 volumio volumio[597]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 10:54:39 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 18 10:54:39 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:54:39 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 18 10:54:39 volumio volumio[597]: Invalid card number. May 18 10:54:39 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:54:39 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:54:39 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:54:39 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:54:39 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:54:39 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:54:39 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:54:39 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 18 10:54:39 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 18 10:54:47 volumio volumio[597]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 18 10:54:47 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 18 10:54:47 volumio volumio[597]: info: Preparing to save Alsa Options, stopping services first May 18 10:54:47 volumio volumio[597]: info: CoreCommandRouter::volumioGetState May 18 10:54:47 volumio volumio[597]: info: CoreCommandRouter::volumioPause May 18 10:54:47 volumio volumio[597]: info: CoreStateMachine::pause May 18 10:54:47 volumio volumio[597]: info: CoreStateMachine::stPlaybackTimer May 18 10:54:47 volumio volumio[597]: info: CoreStateMachine::servicePause May 18 10:54:47 volumio volumio[597]: info: CorePlayQueue::getTrack 0 May 18 10:54:47 volumio volumio[597]: info: Error: no service or no trackblock to pause May 18 10:54:47 volumio volumio[597]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Combo384 Amanero"}} May 18 10:54:47 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 18 10:54:48 volumio volumio[597]: warn: Unable to locate the device Combo384 Amanero a reboot may be required. May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 18 10:54:48 volumio volumio[597]: Invalid card number. May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 18 10:54:48 volumio volumio[597]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 18 10:54:48 volumio volumio[597]: info: Disabling external Volume Control May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 10:54:48 volumio volumio[597]: info: Preparing to generate the ALSA configuration file May 18 10:54:48 volumio volumio[597]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 18 10:54:48 volumio volumio[597]: info: Reading ALSA contributions from plugins. May 18 10:54:48 volumio volumio[597]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 18 10:54:48 volumio volumio[597]: Invalid card number. May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 18 10:54:48 volumio volumio[597]: info: VolumeController:: Volume=undefined Mute =false May 18 10:54:48 volumio volumio[597]: info: CoreStateMachine::pushState May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::volumioPushState May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::volumioGetQueue May 18 10:54:48 volumio volumio[597]: info: CoreStateMachine::getQueue May 18 10:54:48 volumio volumio[597]: info: CorePlayQueue::getQueue May 18 10:54:48 volumio volumio[597]: info: Asound.conf file written May 18 10:54:48 volumio sudo[32548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 18 10:54:48 volumio sudo[32548]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:54:48 volumio sudo[32548]: pam_unix(sudo:session): session closed for user root May 18 10:54:48 volumio volumio[597]: info: Output device has changed, restarting MPD May 18 10:54:48 volumio sudo[32554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 18 10:54:48 volumio sudo[32554]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:54:48 volumio volumio[597]: info: Output device has changed, restarting Shairport Sync May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:54:48 volumio sudo[32554]: pam_unix(sudo:session): session closed for user root May 18 10:54:48 volumio sudo[32557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 18 10:54:48 volumio sudo[32557]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:54:48 volumio systemd[1]: Stopping Music Player Daemon... May 18 10:54:48 volumio volumio[597]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 18 10:54:48 volumio volumio[597]: info: Output device has changed, restarting MPD May 18 10:54:48 volumio sudo[32565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 18 10:54:48 volumio sudo[32565]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:54:48 volumio sudo[32565]: pam_unix(sudo:session): session closed for user root May 18 10:54:48 volumio systemd[1]: mpd.service: Succeeded. May 18 10:54:48 volumio systemd[1]: Stopped Music Player Daemon. May 18 10:54:48 volumio volumio[597]: info: Output device has changed, restarting Shairport Sync May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:54:48 volumio sudo[32568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 18 10:54:48 volumio sudo[32568]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:54:48 volumio systemd[1]: Starting Music Player Daemon... May 18 10:54:48 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 18 10:54:48 volumio systemd[1]: mpd.service: Succeeded. May 18 10:54:48 volumio systemd[1]: Stopped Music Player Daemon. May 18 10:54:48 volumio volumio[597]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 18 10:54:48 volumio volumio[597]: info: MPD Permissions set May 18 10:54:48 volumio systemd[1]: Starting Music Player Daemon... May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:48 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:49 volumio volumio[597]: info: Starting Shairport Sync May 18 10:54:49 volumio volumio[597]: info: Starting Shairport Sync May 18 10:54:49 volumio sudo[32589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 18 10:54:49 volumio sudo[32589]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:54:49 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 18 10:54:49 volumio systemd[1]: shairport-sync.service: Succeeded. May 18 10:54:49 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 18 10:54:49 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:49 volumio sudo[32589]: pam_unix(sudo:session): session closed for user root May 18 10:54:49 volumio volumio[597]: info: Shairport-Sync Started May 18 10:54:49 volumio sudo[32593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 18 10:54:49 volumio sudo[32593]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:54:49 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 18 10:54:49 volumio systemd[1]: shairport-sync.service: Succeeded. May 18 10:54:49 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 18 10:54:49 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 18 10:54:49 volumio sudo[32593]: pam_unix(sudo:session): session closed for user root May 18 10:54:49 volumio volumio[597]: error: Upnp client error: Error: This socket has been ended by the other party May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:49 volumio volumio[597]: info: Shairport-Sync Started May 18 10:54:49 volumio volumio[597]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 18 10:54:49 volumio volumio[597]: info: Preparing to save Alsa Options, stopping services first May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::volumioGetState May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::volumioPause May 18 10:54:49 volumio volumio[597]: info: CoreStateMachine::pause May 18 10:54:49 volumio volumio[597]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Combo384 Amanero"}} May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 18 10:54:49 volumio volumio[597]: warn: Unable to locate the device Combo384 Amanero a reboot may be required. May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 18 10:54:49 volumio volumio[597]: Invalid card number. May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 18 10:54:49 volumio volumio[597]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 18 10:54:49 volumio volumio[597]: info: Disabling external Volume Control May 18 10:54:49 volumio volumio[597]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 10:54:49 volumio volumio[597]: info: Preparing to generate the ALSA configuration file May 18 10:54:49 volumio volumio[597]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 18 10:54:49 volumio volumio[597]: info: Reading ALSA contributions from plugins. May 18 10:54:49 volumio volumio[597]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 18 10:54:50 volumio volumio[597]: Invalid card number. May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 18 10:54:50 volumio volumio[597]: info: VolumeController:: Volume=undefined Mute =false May 18 10:54:50 volumio volumio[597]: info: CoreStateMachine::pushState May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::volumioPushState May 18 10:54:50 volumio volumio[597]: info: Asound.conf file unchanged, so no further update is needed May 18 10:54:50 volumio volumio[597]: info: Output device has changed, restarting MPD May 18 10:54:50 volumio volumio[597]: info: Output device has changed, restarting Shairport Sync May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:54:50 volumio sudo[32619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 18 10:54:50 volumio sudo[32619]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:54:50 volumio sudo[32619]: pam_unix(sudo:session): session closed for user root May 18 10:54:50 volumio volumio[597]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 18 10:54:50 volumio sudo[32621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 18 10:54:50 volumio volumio[597]: info: MPD Permissions set May 18 10:54:50 volumio sudo[32621]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::volumioGetQueue May 18 10:54:50 volumio volumio[597]: info: CoreStateMachine::getQueue May 18 10:54:50 volumio volumio[597]: info: CorePlayQueue::getQueue May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:50 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 18 10:54:50 volumio systemd[1]: mpd.service: Succeeded. May 18 10:54:50 volumio systemd[1]: Stopped Music Player Daemon. May 18 10:54:50 volumio systemd[1]: Starting Music Player Daemon... May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:50 volumio volumio[597]: info: Starting Shairport Sync May 18 10:54:50 volumio sudo[32636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 18 10:54:50 volumio sudo[32636]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:54:50 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 18 10:54:50 volumio systemd[1]: shairport-sync.service: Succeeded. May 18 10:54:50 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 18 10:54:50 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 18 10:54:50 volumio sudo[32636]: pam_unix(sudo:session): session closed for user root May 18 10:54:50 volumio volumio[597]: info: Shairport-Sync Started May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:50 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 10:54:52 volumio volumio[597]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 18 10:54:52 volumio volumio[597]: info: CoreStateMachine::getcurrentVolume May 18 10:54:52 volumio volumio[597]: info: CoreCommandRouter::volumioRetrievevolume May 18 10:54:52 volumio volumio[597]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 18 10:54:52 volumio volumio[597]: info: VolumeController:: Volume=undefined Mute =false May 18 10:54:52 volumio volumio[597]: info: CoreStateMachine::pushState May 18 10:54:52 volumio volumio[597]: info: CoreCommandRouter::volumioPushState May 18 10:54:52 volumio volumio[597]: info: CoreStateMachine::updateTrackBlock May 18 10:54:52 volumio volumio[597]: info: CorePlayQueue::getTrackBlock May 18 10:54:52 volumio volumio[597]: info: CoreCommandRouter::volumioRetrievevolume May 18 10:54:52 volumio volumio[597]: info: CoreCommandRouter::volumioGetQueue May 18 10:54:52 volumio volumio[597]: info: CoreStateMachine::getQueue May 18 10:54:52 volumio volumio[597]: info: CorePlayQueue::getQueue May 18 10:54:52 volumio volumio[597]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 18 10:54:52 volumio volumio[597]: info: VolumeController:: Volume=undefined Mute =false May 18 10:54:52 volumio volumio[597]: info: CoreStateMachine::pushState May 18 10:54:52 volumio volumio[597]: info: CoreCommandRouter::volumioPushState May 18 10:54:52 volumio volumio[597]: info: CoreCommandRouter::volumioGetQueue May 18 10:54:52 volumio volumio[597]: info: CoreStateMachine::getQueue May 18 10:54:52 volumio volumio[597]: info: CorePlayQueue::getQueue May 18 10:54:52 volumio mpd[32628]: May 18 10:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 18 10:54:52 volumio systemd[1]: Started Music Player Daemon. May 18 10:54:52 volumio sudo[32568]: pam_unix(sudo:session): session closed for user root May 18 10:54:52 volumio sudo[32621]: pam_unix(sudo:session): session closed for user root May 18 10:54:52 volumio sudo[32557]: pam_unix(sudo:session): session closed for user root May 18 10:54:52 volumio volumio[597]: error: MPD error: The expression evaluated to a falsy value: May 18 10:54:52 volumio volumio[597]: assert.ok(self.idling) May 18 10:54:52 volumio volumio[597]: error: The expression evaluated to a falsy value: May 18 10:54:52 volumio volumio[597]: assert.ok(self.idling) May 18 10:54:52 volumio volumio[597]: error: MPD error: The expression evaluated to a falsy value: May 18 10:54:52 volumio volumio[597]: assert.ok(self.idling) May 18 10:54:52 volumio volumio[597]: error: The expression evaluated to a falsy value: May 18 10:54:52 volumio volumio[597]: assert.ok(self.idling) May 18 10:54:52 volumio volumio[597]: error: updateQueue error: null May 18 10:54:53 volumio volumio[597]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 18 10:54:53 volumio volumio[597]: info: CoreStateMachine::getcurrentVolume May 18 10:54:53 volumio volumio[597]: info: CoreCommandRouter::volumioRetrievevolume May 18 10:54:53 volumio volumio[597]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 18 10:54:53 volumio volumio[597]: info: VolumeController:: Volume=undefined Mute =false May 18 10:54:53 volumio volumio[597]: info: CoreStateMachine::pushState May 18 10:54:53 volumio volumio[597]: info: CoreCommandRouter::volumioPushState May 18 10:54:53 volumio volumio[597]: info: CoreStateMachine::updateTrackBlock May 18 10:54:53 volumio volumio[597]: info: CorePlayQueue::getTrackBlock May 18 10:54:53 volumio volumio[597]: info: CoreCommandRouter::volumioRetrievevolume May 18 10:54:53 volumio volumio[597]: info: CoreCommandRouter::volumioGetQueue May 18 10:54:53 volumio volumio[597]: info: CoreStateMachine::getQueue May 18 10:54:53 volumio volumio[597]: info: CorePlayQueue::getQueue May 18 10:54:53 volumio volumio[597]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 18 10:54:54 volumio volumio[597]: info: VolumeController:: Volume=undefined Mute =false May 18 10:54:54 volumio volumio[597]: info: CoreStateMachine::pushState May 18 10:54:54 volumio volumio[597]: info: CoreCommandRouter::volumioPushState May 18 10:54:54 volumio volumio[597]: info: CoreCommandRouter::volumioGetQueue May 18 10:54:54 volumio volumio[597]: info: CoreStateMachine::getQueue May 18 10:54:54 volumio volumio[597]: info: CorePlayQueue::getQueue May 18 10:54:55 volumio volumio[597]: info: Clearing queue after UPNP request May 18 10:54:55 volumio volumio[597]: info: CoreStateMachine::ClearQueue May 18 10:54:55 volumio volumio[597]: info: CoreStateMachine::stop May 18 10:54:55 volumio volumio[597]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 10:54:55 volumio volumio[597]: info: CoreStateMachine::updateTrackBlock May 18 10:54:55 volumio volumio[597]: info: CorePlayQueue::getTrackBlock May 18 10:54:55 volumio volumio[597]: info: CoreStateMachine::stPlaybackTimer May 18 10:54:55 volumio volumio[597]: info: CoreStateMachine::pushState May 18 10:54:55 volumio volumio[597]: info: CorePlayQueue::getTrack 0 May 18 10:54:55 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 10:54:55 volumio volumio[597]: info: CoreCommandRouter::volumioPushState May 18 10:54:55 volumio volumio[597]: info: CoreStateMachine::serviceStop May 18 10:54:55 volumio volumio[597]: info: CorePlayQueue::getTrack 0 May 18 10:54:55 volumio volumio[597]: info: ControllerMpd::stop May 18 10:54:55 volumio volumio[597]: verbose: ControllerMpd::sendMpdCommand stop May 18 10:54:55 volumio volumio[597]: info: CorePlayQueue::clearPlayQueue May 18 10:54:55 volumio volumio[597]: info: CorePlayQueue::saveQueue May 18 10:54:55 volumio volumio[597]: info: CoreCommandRouter::volumioPushState May 18 10:54:55 volumio volumio[597]: info: CoreCommandRouter::volumioPushQueue May 18 10:54:55 volumio volumio[597]: info: May 18 10:54:55 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:55 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:55 volumio volumio[597]: info: sendMpdCommand stop took 28 milliseconds May 18 10:54:55 volumio volumio[597]: error: updateQueue error: null May 18 10:54:55 volumio volumio[597]: info: ------------------------------ 11ms May 18 10:54:55 volumio volumio[597]: info: May 18 10:54:55 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:55 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:55 volumio volumio[597]: info: May 18 10:54:55 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:55 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:55 volumio volumio[597]: error: updateQueue error: null May 18 10:54:55 volumio volumio[597]: info: ------------------------------ 9ms May 18 10:54:55 volumio volumio[597]: info: ------------------------------ 4ms May 18 10:54:55 volumio volumio[597]: info: Starting UPNP Playback May 18 10:54:55 volumio volumio[597]: info: Preparing playback through UPNP May 18 10:54:55 volumio volumio[597]: info: CoreCommandRouter::volumioGetState May 18 10:54:55 volumio volumio[597]: info: CorePlayQueue::getTrack 0 May 18 10:54:55 volumio volumio[597]: info: CoreStateMachine::setConsumeUpdateService mpd May 18 10:54:55 volumio sudo[31932]: pam_unix(sudo:session): session closed for user root May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:56 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:56 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:56 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 8ms May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 5ms May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:56 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:56 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:56 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:56 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 11ms May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 8ms May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 7ms May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:56 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:56 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 6ms May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 4ms May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:56 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:56 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:56 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 6ms May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 4ms May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 3ms May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:56 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:56 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:56 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 5ms May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:56 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 8ms May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 7ms May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:56 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces system playlist update May 18 10:54:56 volumio volumio[597]: info: Ignoring MPD Status Update May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 7ms May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 6ms May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 5ms May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces state update: player May 18 10:54:56 volumio volumio[597]: info: ControllerMpd::getState May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::sendMpdCommand status May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces state update: player May 18 10:54:56 volumio volumio[597]: info: ControllerMpd::getState May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::sendMpdCommand status May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces state update: player May 18 10:54:56 volumio volumio[597]: info: ControllerMpd::getState May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::sendMpdCommand status May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces state update: player May 18 10:54:56 volumio volumio[597]: info: sendMpdCommand status took 24 milliseconds May 18 10:54:56 volumio volumio[597]: info: sendMpdCommand status took 23 milliseconds May 18 10:54:56 volumio volumio[597]: info: ControllerMpd::getState May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::sendMpdCommand status May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::parseState May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::parseState May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces state update: player May 18 10:54:56 volumio volumio[597]: info: ControllerMpd::getState May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::sendMpdCommand status May 18 10:54:56 volumio volumio[597]: info: May 18 10:54:56 volumio volumio[597]: ---------------------------- MPD announces state update: player May 18 10:54:56 volumio volumio[597]: info: ControllerMpd::getState May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::sendMpdCommand status May 18 10:54:56 volumio volumio[597]: info: sendMpdCommand status took 24 milliseconds May 18 10:54:56 volumio volumio[597]: info: sendMpdCommand status took 21 milliseconds May 18 10:54:56 volumio volumio[597]: info: sendMpdCommand playlistinfo took 19 milliseconds May 18 10:54:56 volumio volumio[597]: info: sendMpdCommand playlistinfo took 18 milliseconds May 18 10:54:56 volumio volumio[597]: info: sendMpdCommand status took 10 milliseconds May 18 10:54:56 volumio volumio[597]: info: sendMpdCommand status took 9 milliseconds May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::parseState May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::parseState May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::parseTrackInfo May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::parseState May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::parseState May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 10:54:56 volumio volumio[597]: info: ControllerMpd::pushState May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::servicePushState May 18 10:54:56 volumio volumio[597]: verbose: In UPNP mode May 18 10:54:56 volumio volumio[597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"握蓝 - 不期","artist":"握蓝","album":null,"uri":"http://fsandroid.kugou.com/202505181054/6d274326eaf20ce9eba5a5b645ffc594/v3/86ec658f52400f276e285987d16e8d11/yp/full/ap1005_us997964742_df2bdrhe3rd2663pasip06s6wk_pi2_mx314446178_quviper_tape_s1275666171.mp3","trackType":"mp3"} May 18 10:54:56 volumio volumio[597]: verbose: CURRENT POSITION 0 May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::syncState stateService play May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::syncState currentStatus stop May 18 10:54:56 volumio volumio[597]: info: ControllerMpd::pushState May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::servicePushState May 18 10:54:56 volumio volumio[597]: verbose: In UPNP mode May 18 10:54:56 volumio volumio[597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"握蓝 - 不期","artist":"握蓝","album":null,"uri":"http://fsandroid.kugou.com/202505181054/6d274326eaf20ce9eba5a5b645ffc594/v3/86ec658f52400f276e285987d16e8d11/yp/full/ap1005_us997964742_df2bdrhe3rd2663pasip06s6wk_pi2_mx314446178_quviper_tape_s1275666171.mp3","trackType":"mp3"} May 18 10:54:56 volumio volumio[597]: verbose: CURRENT POSITION 0 May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::syncState stateService play May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::syncState currentStatus play May 18 10:54:56 volumio volumio[597]: info: Received an update from plugin. extracting info from payload May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::pushState May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::volumioPushState May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::pushState May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::volumioPushState May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 82ms May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 87ms May 18 10:54:56 volumio volumio[597]: info: sendMpdCommand playlistinfo took 42 milliseconds May 18 10:54:56 volumio volumio[597]: info: sendMpdCommand playlistinfo took 42 milliseconds May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::parseTrackInfo May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::parseTrackInfo May 18 10:54:56 volumio volumio[597]: info: ControllerMpd::pushState May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::servicePushState May 18 10:54:56 volumio volumio[597]: verbose: In UPNP mode May 18 10:54:56 volumio volumio[597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"握蓝 - 不期","artist":"握蓝","album":null,"uri":"http://fsandroid.kugou.com/202505181054/6d274326eaf20ce9eba5a5b645ffc594/v3/86ec658f52400f276e285987d16e8d11/yp/full/ap1005_us997964742_df2bdrhe3rd2663pasip06s6wk_pi2_mx314446178_quviper_tape_s1275666171.mp3","trackType":"mp3"} May 18 10:54:56 volumio volumio[597]: verbose: CURRENT POSITION 0 May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::syncState stateService play May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::syncState currentStatus play May 18 10:54:56 volumio volumio[597]: info: Received an update from plugin. extracting info from payload May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::pushState May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::volumioPushState May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::pushState May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::volumioPushState May 18 10:54:56 volumio volumio[597]: info: ControllerMpd::pushState May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::servicePushState May 18 10:54:56 volumio volumio[597]: verbose: In UPNP mode May 18 10:54:56 volumio volumio[597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"握蓝 - 不期","artist":"握蓝","album":null,"uri":"http://fsandroid.kugou.com/202505181054/6d274326eaf20ce9eba5a5b645ffc594/v3/86ec658f52400f276e285987d16e8d11/yp/full/ap1005_us997964742_df2bdrhe3rd2663pasip06s6wk_pi2_mx314446178_quviper_tape_s1275666171.mp3","trackType":"mp3"} May 18 10:54:56 volumio volumio[597]: verbose: CURRENT POSITION 0 May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::syncState stateService play May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::syncState currentStatus play May 18 10:54:56 volumio volumio[597]: info: Received an update from plugin. extracting info from payload May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::pushState May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::volumioPushState May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::pushState May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::volumioPushState May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 139ms May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 121ms May 18 10:54:56 volumio volumio[597]: info: sendMpdCommand playlistinfo took 124 milliseconds May 18 10:54:56 volumio volumio[597]: info: sendMpdCommand playlistinfo took 138 milliseconds May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::parseTrackInfo May 18 10:54:56 volumio volumio[597]: verbose: ControllerMpd::parseTrackInfo May 18 10:54:56 volumio volumio[597]: info: ControllerMpd::pushState May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::servicePushState May 18 10:54:56 volumio volumio[597]: verbose: In UPNP mode May 18 10:54:56 volumio volumio[597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"握蓝 - 不期","artist":"握蓝","album":null,"uri":"http://fsandroid.kugou.com/202505181054/6d274326eaf20ce9eba5a5b645ffc594/v3/86ec658f52400f276e285987d16e8d11/yp/full/ap1005_us997964742_df2bdrhe3rd2663pasip06s6wk_pi2_mx314446178_quviper_tape_s1275666171.mp3","trackType":"mp3"} May 18 10:54:56 volumio volumio[597]: verbose: CURRENT POSITION 0 May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::syncState stateService play May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::syncState currentStatus play May 18 10:54:56 volumio volumio[597]: info: Received an update from plugin. extracting info from payload May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::pushState May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::volumioPushState May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::pushState May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::volumioPushState May 18 10:54:56 volumio volumio[597]: info: ControllerMpd::pushState May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::servicePushState May 18 10:54:56 volumio volumio[597]: verbose: In UPNP mode May 18 10:54:56 volumio volumio[597]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"握蓝 - 不期","artist":"握蓝","album":null,"uri":"http://fsandroid.kugou.com/202505181054/6d274326eaf20ce9eba5a5b645ffc594/v3/86ec658f52400f276e285987d16e8d11/yp/full/ap1005_us997964742_df2bdrhe3rd2663pasip06s6wk_pi2_mx314446178_quviper_tape_s1275666171.mp3","trackType":"mp3"} May 18 10:54:56 volumio volumio[597]: verbose: CURRENT POSITION 0 May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::syncState stateService play May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::syncState currentStatus play May 18 10:54:56 volumio volumio[597]: info: Received an update from plugin. extracting info from payload May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::pushState May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::volumioPushState May 18 10:54:56 volumio volumio[597]: info: CoreStateMachine::pushState May 18 10:54:56 volumio volumio[597]: info: CoreCommandRouter::volumioPushState May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 209ms May 18 10:54:56 volumio volumio[597]: info: ------------------------------ 209ms May 18 10:55:02 volumio volumio[597]: info: CoreCommandRouter::volumioGetState May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 10:55:05 volumio volumio[597]: info: Retrieving Cloud Streaming UI May 18 10:55:05 volumio volumio[597]: info: Getting Tidal Cloud Configuration May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 10:55:05 volumio volumio[597]: info: Getting Qobuz Cloud Configuration May 18 10:55:05 volumio volumio[597]: info: Asking plugin for UI Config May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 10:55:05 volumio volumio[597]: info: Getting Spotify Cloud Configuration May 18 10:55:05 volumio volumio[597]: info: Asking plugin for UI Config May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 10:55:05 volumio volumio[597]: info: Saving Spotify Acccount May 18 10:55:05 volumio volumio[597]: info: Got Tidal Cloud Configuration May 18 10:55:05 volumio volumio[597]: info: Got it May 18 10:55:05 volumio volumio[597]: info: Got it May 18 10:55:05 volumio volumio[597]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 10:55:05 volumio volumio[597]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::volumioGetBrowseSources May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::volumioGetBrowseSources May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::volumioGetBrowseSources May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 10:55:05 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 18 10:55:06 volumio volumio[597]: info: CoreCommandRouter::volumioGetState May 18 10:55:06 volumio sudo[32763]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py May 18 10:55:06 volumio sudo[32763]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:55:09 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 18 10:55:10 volumio volumio[597]: info: Disabling MyMusic plugin upnp May 18 10:55:10 volumio sudo[334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 18 10:55:10 volumio sudo[334]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 10:55:10 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... May 18 10:55:10 volumio volumio[597]: error: Upnp client error: Error: This socket has been ended by the other party May 18 10:55:11 volumio volumio[597]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 8 May 18 10:55:11 volumio volumio[597]: info: CoreCommandRouter::volumioGetState May 18 10:55:11 volumio volumio[597]: info: Enabling MyMusic plugin upnp May 18 10:55:11 volumio volumio[597]: info: Enabling plugin upnp May 18 10:55:11 volumio volumio[597]: info: Loading plugin "upnp"... May 18 10:55:11 volumio volumio[597]: info: [1747536911239] Starting Upmpd Daemon May 18 10:55:11 volumio volumio[597]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 18 10:55:11 volumio volumio[597]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 10:55:11 volumio volumio[597]: Error: listen EADDRINUSE: address already in use :::6599 May 18 10:55:11 volumio volumio[597]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) May 18 10:55:11 volumio volumio[597]: at listenInCluster (net.js:1379:12) May 18 10:55:11 volumio volumio[597]: at Server.listen (net.js:1465:7) May 18 10:55:11 volumio volumio[597]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) May 18 10:55:11 volumio volumio[597]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) May 18 10:55:11 volumio volumio[597]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 18 10:55:11 volumio volumio[597]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 18 10:55:11 volumio volumio[597]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { May 18 10:55:11 volumio volumio[597]: code: 'EADDRINUSE', May 18 10:55:11 volumio volumio[597]: errno: -98, May 18 10:55:11 volumio volumio[597]: syscall: 'listen', May 18 10:55:11 volumio volumio[597]: address: '::', May 18 10:55:11 volumio volumio[597]: port: 6599 May 18 10:55:11 volumio volumio[597]: } May 18 10:55:11 volumio volumio[597]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 10:55:11 volumio sudo[357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-18 10:54 May 18 10:55:11 volumio sudo[357]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"