-- Logs begin at Wed 2024-07-17 16:47:42 +07, end at Wed 2024-07-17 17:09:20 +07. -- Jul 17 17:08:00 volumio volumio[1074]: info: Get:7 http://deb.debian.org/debian buster/contrib amd64 Packages [50.1 kB] Jul 17 17:08:00 volumio volumio[1074]: info: Get:8 http://deb.debian.org/debian buster/contrib i386 Packages [46.0 kB] Jul 17 17:08:00 volumio volumio[1074]: info: Get:9 http://deb.debian.org/debian buster/contrib Translation-en [44.2 kB] Jul 17 17:08:00 volumio volumio[1074]: info: Get:10 http://deb.debian.org/debian buster/non-free i386 Packages [76.3 kB] Jul 17 17:08:00 volumio volumio[1074]: info: Get:11 http://deb.debian.org/debian buster/non-free amd64 Packages [87.8 kB] Jul 17 17:08:01 volumio volumio[1074]: info: Get:12 http://deb.debian.org/debian buster/non-free Translation-en [88.9 kB] Jul 17 17:08:01 volumio volumio[1074]: verbose: New Socket.io Connection to 192.168.100.234 from 192.168.100.176 UA: unknown Engine version: 3 Transport: websocket Total Clients: 7 Jul 17 17:08:01 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:01 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:01 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 17 17:08:01 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 17 17:08:02 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 17 17:08:02 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 17 17:08:02 volumio volumio[1074]: info: Discovery: Getting this device information Jul 17 17:08:02 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:02 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:02 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 17 17:08:02 volumio volumio[1074]: verbose: New Socket.io Connection to 192.168.100.234 from 192.168.100.176 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_7_8 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Jul 17 17:08:02 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 17 17:08:02 volumio volumio[1074]: info: CoreCommandRouter::volumioGetVisibleSources Jul 17 17:08:02 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 17:08:02 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:02 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:02 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 17 17:08:02 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 17 17:08:02 volumio volumio[1074]: info: Received Get System Info Jul 17 17:08:02 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 17 17:08:02 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 17 17:08:02 volumio volumio[1074]: info: Discovery: Getting this device information Jul 17 17:08:02 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:02 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:02 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 17 17:08:02 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:02 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:02 volumio volumio[1074]: info: Listing playlists Jul 17 17:08:02 volumio volumio[1074]: info: CoreCommandRouter::volumioGetQueue Jul 17 17:08:02 volumio volumio[1074]: info: CoreStateMachine::getQueue Jul 17 17:08:02 volumio volumio[1074]: info: CorePlayQueue::getQueue Jul 17 17:08:02 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 17 17:08:05 volumio volumio[1074]: info: Fetched 22.3 MB in 56s (394 kB/s) Jul 17 17:08:07 volumio volumio[1074]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jul 17 17:08:07 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jul 17 17:08:07 volumio volumio[1074]: info: Preparing to save Alsa Options, stopping services first Jul 17 17:08:07 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:07 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:07 volumio volumio[1074]: info: CoreCommandRouter::volumioPause Jul 17 17:08:07 volumio volumio[1074]: info: CoreStateMachine::pause Jul 17 17:08:07 volumio volumio[1074]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB2.0 High-Speed True HD Audio"}} Jul 17 17:08:07 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 17 17:08:07 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 17 17:08:07 volumio volumio[1074]: info: Setting default mixerSoftMaster for Softvolume device Jul 17 17:08:07 volumio volumio[1074]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 17 17:08:07 volumio volumio[1074]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 17 17:08:08 volumio volumio[1074]: info: Disabling external Volume Control Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 17:08:08 volumio volumio[1074]: info: Enable softmixer device for audio device undefined Jul 17 17:08:08 volumio volumio[1074]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jul 17 17:08:08 volumio volumio[1074]: info: Software Volume ALSA configuration written Jul 17 17:08:08 volumio volumio[1074]: info: Preparing to generate the ALSA configuration file Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 17 17:08:08 volumio volumio[1074]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 17 17:08:08 volumio volumio[1074]: info: Reading ALSA contributions from plugins. Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:08 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:08 volumio volumio[1074]: info: CoreStateMachine::pushState Jul 17 17:08:08 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Jul 17 17:08:08 volumio volumio[1074]: info: Asound.conf file unchanged, so no further update is needed Jul 17 17:08:08 volumio volumio[1074]: info: Output device has changed, restarting MPD Jul 17 17:08:08 volumio sudo[3752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 17:08:08 volumio sudo[3752]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:08:08 volumio sudo[3752]: pam_unix(sudo:session): session closed for user root Jul 17 17:08:08 volumio volumio[1074]: info: Output device has changed, restarting Shairport Sync Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:08:08 volumio sudo[3757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 17:08:08 volumio sudo[3757]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:08:08 volumio systemd[1]: Stopping Music Player Daemon... Jul 17 17:08:08 volumio systemd[1]: mpd.service: Succeeded. Jul 17 17:08:08 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 17:08:08 volumio systemd[1]: Starting Music Player Daemon... Jul 17 17:08:08 volumio volumio[1074]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 17:08:08 volumio volumio[1074]: info: Output device has changed, restarting Shairport Sync Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:08:08 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:08:08 volumio sudo[3762]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 17 17:08:08 volumio sudo[3762]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:08:08 volumio sudo[3762]: pam_unix(sudo:session): session closed for user root Jul 17 17:08:08 volumio volumio[1074]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 17:08:08 volumio volumio[1074]: info: VolumeController::SetAlsaVolume0 Jul 17 17:08:09 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:09 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:09 volumio volumio[1074]: info: CoreStateMachine::pushState Jul 17 17:08:09 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:09 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 17:08:09 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Jul 17 17:08:09 volumio volumio[1074]: info: MPD Permissions set Jul 17 17:08:09 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:09 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:09 volumio volumio[1074]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jul 17 17:08:09 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:08:09 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:08:09 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:08:09 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:08:09 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:08:09 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:08:09 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:08:09 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:08:09 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 17 17:08:09 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:08:09 volumio volumio[1074]: info: VolumeController::SetAlsaVolume0 Jul 17 17:08:09 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:09 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:09 volumio volumio[1074]: info: CoreStateMachine::pushState Jul 17 17:08:09 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:09 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Jul 17 17:08:09 volumio volumio[1074]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jul 17 17:08:09 volumio volumio[1074]: info: Starting Shairport Sync Jul 17 17:08:09 volumio volumio[1074]: info: Starting Shairport Sync Jul 17 17:08:09 volumio volumio[1074]: info: VolumeController::SetAlsaVolume0 Jul 17 17:08:09 volumio sudo[3777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 17 17:08:09 volumio sudo[3777]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:08:09 volumio sudo[3779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 17 17:08:09 volumio sudo[3779]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:08:09 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 17 17:08:09 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 17 17:08:09 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 17 17:08:09 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 17 17:08:09 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:09 volumio sudo[3779]: pam_unix(sudo:session): session closed for user root Jul 17 17:08:09 volumio sudo[3777]: pam_unix(sudo:session): session closed for user root Jul 17 17:08:09 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:09 volumio volumio[1074]: info: CoreStateMachine::pushState Jul 17 17:08:09 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:09 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Jul 17 17:08:09 volumio volumio[1074]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jul 17 17:08:09 volumio volumio[1074]: info: Shairport-Sync Started Jul 17 17:08:09 volumio volumio[1074]: (node:1074) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jul 17 17:08:09 volumio volumio[1074]: (node:1074) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jul 17 17:08:09 volumio volumio[1074]: (node:1074) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jul 17 17:08:09 volumio volumio[1074]: (node:1074) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jul 17 17:08:09 volumio volumio[1074]: (node:1074) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jul 17 17:08:10 volumio mpd[3767]: Jul 17 17:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 17:08:10 volumio systemd[1]: Started Music Player Daemon. Jul 17 17:08:10 volumio sudo[3757]: pam_unix(sudo:session): session closed for user root Jul 17 17:08:10 volumio volumio[1074]: error: updateQueue error: null Jul 17 17:08:11 volumio volumio[1074]: info: Reading package lists... Jul 17 17:08:12 volumio volumio[1074]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 17 17:08:12 volumio volumio[1074]: info: CoreStateMachine::getcurrentVolume Jul 17 17:08:12 volumio volumio[1074]: info: CoreCommandRouter::volumioRetrievevolume Jul 17 17:08:12 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:12 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:12 volumio volumio[1074]: info: CoreStateMachine::pushState Jul 17 17:08:12 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:12 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 17:08:12 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Jul 17 17:08:12 volumio volumio[1074]: info: CoreStateMachine::updateTrackBlock Jul 17 17:08:12 volumio volumio[1074]: info: CorePlayQueue::getTrackBlock Jul 17 17:08:12 volumio volumio[1074]: info: CoreCommandRouter::volumioRetrievevolume Jul 17 17:08:12 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:12 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:12 volumio volumio[1074]: info: CoreStateMachine::pushState Jul 17 17:08:12 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:12 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Jul 17 17:08:12 volumio volumio[1074]: info: Building dependency tree... Jul 17 17:08:12 volumio volumio[1074]: info: Reading state information... Jul 17 17:08:12 volumio volumio[1074]: info: All packages are up to date. Jul 17 17:08:12 volumio sudo[3467]: pam_unix(sudo:session): session closed for user root Jul 17 17:08:12 volumio sudo[3801]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Jul 17 17:08:12 volumio sudo[3801]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:08:13 volumio volumio[1074]: info: Reading package lists... Jul 17 17:08:14 volumio volumio[1074]: info: Building dependency tree... Jul 17 17:08:14 volumio volumio[1074]: info: Reading state information... Jul 17 17:08:15 volumio volumio[1074]: info: The following additional packages will be installed: Jul 17 17:08:15 volumio volumio[1074]: info: binutils binutils-common binutils-x86-64-linux-gnu build-essential bzip2 Jul 17 17:08:15 volumio volumio[1074]: info: dh-python dpkg-dev fakeroot g++ g++-8 gcc gcc-8 libalgorithm-diff-perl Jul 17 17:08:15 volumio volumio[1074]: info: libalgorithm-diff-xs-perl libalgorithm-merge-perl libasan5 libbinutils Jul 17 17:08:15 volumio volumio[1074]: info: libcc1-0 libexpat1-dev libfakeroot libgcc-8-dev libitm1 liblsan0 libmpx2 Jul 17 17:08:15 volumio volumio[1074]: info: libpython3-dev libpython3.7 libpython3.7-dev libquadmath0 libstdc++-8-dev Jul 17 17:08:15 volumio volumio[1074]: info: libtsan0 libubsan1 make patch python-pip-whl python3-asn1crypto Jul 17 17:08:15 volumio volumio[1074]: info: python3-async-timeout python3-attr python3-cffi-backend python3-crypto Jul 17 17:08:15 volumio volumio[1074]: info: python3-cryptography python3-dbus python3-dev python3-entrypoints python3-gi Jul 17 17:08:15 volumio volumio[1074]: info: python3-keyring python3-keyrings.alt python3-multidict python3-secretstorage Jul 17 17:08:15 volumio volumio[1074]: info: python3-setuptools python3-wheel python3-xdg python3-yarl python3.7-dev Jul 17 17:08:15 volumio volumio[1074]: info: Suggested packages: Jul 17 17:08:15 volumio volumio[1074]: info: binutils-doc bzip2-doc debian-keyring g++-multilib g++-8-multilib gcc-8-doc Jul 17 17:08:15 volumio volumio[1074]: info: libstdc++6-8-dbg gcc-multilib autoconf automake libtool flex bison gdb Jul 17 17:08:15 volumio volumio[1074]: info: gcc-doc gcc-8-multilib gcc-8-locales libgcc1-dbg libgomp1-dbg libitm1-dbg Jul 17 17:08:15 volumio volumio[1074]: info: libatomic1-dbg libasan5-dbg liblsan0-dbg libtsan0-dbg libubsan1-dbg Jul 17 17:08:15 volumio volumio[1074]: info: libmpx2-dbg libquadmath0-dbg libstdc++-8-doc make-doc ed diffutils-doc Jul 17 17:08:15 volumio volumio[1074]: info: python-attr-doc python-crypto-doc python-cryptography-doc Jul 17 17:08:15 volumio volumio[1074]: info: python3-cryptography-vectors python-dbus-doc python3-dbus-dbg gnome-keyring Jul 17 17:08:15 volumio volumio[1074]: info: libkf5wallet-bin gir1.2-gnomekeyring-1.0 python-secretstorage-doc Jul 17 17:08:15 volumio volumio[1074]: info: python-setuptools-doc Jul 17 17:08:15 volumio volumio[1074]: info: The following NEW packages will be installed: Jul 17 17:08:15 volumio volumio[1074]: info: binutils binutils-common binutils-x86-64-linux-gnu build-essential bzip2 Jul 17 17:08:15 volumio volumio[1074]: info: dh-python dpkg-dev fakeroot g++ g++-8 gcc gcc-8 libalgorithm-diff-perl Jul 17 17:08:15 volumio volumio[1074]: info: libalgorithm-diff-xs-perl libalgorithm-merge-perl libasan5 libbinutils Jul 17 17:08:15 volumio volumio[1074]: info: libcc1-0 libexpat1-dev libfakeroot libgcc-8-dev libitm1 liblsan0 libmpx2 Jul 17 17:08:15 volumio volumio[1074]: info: libpython3-dev libpython3.7 libpython3.7-dev libquadmath0 libstdc++-8-dev Jul 17 17:08:15 volumio volumio[1074]: info: libtsan0 libubsan1 make patch python-pip-whl python3-aiohttp Jul 17 17:08:15 volumio volumio[1074]: info: python3-asn1crypto python3-async-timeout python3-attr python3-cffi-backend Jul 17 17:08:15 volumio volumio[1074]: info: python3-crypto python3-cryptography python3-dbus python3-dev Jul 17 17:08:15 volumio volumio[1074]: info: python3-entrypoints python3-gi python3-keyring python3-keyrings.alt Jul 17 17:08:15 volumio volumio[1074]: info: python3-multidict python3-pip python3-secretstorage python3-setuptools Jul 17 17:08:15 volumio volumio[1074]: info: python3-wheel python3-xdg python3-yarl python3.7-dev Jul 17 17:08:15 volumio volumio[1074]: info: 0 upgraded, 55 newly installed, 0 to remove and 0 not upgraded. Jul 17 17:08:15 volumio volumio[1074]: info: Need to get 85.3 MB of archives. Jul 17 17:08:15 volumio volumio[1074]: info: After this operation, 227 MB of additional disk space will be used. Jul 17 17:08:15 volumio volumio[1074]: info: Get:1 http://deb.debian.org/debian buster/main amd64 bzip2 amd64 1.0.6-9.2~deb10u1 [48.4 kB] Jul 17 17:08:15 volumio volumio[1074]: info: Get:2 http://deb.debian.org/debian buster/main amd64 binutils-common amd64 2.31.1-16 [2073 kB] Jul 17 17:08:20 volumio volumio[1074]: info: Get:3 http://deb.debian.org/debian buster/main amd64 libbinutils amd64 2.31.1-16 [478 kB] Jul 17 17:08:21 volumio volumio[1074]: info: Get:4 http://deb.debian.org/debian buster/main amd64 binutils-x86-64-linux-gnu amd64 2.31.1-16 [1823 kB] Jul 17 17:08:26 volumio volumio[1074]: info: Get:5 http://deb.debian.org/debian buster/main amd64 binutils amd64 2.31.1-16 [56.8 kB] Jul 17 17:08:26 volumio volumio[1074]: info: Get:6 http://deb.debian.org/debian buster/main amd64 libcc1-0 amd64 8.3.0-6 [46.6 kB] Jul 17 17:08:26 volumio volumio[1074]: info: Get:7 http://deb.debian.org/debian buster/main amd64 libitm1 amd64 8.3.0-6 [27.7 kB] Jul 17 17:08:26 volumio volumio[1074]: info: Get:8 http://deb.debian.org/debian buster/main amd64 libasan5 amd64 8.3.0-6 [362 kB] Jul 17 17:08:27 volumio volumio[1074]: info: Get:9 http://deb.debian.org/debian buster/main amd64 liblsan0 amd64 8.3.0-6 [131 kB] Jul 17 17:08:27 volumio volumio[1074]: info: Get:10 http://deb.debian.org/debian buster/main amd64 libtsan0 amd64 8.3.0-6 [283 kB] Jul 17 17:08:28 volumio volumio[1074]: info: Get:11 http://deb.debian.org/debian buster/main amd64 libubsan1 amd64 8.3.0-6 [120 kB] Jul 17 17:08:28 volumio volumio[1074]: info: Get:12 http://deb.debian.org/debian buster/main amd64 libmpx2 amd64 8.3.0-6 [11.4 kB] Jul 17 17:08:28 volumio volumio[1074]: info: Get:13 http://deb.debian.org/debian buster/main amd64 libquadmath0 amd64 8.3.0-6 [133 kB] Jul 17 17:08:28 volumio volumio[1074]: info: Get:14 http://deb.debian.org/debian buster/main amd64 libgcc-8-dev amd64 8.3.0-6 [2298 kB] Jul 17 17:08:30 volumio ntpd[890]: Soliciting pool server 2001:deb:0:4070::33 Jul 17 17:08:34 volumio volumio[1074]: info: Get:15 http://deb.debian.org/debian buster/main amd64 gcc-8 amd64 8.3.0-6 [9452 kB] Jul 17 17:08:34 volumio volumio[1074]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:34 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:34 volumio volumio[1074]: info: Restoring Previous Volume level: false true Jul 17 17:08:34 volumio volumio[1074]: info: VolumeController::SetAlsaVolume100 Jul 17 17:08:34 volumio volumio[1074]: info: Volume configurations have been set Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 17 17:08:34 volumio volumio[1074]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 4 Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 17 17:08:34 volumio volumio[1074]: info: Disabling external Volume Control Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:34 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:34 volumio volumio[1074]: info: CoreStateMachine::pushState Jul 17 17:08:34 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Jul 17 17:08:34 volumio volumio[1074]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 17 17:08:34 volumio volumio[1074]: SPOTIFY: SPOTIFY VOLUME 75 Jul 17 17:08:34 volumio volumio[1074]: SPOTIFY: VOLUMIO VOLUME 100 Jul 17 17:08:34 volumio volumio[1074]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 17 17:08:34 volumio volumio[1074]: info: Setting Spotify Volume from Volumio: 100 Jul 17 17:08:34 volumio volumio[1074]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jul 17 17:08:34 volumio volumio[1074]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:34 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:34 volumio volumio[1074]: info: CoreStateMachine::pushState Jul 17 17:08:34 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 17 17:08:34 volumio volumio[1074]: info: VolumeController::SetAlsaVolume0 Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:34 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:34 volumio volumio[1074]: info: CoreStateMachine::pushState Jul 17 17:08:34 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 17:08:34 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Jul 17 17:08:34 volumio volumio[1074]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jul 17 17:08:36 volumio volumio[1074]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Jul 17 17:08:36 volumio volumio[1074]: info: Sending Spotify command with payload to local API: /player/volume Jul 17 17:08:36 volumio go-librespot[2521]: time="2024-07-17T17:08:36+07:00" level=debug msg="update volume to 65535/65535" Jul 17 17:08:36 volumio go-librespot[2521]: time="2024-07-17T17:08:36+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 17 17:08:36 volumio go-librespot[2521]: time="2024-07-17T17:08:36+07:00" level=trace msg="emitting websocket event: volume" Jul 17 17:08:36 volumio volumio[1074]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Jul 17 17:08:36 volumio volumio[1074]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Jul 17 17:08:36 volumio volumio[1074]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Jul 17 17:08:36 volumio volumio[1074]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Jul 17 17:08:38 volumio volumio[1074]: info: VolumeController::SetAlsaVolumeNaN Jul 17 17:08:38 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:38 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:38 volumio volumio[1074]: info: CoreStateMachine::pushState Jul 17 17:08:38 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:38 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 17 17:08:38 volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Jul 17 17:08:38 volumio volumio[1074]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jul 17 17:08:48 volumio volumio[1074]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Jul 17 17:08:48 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Jul 17 17:08:48 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Jul 17 17:08:48 volumio sudo[3826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 17:08:48 volumio sudo[3826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:08:48 volumio sudo[3826]: pam_unix(sudo:session): session closed for user root Jul 17 17:08:48 volumio volumio[1074]: info: MPD Permissions set Jul 17 17:08:48 volumio sudo[3831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 17:08:48 volumio sudo[3831]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:08:48 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:08:48 volumio systemd[1]: Stopping Music Player Daemon... Jul 17 17:08:48 volumio systemd[1]: mpd.service: Succeeded. Jul 17 17:08:48 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:08:48 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 17 17:08:48 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 17:08:48 volumio systemd[1]: Starting Music Player Daemon... Jul 17 17:08:48 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:08:48 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:48 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:48 volumio sudo[3837]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 17 17:08:48 volumio sudo[3837]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:08:48 volumio sudo[3837]: pam_unix(sudo:session): session closed for user root Jul 17 17:08:50 volumio mpd[3841]: Jul 17 17:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 17:08:50 volumio systemd[1]: Started Music Player Daemon. Jul 17 17:08:50 volumio sudo[3831]: pam_unix(sudo:session): session closed for user root Jul 17 17:08:50 volumio volumio[1074]: error: updateQueue error: null Jul 17 17:08:55 volumio volumio[1074]: info: Get:16 http://deb.debian.org/debian buster/main amd64 gcc amd64 4:8.3.0-1 [5196 B] Jul 17 17:08:55 volumio volumio[1074]: info: Get:17 http://deb.debian.org/debian buster/main amd64 libstdc++-8-dev amd64 8.3.0-6 [1532 kB] Jul 17 17:08:59 volumio volumio[1074]: verbose: New Socket.io Connection to 192.168.100.234 from 192.168.100.176 UA: unknown Engine version: 3 Transport: websocket Total Clients: 7 Jul 17 17:08:59 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:08:59 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:08:59 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 17 17:08:59 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 17 17:08:59 volumio volumio[1074]: info: Get:18 http://deb.debian.org/debian buster/main amd64 g++-8 amd64 8.3.0-6 [9752 kB] Jul 17 17:09:00 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 17 17:09:00 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 17 17:09:00 volumio volumio[1074]: info: Discovery: Getting this device information Jul 17 17:09:00 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:09:00 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:09:00 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 17 17:09:00 volumio volumio[1074]: verbose: New Socket.io Connection to 192.168.100.234 from 192.168.100.176 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_7_8 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Jul 17 17:09:02 volumio sudo[3890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 17 17:09:02 volumio sudo[3890]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:09:02 volumio sudo[3890]: pam_unix(sudo:session): session closed for user root Jul 17 17:09:02 volumio sudo[3893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 17 17:09:02 volumio sudo[3893]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:09:03 volumio sudo[3893]: pam_unix(sudo:session): session closed for user root Jul 17 17:09:03 volumio volumio[1074]: verbose: New Socket.io Connection to 192.168.100.234 from 192.168.100.176 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_7_8 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Jul 17 17:09:03 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 17 17:09:03 volumio volumio[1074]: info: CoreCommandRouter::volumioGetVisibleSources Jul 17 17:09:03 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 17 17:09:03 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 17 17:09:03 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:09:03 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:09:03 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 17 17:09:03 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 17 17:09:03 volumio volumio[1074]: info: Received Get System Info Jul 17 17:09:03 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 17 17:09:03 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 17 17:09:03 volumio volumio[1074]: info: Discovery: Getting this device information Jul 17 17:09:03 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:09:03 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:09:03 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 17 17:09:03 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:09:03 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:09:03 volumio volumio[1074]: info: Listing playlists Jul 17 17:09:03 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 17 17:09:03 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 17 17:09:03 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 17 17:09:03 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 17 17:09:04 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 17 17:09:04 volumio volumio[1074]: info: Received Get System Info Jul 17 17:09:04 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 17 17:09:04 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 17 17:09:04 volumio volumio[1074]: info: Discovery: Getting this device information Jul 17 17:09:04 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:09:04 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:09:04 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 17 17:09:04 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 17 17:09:06 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 17 17:09:06 volumio volumio[1074]: info: Received Get System Info Jul 17 17:09:06 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 17 17:09:06 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 17 17:09:06 volumio volumio[1074]: info: Discovery: Getting this device information Jul 17 17:09:06 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:09:06 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:09:06 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 17 17:09:13 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 17 17:09:17 volumio volumio[1074]: info: Enabling plugin fusiondsp Jul 17 17:09:17 volumio volumio[1074]: info: Loading plugin "fusiondsp"... Jul 17 17:09:17 volumio volumio[1074]: info: Preparing to generate the ALSA configuration file Jul 17 17:09:17 volumio volumio[1074]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 17 17:09:17 volumio volumio[1074]: info: Reading ALSA contributions from plugins. Jul 17 17:09:17 volumio volumio[1074]: info: Asound.conf file unchanged, so no further update is needed Jul 17 17:09:17 volumio volumio[1074]: info: Output device has changed, restarting MPD Jul 17 17:09:17 volumio sudo[3897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 17:09:17 volumio sudo[3897]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:09:17 volumio sudo[3897]: pam_unix(sudo:session): session closed for user root Jul 17 17:09:17 volumio volumio[1074]: info: Output device has changed, restarting Shairport Sync Jul 17 17:09:17 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:17 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:09:17 volumio sudo[3900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 17:09:17 volumio sudo[3900]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:09:18 volumio systemd[1]: Stopping Music Player Daemon... Jul 17 17:09:18 volumio systemd[1]: mpd.service: Succeeded. Jul 17 17:09:18 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 17:09:18 volumio volumio[1074]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 17:09:18 volumio volumio[1074]: info: Output device has changed, restarting Shairport Sync Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:09:18 volumio systemd[1]: Starting Music Player Daemon... Jul 17 17:09:18 volumio volumio[1074]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 17:09:18 volumio volumio[1074]: info: PLUGIN START: fusiondsp Jul 17 17:09:18 volumio volumio[1074]: info: Loading i18n strings for locale vi Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: FusionDsp - mixtype--------------------- Software Jul 17 17:09:18 volumio volumio[1074]: info: Preparing to generate the ALSA configuration file Jul 17 17:09:18 volumio sudo[3905]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 17 17:09:18 volumio sudo[3905]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:09:18 volumio volumio[1074]: info: Done. Jul 17 17:09:18 volumio sudo[3905]: pam_unix(sudo:session): session closed for user root Jul 17 17:09:18 volumio volumio[1074]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 17 17:09:18 volumio volumio[1074]: info: Reading ALSA contributions from plugins. Jul 17 17:09:18 volumio volumio[1074]: info: MPD Permissions set Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Jul 17 17:09:18 volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: Starting Shairport Sync Jul 17 17:09:18 volumio volumio[1074]: info: Starting Shairport Sync Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 17:09:18 volumio volumio[1074]: info: Asound.conf file unchanged, so no further update is needed Jul 17 17:09:18 volumio volumio[1074]: info: Output device has changed, restarting MPD Jul 17 17:09:18 volumio sudo[3920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 17 17:09:18 volumio sudo[3920]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:09:18 volumio sudo[3918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 17 17:09:18 volumio sudo[3918]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:09:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 17 17:09:18 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 17 17:09:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 17 17:09:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 17 17:09:18 volumio sudo[3920]: pam_unix(sudo:session): session closed for user root Jul 17 17:09:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 17 17:09:18 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 17 17:09:18 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 17 17:09:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 17 17:09:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 17 17:09:18 volumio sudo[3918]: pam_unix(sudo:session): session closed for user root Jul 17 17:09:18 volumio sudo[3925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 17 17:09:18 volumio sudo[3925]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:09:18 volumio sudo[3925]: pam_unix(sudo:session): session closed for user root Jul 17 17:09:18 volumio volumio[1074]: info: Output device has changed, restarting Shairport Sync Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:09:18 volumio sudo[3929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 17 17:09:18 volumio sudo[3929]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:09:18 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 17 17:09:18 volumio systemd[1]: mpd.service: Succeeded. Jul 17 17:09:18 volumio systemd[1]: Stopped Music Player Daemon. Jul 17 17:09:18 volumio volumio[1074]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 17:09:18 volumio volumio[1074]: info: Output device has changed, restarting Shairport Sync Jul 17 17:09:18 volumio systemd[1]: Starting Music Player Daemon... Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 17 17:09:18 volumio volumio[1074]: info: MPD Permissions set Jul 17 17:09:18 volumio volumio[1074]: info: Shairport-Sync Started Jul 17 17:09:18 volumio volumio[1074]: info: Shairport-Sync Started Jul 17 17:09:18 volumio sudo[3936]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 17 17:09:18 volumio sudo[3936]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:09:18 volumio sudo[3936]: pam_unix(sudo:session): session closed for user root Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 17 17:09:18 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 17 17:09:19 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:19 volumio volumio[1074]: info: Starting Shairport Sync Jul 17 17:09:19 volumio volumio[1074]: info: Starting Shairport Sync Jul 17 17:09:19 volumio sudo[3950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 17 17:09:19 volumio sudo[3950]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:09:19 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 17 17:09:19 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 17 17:09:19 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 17 17:09:19 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 17 17:09:19 volumio sudo[3950]: pam_unix(sudo:session): session closed for user root Jul 17 17:09:19 volumio volumio[1074]: info: Shairport-Sync Started Jul 17 17:09:19 volumio sudo[3954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 17 17:09:19 volumio sudo[3954]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:09:19 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 17 17:09:19 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 17 17:09:19 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 17 17:09:19 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 17 17:09:19 volumio sudo[3954]: pam_unix(sudo:session): session closed for user root Jul 17 17:09:19 volumio volumio[1074]: info: Shairport-Sync Started Jul 17 17:09:19 volumio volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 17 17:09:19 volumio mpd[3942]: Jul 17 17:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 17 17:09:19 volumio systemd[1]: Started Music Player Daemon. Jul 17 17:09:19 volumio sudo[3900]: pam_unix(sudo:session): session closed for user root Jul 17 17:09:19 volumio sudo[3929]: pam_unix(sudo:session): session closed for user root Jul 17 17:09:20 volumio volumio[1074]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Jul 17 17:09:20 volumio volumio[1074]: info: camilladsp service started and running in background, instance 1 Jul 17 17:09:20 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 17 17:09:20 volumio volumio[1074]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 17 17:09:20 volumio volumio[1074]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 17 17:09:20 volumio volumio[1074]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 17 17:09:20 volumio volumio[1074]: info: FusionDsp loaded Jul 17 17:09:20 volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 17 17:09:20 volumio sudo[3972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 17 17:09:20 volumio sudo[3972]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 17 17:09:20 volumio sudo[3972]: pam_unix(sudo:session): session closed for user root Jul 17 17:09:20 volumio volumio[1074]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 17 17:09:20 volumio volumio[1074]: info: Adding Signal Path Element [object Object] Jul 17 17:09:20 volumio volumio[1074]: info: Adding fusiondspeq DSP Signal Path Element Jul 17 17:09:20 volumio volumio[1074]: info: FusionDsp - ---- installed callbackRead Jul 17 17:09:20 volumio volumio[1074]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 17:09:20 volumio volumio[1074]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 17 17:09:20 volumio volumio[1074]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Jul 17 17:09:20 volumio volumio[1074]: at onErrorNT (internal/child_process.js:472:16) Jul 17 17:09:20 volumio volumio[1074]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jul 17 17:09:20 volumio volumio[1074]: at runNextTicks (internal/process/task_queues.js:64:3) Jul 17 17:09:20 volumio volumio[1074]: at listOnTimeout (internal/timers.js:526:9) Jul 17 17:09:20 volumio volumio[1074]: at processTimers (internal/timers.js:500:7) { Jul 17 17:09:20 volumio volumio[1074]: errno: -2, Jul 17 17:09:20 volumio volumio[1074]: code: 'ENOENT', Jul 17 17:09:20 volumio volumio[1074]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 17 17:09:20 volumio volumio[1074]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 17 17:09:20 volumio volumio[1074]: spawnargs: [ Jul 17 17:09:20 volumio volumio[1074]: '-p', Jul 17 17:09:20 volumio volumio[1074]: 9876, Jul 17 17:09:20 volumio volumio[1074]: '-o', Jul 17 17:09:20 volumio volumio[1074]: '/tmp/camilladsp.log', Jul 17 17:09:20 volumio volumio[1074]: '-l', Jul 17 17:09:20 volumio volumio[1074]: 'warn', Jul 17 17:09:20 volumio volumio[1074]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 17 17:09:20 volumio volumio[1074]: ] Jul 17 17:09:20 volumio volumio[1074]: } Jul 17 17:09:20 volumio volumio[1074]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 17 17:09:20 volumio sudo[3983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-17 17:08 Jul 17 17:09:20 volumio sudo[3983]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"