Oct 13 08:12:02 volumiopc volumio[1111]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 08:12:02 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 13 08:12:02 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 08:12:02 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 08:12:02 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:12:02 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:12:02 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:12:02 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:12:02 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:12:02 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:12:02 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:12:02 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 13 08:12:02 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 13 08:13:19 volumiopc kernel: usb 1-6: new high-speed USB device number 6 using xhci_hcd Oct 13 08:13:20 volumiopc kernel: usb 1-6: New USB device found, idVendor=20b1, idProduct=302d, bcdDevice= 6.70 Oct 13 08:13:20 volumiopc kernel: usb 1-6: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Oct 13 08:13:20 volumiopc kernel: usb 1-6: Product: UNISON USB DAC 2 Oct 13 08:13:20 volumiopc kernel: usb 1-6: Manufacturer: UNISON RESEARCH Oct 13 08:13:20 volumiopc kernel: usbcore: registered new interface driver snd-usb-audio Oct 13 08:13:20 volumiopc volumio[1111]: info: Oct 13 08:13:20 volumiopc volumio[1111]: ---------------------------- USB Audio Device Attached Oct 13 08:13:20 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 13 08:13:41 volumiopc volumio[1111]: info: CoreCommandRouter::volumioGetState Oct 13 08:13:41 volumiopc volumio[1111]: info: CorePlayQueue::getTrack 0 Oct 13 08:13:44 volumiopc volumio[1111]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 08:13:44 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 13 08:13:44 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 08:13:44 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 08:13:44 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:13:44 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:13:44 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:13:44 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:13:44 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:13:44 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:13:44 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:13:44 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 13 08:13:44 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 13 08:13:48 volumiopc volumio[1111]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 13 08:13:48 volumiopc volumio[1111]: info: Preparing to save Alsa Options, stopping services first Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::volumioGetState Oct 13 08:13:48 volumiopc volumio[1111]: info: CorePlayQueue::getTrack 0 Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::volumioPause Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreStateMachine::pause Oct 13 08:13:48 volumiopc volumio[1111]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"UNISON USB DAC 2"}} Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 08:13:48 volumiopc volumio[1111]: info: Setting mixer UNISON USB DAC 2 Output for card UNISON USB DAC 2 Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 13 08:13:48 volumiopc volumio[1111]: info: Updating Volume Controller Parameters: Device: 5 Name: UNISON USB DAC 2 Mixer: UNISON USB DAC 2 Output Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 13 08:13:48 volumiopc volumio[1111]: info: Disabling external Volume Control Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 08:13:48 volumiopc volumio[1111]: info: Preparing to generate the ALSA configuration file Oct 13 08:13:48 volumiopc volumio[1111]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 13 08:13:48 volumiopc volumio[1111]: info: Reading ALSA contributions from plugins. Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 13 08:13:48 volumiopc volumio[1111]: info: VolumeController:: Volume=100 Mute =false Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreStateMachine::pushState Oct 13 08:13:48 volumiopc volumio[1111]: info: CorePlayQueue::getTrack 0 Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::volumioPushState Oct 13 08:13:48 volumiopc volumio[1111]: info: Asound.conf file written Oct 13 08:13:48 volumiopc sudo[2961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 13 08:13:48 volumiopc sudo[2961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 08:13:48 volumiopc sudo[2961]: pam_unix(sudo:session): session closed for user root Oct 13 08:13:48 volumiopc volumio[1111]: info: Output device has changed, restarting MPD Oct 13 08:13:48 volumiopc volumio[1111]: info: Output device has changed, restarting Shairport Sync Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 08:13:48 volumiopc sudo[2967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 08:13:48 volumiopc sudo[2967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 08:13:48 volumiopc sudo[2967]: pam_unix(sudo:session): session closed for user root Oct 13 08:13:48 volumiopc sudo[2969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 08:13:48 volumiopc sudo[2969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 08:13:48 volumiopc volumio[1111]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 08:13:48 volumiopc volumio[1111]: info: Output device has changed, restarting MPD Oct 13 08:13:48 volumiopc systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 13 08:13:48 volumiopc volumio[1111]: info: Output device has changed, restarting Shairport Sync Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 08:13:48 volumiopc sudo[2977]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 08:13:48 volumiopc sudo[2977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 08:13:48 volumiopc sudo[2980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 08:13:48 volumiopc sudo[2980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 08:13:48 volumiopc volumio[1111]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 08:13:48 volumiopc sudo[2977]: pam_unix(sudo:session): session closed for user root Oct 13 08:13:48 volumiopc volumio[1111]: info: MPD Permissions set Oct 13 08:13:48 volumiopc volumio[1111]: info: MPD Permissions set Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 13 08:13:48 volumiopc volumio[1111]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 08:13:48 volumiopc volumio[1111]: info: Starting Shairport Sync Oct 13 08:13:48 volumiopc volumio[1111]: info: Starting Shairport Sync Oct 13 08:13:48 volumiopc sudo[3003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 08:13:48 volumiopc sudo[3003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 08:13:48 volumiopc systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 13 08:13:48 volumiopc sudo[3005]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 08:13:48 volumiopc sudo[3005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 08:13:48 volumiopc systemd[1]: shairport-sync.service: Deactivated successfully. Oct 13 08:13:48 volumiopc systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 08:13:48 volumiopc systemd[1]: shairport-sync.service: Consumed 1.635s CPU time. Oct 13 08:13:48 volumiopc systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 08:13:48 volumiopc sudo[3003]: pam_unix(sudo:session): session closed for user root Oct 13 08:13:48 volumiopc volumio[1111]: info: Shairport-Sync Started Oct 13 08:13:48 volumiopc systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 13 08:13:48 volumiopc systemd[1]: shairport-sync.service: Deactivated successfully. Oct 13 08:13:48 volumiopc systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 08:13:48 volumiopc systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 13 08:13:48 volumiopc sudo[3005]: pam_unix(sudo:session): session closed for user root Oct 13 08:13:48 volumiopc volumio[1111]: info: Shairport-Sync Started Oct 13 08:13:48 volumiopc systemd[1]: mpd.service: Deactivated successfully. Oct 13 08:13:48 volumiopc systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 08:13:48 volumiopc systemd[1]: mpd.service: Consumed 1.779s CPU time. Oct 13 08:13:48 volumiopc systemd[1]: mpd.socket: Deactivated successfully. Oct 13 08:13:48 volumiopc systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 08:13:48 volumiopc systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 08:13:48 volumiopc systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 08:13:48 volumiopc systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 08:13:48 volumiopc sudo[3011]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 08:13:48 volumiopc sudo[3011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 08:13:48 volumiopc sudo[3011]: pam_unix(sudo:session): session closed for user root Oct 13 08:13:49 volumiopc mpd[3027]: 2026-10-13T08:13:49 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 13 08:13:49 volumiopc systemd[1]: Started mpd.service - Music Player Daemon. Oct 13 08:13:49 volumiopc sudo[2969]: pam_unix(sudo:session): session closed for user root Oct 13 08:13:49 volumiopc sudo[2980]: pam_unix(sudo:session): session closed for user root Oct 13 08:13:49 volumiopc volumio[1111]: error: MPD error: The expression evaluated to a falsy value: Oct 13 08:13:49 volumiopc volumio[1111]: assert.ok(self.idling) Oct 13 08:13:49 volumiopc volumio[1111]: error: The expression evaluated to a falsy value: Oct 13 08:13:49 volumiopc volumio[1111]: assert.ok(self.idling) Oct 13 08:13:49 volumiopc volumio[1111]: error: updateQueue error: null Oct 13 08:13:50 volumiopc volumio[1111]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 08:13:50 volumiopc volumio[1111]: TypeError: Cannot read properties of null (reading 'logger') Oct 13 08:13:50 volumiopc volumio[1111]: at SqueezeliteMCContext.getLogger (/data/plugins/music_service/squeezelite_mc/dist/lib/SqueezeliteMCContext.js:70:86) Oct 13 08:13:50 volumiopc volumio[1111]: at ControllerSqueezeliteMC._ControllerSqueezeliteMC_stdLogError (/data/plugins/music_service/squeezelite_mc/dist/index.js:729:36) Oct 13 08:13:50 volumiopc volumio[1111]: at /data/plugins/music_service/squeezelite_mc/dist/index.js:1262:121 Oct 13 08:13:50 volumiopc volumio[1111]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 08:13:50 volumiopc sudo[3046]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 08:12' Oct 13 08:13:50 volumiopc sudo[3046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"