-- Logs begin at Sun 2025-03-16 12:52:35 UTC, end at Sun 2025-03-16 12:55:29 UTC. -- Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::volumioGetVisibleSources Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState Mar 16 12:54:00 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 16 12:54:00 volumio volumio[1046]: info: Received Get System Info Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 12:54:00 volumio volumio[1046]: info: Discovery: Getting this device information Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState Mar 16 12:54:00 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState Mar 16 12:54:00 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:54:00 volumio volumio[1046]: info: Listing playlists Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::volumioGetBrowseSources Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 16 12:54:00 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState Mar 16 12:54:00 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:54:01 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 16 12:54:01 volumio volumio[1046]: info: Received Get System Info Mar 16 12:54:01 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 12:54:01 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 12:54:01 volumio volumio[1046]: info: Discovery: Getting this device information Mar 16 12:54:01 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState Mar 16 12:54:01 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:54:01 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 12:54:01 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Mar 16 12:54:01 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 12:54:03 volumio volumio[1046]: info: Retrieving Cloud Streaming UI Mar 16 12:54:03 volumio volumio[1046]: info: Getting Tidal Cloud Configuration Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 12:54:03 volumio volumio[1046]: info: Getting Qobuz Cloud Configuration Mar 16 12:54:03 volumio volumio[1046]: info: Asking plugin for UI Config Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 12:54:03 volumio volumio[1046]: info: Getting Spotify Cloud Configuration Mar 16 12:54:03 volumio volumio[1046]: info: Asking plugin for UI Config Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 12:54:03 volumio volumio[1046]: info: Saving Spotify Acccount Mar 16 12:54:03 volumio volumio[1046]: info: Got Tidal Cloud Configuration Mar 16 12:54:03 volumio volumio[1046]: info: Got it Mar 16 12:54:03 volumio volumio[1046]: info: Got it Mar 16 12:54:03 volumio volumio[1046]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 12:54:03 volumio volumio[1046]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::volumioGetBrowseSources Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::volumioGetBrowseSources Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::volumioGetBrowseSources Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 16 12:54:03 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 16 12:54:06 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 16 12:54:07 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState Mar 16 12:54:07 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:54:08 volumio volumio[1046]: info: CoreCommandRouter::Close All Modals sent Mar 16 12:54:08 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Mar 16 12:54:09 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 16 12:54:10 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 12:54:10 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 16 12:54:10 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 12:54:10 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 16 12:54:10 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:10 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:10 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:10 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:10 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:10 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:10 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:10 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 16 12:54:10 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 16 12:54:15 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 16 12:54:15 volumio volumio[1046]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 16 12:54:15 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 16 12:54:15 volumio volumio[1046]: info: Received Get System Version Mar 16 12:54:15 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 16 12:54:15 volumio volumio[1046]: info: Received Get System Info Mar 16 12:54:15 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 12:54:15 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 12:54:15 volumio volumio[1046]: info: Discovery: Getting this device information Mar 16 12:54:15 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState Mar 16 12:54:15 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:54:15 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 12:54:15 volumio kernel: usb 3-1.4: new high-speed USB device number 3 using ehci-pci Mar 16 12:54:15 volumio kernel: usb 3-1.4: New USB device found, idVendor=22e1, idProduct=ca01, bcdDevice= 1.01 Mar 16 12:54:15 volumio kernel: usb 3-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Mar 16 12:54:15 volumio kernel: usb 3-1.4: Product: Serenade DSD Mar 16 12:54:15 volumio kernel: usb 3-1.4: Manufacturer: TempoTec Corp. Mar 16 12:54:15 volumio kernel: input: TempoTec Corp. Serenade DSD as /devices/pci0000:00/0000:00:1a.0/usb3/3-1/3-1.4/3-1.4:1.2/0003:22E1:CA01.0005/input/input26 Mar 16 12:54:15 volumio kernel: hid-generic 0003:22E1:CA01.0005: input,hidraw4: USB HID v1.00 Device [TempoTec Corp. Serenade DSD] on usb-0000:00:1a.0-1.4/input2 Mar 16 12:54:15 volumio kernel: usb 3-1.4: 1:3 : unsupported format bits 0x100000000 Mar 16 12:54:15 volumio kernel: usbcore: registered new interface driver snd-usb-audio Mar 16 12:54:15 volumio systemd-udevd[2593]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 16 12:54:15 volumio systemd-udevd[2593]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Mar 16 12:54:15 volumio volumio[1046]: info: Mar 16 12:54:15 volumio volumio[1046]: ---------------------------- USB Audio Device Attached Mar 16 12:54:15 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Mar 16 12:54:17 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 16 12:54:17 volumio volumio[1046]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 16 12:54:17 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 16 12:54:17 volumio volumio[1046]: info: Received Get System Version Mar 16 12:54:17 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 16 12:54:17 volumio volumio[1046]: info: Received Get System Info Mar 16 12:54:17 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 16 12:54:17 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 16 12:54:17 volumio volumio[1046]: info: Discovery: Getting this device information Mar 16 12:54:17 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState Mar 16 12:54:17 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:54:17 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 16 12:54:24 volumio systemd[1]: systemd-timedated.service: Succeeded. Mar 16 12:54:24 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState Mar 16 12:54:24 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:54:26 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 12:54:26 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 16 12:54:26 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 12:54:26 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 16 12:54:26 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:26 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:26 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:26 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:26 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:26 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:26 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:26 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 16 12:54:26 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 16 12:54:29 volumio volumio[1046]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 16 12:54:29 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 16 12:54:29 volumio volumio[1046]: info: Preparing to save Alsa Options, stopping services first Mar 16 12:54:29 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState Mar 16 12:54:29 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:54:29 volumio volumio[1046]: info: CoreCommandRouter::volumioPause Mar 16 12:54:29 volumio volumio[1046]: info: CoreStateMachine::pause Mar 16 12:54:29 volumio volumio[1046]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Serenade DSD"}} Mar 16 12:54:29 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 16 12:54:29 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 16 12:54:29 volumio volumio[1046]: info: Setting mixer PCM for card Serenade DSD Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 16 12:54:30 volumio volumio[1046]: info: Updating Volume Controller Parameters: Device: 5 Name: Serenade DSD Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 16 12:54:30 volumio volumio[1046]: info: Disabling external Volume Control Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 12:54:30 volumio volumio[1046]: info: Preparing to generate the ALSA configuration file Mar 16 12:54:30 volumio volumio[1046]: Unable to parse: Mar 16 12:54:30 volumio volumio[1046]: Simple mixer control 'PCM',0 Mar 16 12:54:30 volumio volumio[1046]: Capabilities: pswitch pswitch-joined Mar 16 12:54:30 volumio volumio[1046]: Playback channels: Mono Mar 16 12:54:30 volumio volumio[1046]: Mono: Playback [on] Mar 16 12:54:30 volumio volumio[1046]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 16 12:54:30 volumio volumio[1046]: Unable to parse: Mar 16 12:54:30 volumio volumio[1046]: Simple mixer control 'PCM',0 Mar 16 12:54:30 volumio volumio[1046]: Capabilities: pswitch pswitch-joined Mar 16 12:54:30 volumio volumio[1046]: Playback channels: Mono Mar 16 12:54:30 volumio volumio[1046]: Mono: Playback [on] Mar 16 12:54:30 volumio volumio[1046]: info: VolumeController:: Volume=undefined Mute =false Mar 16 12:54:30 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 16 12:54:30 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 16 12:54:30 volumio volumio[1046]: info: Asound.conf file written Mar 16 12:54:30 volumio sudo[2687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 16 12:54:30 volumio sudo[2687]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 12:54:30 volumio sudo[2687]: pam_unix(sudo:session): session closed for user root Mar 16 12:54:30 volumio volumio[1046]: No state is present for card PCH Mar 16 12:54:30 volumio volumio[1046]: Found hardware: "HDA-Intel" "Realtek ALC270" "HDA:10ec0270,104385b1,00100100 HDA:80862806,80860101,00100000" "0x1043" "0x85b1" Mar 16 12:54:30 volumio volumio[1046]: Hardware is initialized using a generic method Mar 16 12:54:30 volumio volumio[1046]: No state is present for card PCH Mar 16 12:54:30 volumio volumio[1046]: No state is present for card DSD Mar 16 12:54:30 volumio volumio[1046]: Found hardware: "USB-Audio" "USB Mixer" "USB22e1:ca01" "" "" Mar 16 12:54:30 volumio volumio[1046]: Hardware is initialized using a generic method Mar 16 12:54:30 volumio volumio[1046]: No state is present for card DSD Mar 16 12:54:30 volumio volumio[1046]: info: Output device has changed, restarting MPD Mar 16 12:54:30 volumio sudo[2692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 16 12:54:30 volumio sudo[2692]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 12:54:30 volumio volumio[1046]: info: Output device has changed, restarting Shairport Sync Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 12:54:30 volumio sudo[2692]: pam_unix(sudo:session): session closed for user root Mar 16 12:54:30 volumio sudo[2694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 16 12:54:30 volumio sudo[2694]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 12:54:30 volumio systemd[1]: Stopping Music Player Daemon... Mar 16 12:54:30 volumio volumio[1046]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 16 12:54:30 volumio volumio[1046]: info: Output device has changed, restarting MPD Mar 16 12:54:30 volumio systemd[1]: mpd.service: Succeeded. Mar 16 12:54:30 volumio systemd[1]: Stopped Music Player Daemon. Mar 16 12:54:30 volumio volumio[1046]: info: Output device has changed, restarting Shairport Sync Mar 16 12:54:30 volumio sudo[2701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 16 12:54:30 volumio sudo[2701]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 12:54:30 volumio sudo[2704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 16 12:54:30 volumio sudo[2704]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 12:54:30 volumio sudo[2701]: pam_unix(sudo:session): session closed for user root Mar 16 12:54:30 volumio systemd[1]: Starting Music Player Daemon... Mar 16 12:54:30 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 16 12:54:30 volumio systemd[1]: mpd.service: Succeeded. Mar 16 12:54:30 volumio systemd[1]: Stopped Music Player Daemon. Mar 16 12:54:30 volumio volumio[1046]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 16 12:54:30 volumio systemd[1]: Starting Music Player Daemon... Mar 16 12:54:30 volumio volumio[1046]: info: MPD Permissions set Mar 16 12:54:30 volumio volumio[1046]: info: MPD Permissions set Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 16 12:54:30 volumio sudo[2710]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 16 12:54:30 volumio sudo[2710]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 12:54:30 volumio sudo[2710]: pam_unix(sudo:session): session closed for user root Mar 16 12:54:30 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 12:54:30 volumio volumio[1046]: info: Starting Shairport Sync Mar 16 12:54:30 volumio volumio[1046]: info: Starting Shairport Sync Mar 16 12:54:30 volumio sudo[2726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 12:54:30 volumio sudo[2726]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 12:54:30 volumio sudo[2728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 12:54:30 volumio sudo[2728]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 12:54:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 16 12:54:30 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 16 12:54:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 16 12:54:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 16 12:54:30 volumio sudo[2728]: pam_unix(sudo:session): session closed for user root Mar 16 12:54:30 volumio sudo[2726]: pam_unix(sudo:session): session closed for user root Mar 16 12:54:30 volumio volumio[1046]: info: Shairport-Sync Started Mar 16 12:54:30 volumio volumio[1046]: info: Shairport-Sync Started Mar 16 12:54:30 volumio mpd[2723]: Mar 16 12:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 16 12:54:30 volumio systemd[1]: Started Music Player Daemon. Mar 16 12:54:30 volumio sudo[2704]: pam_unix(sudo:session): session closed for user root Mar 16 12:54:30 volumio sudo[2694]: pam_unix(sudo:session): session closed for user root Mar 16 12:54:30 volumio volumio[1046]: error: MPD error: The expression evaluated to a falsy value: Mar 16 12:54:30 volumio volumio[1046]: assert.ok(self.idling) Mar 16 12:54:30 volumio volumio[1046]: error: The expression evaluated to a falsy value: Mar 16 12:54:30 volumio volumio[1046]: assert.ok(self.idling) Mar 16 12:54:30 volumio volumio[1046]: error: updateQueue error: null Mar 16 12:54:34 volumio volumio[1046]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 16 12:54:34 volumio volumio[1046]: info: CoreStateMachine::getcurrentVolume Mar 16 12:54:34 volumio volumio[1046]: info: CoreCommandRouter::volumioRetrievevolume Mar 16 12:54:34 volumio volumio[1046]: Unable to parse: Mar 16 12:54:34 volumio volumio[1046]: Simple mixer control 'PCM',0 Mar 16 12:54:34 volumio volumio[1046]: Capabilities: pswitch pswitch-joined Mar 16 12:54:34 volumio volumio[1046]: Playback channels: Mono Mar 16 12:54:34 volumio volumio[1046]: Mono: Playback [on] Mar 16 12:54:34 volumio volumio[1046]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Mar 16 12:54:34 volumio volumio[1046]: Unable to parse: Mar 16 12:54:34 volumio volumio[1046]: Simple mixer control 'PCM',0 Mar 16 12:54:34 volumio volumio[1046]: Capabilities: pswitch pswitch-joined Mar 16 12:54:34 volumio volumio[1046]: Playback channels: Mono Mar 16 12:54:34 volumio volumio[1046]: Mono: Playback [on] Mar 16 12:54:34 volumio volumio[1046]: info: VolumeController:: Volume=undefined Mute =false Mar 16 12:54:34 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 16 12:54:34 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:54:34 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 16 12:54:34 volumio volumio[1046]: info: CoreStateMachine::updateTrackBlock Mar 16 12:54:34 volumio volumio[1046]: info: CorePlayQueue::getTrackBlock Mar 16 12:54:34 volumio volumio[1046]: info: CoreCommandRouter::volumioRetrievevolume Mar 16 12:54:34 volumio volumio[1046]: Unable to parse: Mar 16 12:54:34 volumio volumio[1046]: Simple mixer control 'PCM',0 Mar 16 12:54:34 volumio volumio[1046]: Capabilities: pswitch pswitch-joined Mar 16 12:54:34 volumio volumio[1046]: Playback channels: Mono Mar 16 12:54:34 volumio volumio[1046]: Mono: Playback [on] Mar 16 12:54:34 volumio volumio[1046]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Mar 16 12:54:34 volumio volumio[1046]: Unable to parse: Mar 16 12:54:34 volumio volumio[1046]: Simple mixer control 'PCM',0 Mar 16 12:54:34 volumio volumio[1046]: Capabilities: pswitch pswitch-joined Mar 16 12:54:34 volumio volumio[1046]: Playback channels: Mono Mar 16 12:54:34 volumio volumio[1046]: Mono: Playback [on] Mar 16 12:54:34 volumio volumio[1046]: info: VolumeController:: Volume=undefined Mute =false Mar 16 12:54:34 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 16 12:54:34 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:54:34 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 16 12:54:44 volumio volumio[1046]: info: CoreCommandRouter::volumioGetState Mar 16 12:54:44 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 12:54:47 volumio volumio[1046]: info: Retrieving Cloud Streaming UI Mar 16 12:54:47 volumio volumio[1046]: info: Getting Tidal Cloud Configuration Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 12:54:47 volumio volumio[1046]: info: Getting Qobuz Cloud Configuration Mar 16 12:54:47 volumio volumio[1046]: info: Asking plugin for UI Config Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 12:54:47 volumio volumio[1046]: info: Getting Spotify Cloud Configuration Mar 16 12:54:47 volumio volumio[1046]: info: Asking plugin for UI Config Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 12:54:47 volumio volumio[1046]: info: Saving Spotify Acccount Mar 16 12:54:47 volumio volumio[1046]: info: Got Tidal Cloud Configuration Mar 16 12:54:47 volumio volumio[1046]: info: Got it Mar 16 12:54:47 volumio volumio[1046]: info: Got it Mar 16 12:54:47 volumio volumio[1046]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 16 12:54:47 volumio volumio[1046]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::volumioGetBrowseSources Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::volumioGetBrowseSources Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::volumioGetBrowseSources Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 16 12:54:47 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 16 12:54:51 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 16 12:54:55 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Mar 16 12:54:55 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Mar 16 12:54:55 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 16 12:54:59 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 16 12:55:03 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 16 12:55:04 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Mar 16 12:55:04 volumio volumio[1046]: info: Adding a new share Mar 16 12:55:04 volumio volumio[1046]: info: No correspondence found in configuration for share music on IP 192.168.23.243 Mar 16 12:55:04 volumio sudo[2845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.23.243/music /mnt/NAS/music Mar 16 12:55:04 volumio sudo[2845]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 12:55:04 volumio kernel: FS-Cache: Loaded Mar 16 12:55:04 volumio kernel: Key type dns_resolver registered Mar 16 12:55:04 volumio kernel: Key type cifs.spnego registered Mar 16 12:55:04 volumio kernel: Key type cifs.idmap registered Mar 16 12:55:04 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Mar 16 12:55:04 volumio kernel: CIFS: Attempting to mount //192.168.23.243/music Mar 16 12:55:05 volumio sudo[2845]: pam_unix(sudo:session): session closed for user root Mar 16 12:55:05 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:55:05 volumio volumio[1046]: info: Mar 16 12:55:05 volumio volumio[1046]: ---------------------------- MPD announces state update: update Mar 16 12:55:05 volumio volumio[1046]: info: ControllerMpd::getState Mar 16 12:55:05 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:55:05 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:55:05 volumio volumio[1046]: info: Mar 16 12:55:05 volumio volumio[1046]: ---------------------------- MPD announces state update: update Mar 16 12:55:05 volumio volumio[1046]: info: ControllerMpd::getState Mar 16 12:55:05 volumio volumio[1046]: verbose: ControllerMpd::sendMpdCommand status Mar 16 12:55:05 volumio volumio[1046]: info: sendMpdCommand status took 5 milliseconds Mar 16 12:55:05 volumio volumio[1046]: info: sendMpdCommand status took 3 milliseconds Mar 16 12:55:05 volumio volumio[1046]: info: sendMpdCommand status took 3 milliseconds Mar 16 12:55:05 volumio volumio[1046]: info: sendMpdCommand status took 3 milliseconds Mar 16 12:55:05 volumio volumio[1046]: verbose: ControllerMpd::parseState Mar 16 12:55:06 volumio volumio[1046]: info: Command Router : Notfying DB Updatetrue Mar 16 12:55:06 volumio volumio[1046]: verbose: ControllerMpd::parseState Mar 16 12:55:06 volumio volumio[1046]: info: Command Router : Notfying DB Updatetrue Mar 16 12:55:06 volumio volumio[1046]: info: CoreCommandRouter::Close All Modals sent Mar 16 12:55:06 volumio volumio[1046]: verbose: ControllerMpd::parseState Mar 16 12:55:06 volumio volumio[1046]: info: ControllerMpd::pushState Mar 16 12:55:06 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 16 12:55:06 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 16 12:55:06 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:55:06 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 16 12:55:06 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:55:06 volumio volumio[1046]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 12:55:06 volumio volumio[1046]: verbose: CURRENT POSITION 0 Mar 16 12:55:06 volumio volumio[1046]: info: CoreStateMachine::syncState stateService stop Mar 16 12:55:06 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:55:06 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 16 12:55:06 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:55:06 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 16 12:55:06 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 16 12:55:06 volumio volumio[1046]: info: No code Mar 16 12:55:06 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 16 12:55:06 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:55:06 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 16 12:55:06 volumio volumio[1046]: info: ControllerMpd::pushState Mar 16 12:55:06 volumio volumio[1046]: info: CoreCommandRouter::servicePushState Mar 16 12:55:06 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 16 12:55:06 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:55:06 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 16 12:55:06 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:55:06 volumio volumio[1046]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 16 12:55:06 volumio volumio[1046]: verbose: CURRENT POSITION 0 Mar 16 12:55:06 volumio volumio[1046]: info: CoreStateMachine::syncState stateService stop Mar 16 12:55:06 volumio volumio[1046]: info: CoreStateMachine::syncState currentStatus stop Mar 16 12:55:06 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 16 12:55:06 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:55:06 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 16 12:55:06 volumio volumio[1046]: info: No code Mar 16 12:55:06 volumio volumio[1046]: info: CoreStateMachine::pushState Mar 16 12:55:06 volumio volumio[1046]: info: CorePlayQueue::getTrack 0 Mar 16 12:55:06 volumio volumio[1046]: info: CoreCommandRouter::volumioPushState Mar 16 12:55:06 volumio volumio[1046]: info: ------------------------------ 1406ms Mar 16 12:55:06 volumio volumio[1046]: info: ------------------------------ 1406ms Mar 16 12:55:06 volumio volumio[1046]: info: [1742129706438] Database update started Mar 16 12:55:06 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 16 12:55:07 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 16 12:55:11 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 16 12:55:15 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 16 12:55:19 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 16 12:55:23 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 16 12:55:27 volumio volumio[1046]: info: Disabling MyMusic plugin airplay_emulation Mar 16 12:55:27 volumio volumio[1046]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Mar 16 12:55:27 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 16 12:55:27 volumio volumio[1046]: info: Disabling plugin airplay_emulation Mar 16 12:55:27 volumio volumio[1046]: info: Done. Mar 16 12:55:27 volumio sudo[2983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Mar 16 12:55:27 volumio sudo[2983]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 12:55:27 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 16 12:55:27 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 16 12:55:27 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 16 12:55:27 volumio sudo[2983]: pam_unix(sudo:session): session closed for user root Mar 16 12:55:27 volumio volumio[1046]: info: Shairport-Sync Stopped Mar 16 12:55:27 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 16 12:55:27 volumio volumio[1046]: info: Enabling MyMusic plugin airplay_emulation Mar 16 12:55:27 volumio volumio[1046]: info: Enabling plugin airplay_emulation Mar 16 12:55:27 volumio volumio[1046]: info: Loading plugin "airplay_emulation"... Mar 16 12:55:27 volumio volumio[1046]: info: Starting Shairport Sync Mar 16 12:55:27 volumio volumio[1046]: info: PLUGIN START: airplay_emulation Mar 16 12:55:27 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 12:55:27 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 12:55:27 volumio volumio[1046]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 16 12:55:27 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 16 12:55:27 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 16 12:55:27 volumio volumio[1046]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 16 12:55:27 volumio volumio[1046]: info: Done. Mar 16 12:55:27 volumio volumio[1046]: info: Starting Shairport Sync Mar 16 12:55:27 volumio volumio[1046]: info: Starting Shairport Sync Mar 16 12:55:27 volumio sudo[2992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 12:55:27 volumio sudo[2992]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 12:55:27 volumio sudo[2994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 16 12:55:27 volumio sudo[2994]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 12:55:27 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 16 12:55:27 volumio sudo[2992]: pam_unix(sudo:session): session closed for user root Mar 16 12:55:27 volumio sudo[2994]: pam_unix(sudo:session): session closed for user root Mar 16 12:55:27 volumio volumio[1046]: info: Shairport-Sync Started Mar 16 12:55:27 volumio volumio[1046]: info: Shairport-Sync Started Mar 16 12:55:28 volumio volumio[1046]: info: Disabling MyMusic plugin upnp Mar 16 12:55:28 volumio sudo[3001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Mar 16 12:55:28 volumio sudo[3001]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 16 12:55:28 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Mar 16 12:55:28 volumio volumio[1046]: error: Upnp client error: Error: This socket has been ended by the other party Mar 16 12:55:29 volumio volumio[1046]: info: Enabling MyMusic plugin upnp Mar 16 12:55:29 volumio volumio[1046]: info: Enabling plugin upnp Mar 16 12:55:29 volumio volumio[1046]: info: Loading plugin "upnp"... Mar 16 12:55:29 volumio volumio[1046]: info: [1742129729508] Starting Upmpd Daemon Mar 16 12:55:29 volumio volumio[1046]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 16 12:55:29 volumio volumio[1046]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 12:55:29 volumio volumio[1046]: Error: listen EADDRINUSE: address already in use :::6599 Mar 16 12:55:29 volumio volumio[1046]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Mar 16 12:55:29 volumio volumio[1046]: at listenInCluster (net.js:1379:12) Mar 16 12:55:29 volumio volumio[1046]: at Server.listen (net.js:1465:7) Mar 16 12:55:29 volumio volumio[1046]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Mar 16 12:55:29 volumio volumio[1046]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Mar 16 12:55:29 volumio volumio[1046]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Mar 16 12:55:29 volumio volumio[1046]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 16 12:55:29 volumio volumio[1046]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Mar 16 12:55:29 volumio volumio[1046]: code: 'EADDRINUSE', Mar 16 12:55:29 volumio volumio[1046]: errno: -98, Mar 16 12:55:29 volumio volumio[1046]: syscall: 'listen', Mar 16 12:55:29 volumio volumio[1046]: address: '::', Mar 16 12:55:29 volumio volumio[1046]: port: 6599 Mar 16 12:55:29 volumio volumio[1046]: } Mar 16 12:55:29 volumio volumio[1046]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 16 12:55:29 volumio sudo[3012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-16 12:54 Mar 16 12:55:29 volumio sudo[3012]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"