-- Logs begin at Thu 2019-02-14 12:11:58 EET, end at Wed 2025-05-21 17:17:50 EEST. -- May 21 17:16:05 volumio2 sudo[4948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 21 17:16:05 volumio2 sudo[4948]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:05 volumio2 sudo[4948]: pam_unix(sudo:session): session closed for user root May 21 17:16:05 volumio2 sudo[4951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 21 17:16:05 volumio2 sudo[4951]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:05 volumio2 sudo[4951]: pam_unix(sudo:session): session closed for user root May 21 17:16:05 volumio2 volumio[952]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.6 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 21 17:16:05 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 21 17:16:05 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 21 17:16:06 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetVisibleSources May 21 17:16:06 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 17:16:06 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:06 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:06 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 21 17:16:06 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 21 17:16:06 volumio2 volumio[952]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 21 17:16:06 volumio2 volumio[952]: info: Received Get System Info May 21 17:16:06 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 17:16:06 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 17:16:06 volumio2 volumio[952]: info: Discovery: Getting this device information May 21 17:16:06 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:06 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:06 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 17:16:06 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:06 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:06 volumio2 volumio[952]: info: Listing playlists May 21 17:16:06 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 21 17:16:06 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 21 17:16:07 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 21 17:16:07 volumio2 volumio[952]: info: Received Get System Info May 21 17:16:07 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 17:16:07 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 17:16:07 volumio2 volumio[952]: info: Discovery: Getting this device information May 21 17:16:07 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:07 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:07 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 17:16:07 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 21 17:16:09 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 21 17:16:09 volumio2 volumio[952]: info: Received Get System Info May 21 17:16:09 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 17:16:09 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 17:16:09 volumio2 volumio[952]: info: Discovery: Getting this device information May 21 17:16:09 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:09 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:09 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 17:16:15 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 21 17:16:15 volumio2 volumio[952]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 17:16:15 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 21 17:16:15 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 17:16:15 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 21 17:16:15 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 17:16:15 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 17:16:15 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 17:16:15 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 17:16:15 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 17:16:15 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 17:16:15 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 17:16:15 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 21 17:16:15 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 21 17:16:24 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 21 17:16:24 volumio2 volumio[952]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 21 17:16:24 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 21 17:16:24 volumio2 volumio[952]: info: Received Get System Version May 21 17:16:24 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 21 17:16:24 volumio2 volumio[952]: info: Received Get System Info May 21 17:16:24 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 17:16:24 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 17:16:24 volumio2 volumio[952]: info: Discovery: Getting this device information May 21 17:16:24 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:24 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:24 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 17:16:31 volumio2 volumio[952]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 21 17:16:31 volumio2 volumio[952]: info: Preparing to save Alsa Options, stopping services first May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:31 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::volumioPause May 21 17:16:31 volumio2 volumio[952]: info: CoreStateMachine::pause May 21 17:16:31 volumio2 volumio[952]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Combo384 Amanero"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 21 17:16:31 volumio2 volumio[952]: info: Setting default mixerSoftMaster for Softvolume device May 21 17:16:31 volumio2 volumio[952]: info: QobuzConnect: setDeactiveState invoked May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:31 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:31 volumio2 vtcs[1983]: [2025-05-21 17:16:31.433] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 21 17:16:31 volumio2 vtcs[1983]: [2025-05-21 17:16:31.434] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 21 17:16:31 volumio2 sudo[5012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 21 17:16:31 volumio2 sudo[5012]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:31 volumio2 systemd[1]: Stopping Volumio Tidal Connect Service... May 21 17:16:31 volumio2 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 21 17:16:31 volumio2 systemd[1]: vtcs.service: Succeeded. May 21 17:16:31 volumio2 systemd[1]: Stopped Volumio Tidal Connect Service. May 21 17:16:31 volumio2 sudo[5012]: pam_unix(sudo:session): session closed for user root May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 21 17:16:31 volumio2 volumio[952]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 21 17:16:31 volumio2 volumio[952]: info: Disabling external Volume Control May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 17:16:31 volumio2 volumio[952]: info: Enable softmixer device for audio device undefined May 21 17:16:31 volumio2 sudo[5025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 21 17:16:31 volumio2 sudo[5025]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:31 volumio2 sudo[5025]: pam_unix(sudo:session): session closed for user root May 21 17:16:31 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 17:16:31 volumio2 sudo[5032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 21 17:16:31 volumio2 sudo[5032]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:31 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:31 volumio2 volumio[952]: info: CoreStateMachine::pushState May 21 17:16:31 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::volumioPushState May 21 17:16:31 volumio2 sudo[5032]: pam_unix(sudo:session): session closed for user root May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:31 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:31 volumio2 sudo[5034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 21 17:16:31 volumio2 sudo[5034]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:31 volumio2 volumio[952]: info: Software Volume ALSA configuration written May 21 17:16:31 volumio2 volumio[952]: info: Preparing to generate the ALSA configuration file May 21 17:16:31 volumio2 systemd[1]: Stopping Volumio Qobuz Connect Service... May 21 17:16:31 volumio2 qobuz-connect[1945]: 20250521 17:16:31.675 [1945.1945] INFO SampleApp: Stopping Local configuration server May 21 17:16:31 volumio2 qobuz-connect[1945]: 20250521 17:16:31.693 [1945.1945] INFO SampleApp: shat down connection on UNIX socket May 21 17:16:31 volumio2 systemd[1]: qobuz-connect.service: Succeeded. May 21 17:16:31 volumio2 systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 17:16:31 volumio2 systemd[1]: Started Volumio Qobuz Connect Service. May 21 17:16:31 volumio2 sudo[5034]: pam_unix(sudo:session): session closed for user root May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 21 17:16:31 volumio2 volumio[952]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 21 17:16:31 volumio2 volumio[952]: info: Reading ALSA contributions from plugins. May 21 17:16:31 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 17:16:31 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 17:16:31 volumio2 volumio[952]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 21 17:16:31 volumio2 volumio[952]: info: QobuzConnect: setDeactiveState invoked May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:31 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:31 volumio2 volumio[952]: info: Executing endpoint qc_getconfig May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 21 17:16:31 volumio2 qobuz-connect[5037]: 20250521 17:16:31.865 [5037.5037] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 21 17:16:31 volumio2 volumio[952]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 21 17:16:31 volumio2 volumio[952]: info: QobuzConnect: QOBUZ Connect daemon connected May 21 17:16:31 volumio2 volumio[952]: info: Asound.conf file written May 21 17:16:31 volumio2 qobuz-connect[5037]: 20250521 17:16:31.870 [5037.5037] INFO VolumeManager: [0x833910]: Setting new playback volume: 75 May 21 17:16:31 volumio2 qobuz-connect[5037]: 20250521 17:16:31.870 [5037.5037] INFO VolumeManager: [0x833910]: Setting new mute state: 0 May 21 17:16:31 volumio2 qobuz-connect[5037]: 20250521 17:16:31.870 [5037.5037] INFO QobuzConnect: [0x8342e0]: Client initialized! May 21 17:16:31 volumio2 qobuz-connect[5037]: 20250521 17:16:31.870 [5037.5037] INFO SampleApp: Starting Avahi advertising, name: Volumio2, service name: _qobuz-connect._tcp May 21 17:16:31 volumio2 qobuz-connect[5037]: 20250521 17:16:31.876 [5037.5037] INFO LocalConfigManager: [0x8331f0]: Starting Local Configuration server May 21 17:16:31 volumio2 qobuz-connect[5037]: 20250521 17:16:31.876 [5037.5037] INFO SampleApp: Starting Local configuration server May 21 17:16:31 volumio2 qobuz-connect[5037]: 20250521 17:16:31.877 [5037.5037] INFO SampleApp: Connected to UNIX socket client 0x828f60 May 21 17:16:31 volumio2 sudo[5045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 21 17:16:31 volumio2 sudo[5045]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:31 volumio2 sudo[5045]: pam_unix(sudo:session): session closed for user root May 21 17:16:31 volumio2 volumio[952]: info: Output device has changed, restarting MPD May 21 17:16:31 volumio2 sudo[5054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 17:16:31 volumio2 sudo[5054]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:31 volumio2 sudo[5054]: pam_unix(sudo:session): session closed for user root May 21 17:16:31 volumio2 volumio[952]: info: Output device has changed, restarting Shairport Sync May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 17:16:31 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 17:16:31 volumio2 sudo[5057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 17:16:31 volumio2 sudo[5057]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:32 volumio2 systemd[1]: Stopping Music Player Daemon... May 21 17:16:32 volumio2 qobuz-connect[5037]: 20250521 17:16:32.015 [5037.5037] INFO SampleApp: Playback volume changed: 75 May 21 17:16:32 volumio2 volumio[952]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 21 17:16:32 volumio2 volumio[952]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 17:16:32 volumio2 systemd[1]: mpd.service: Succeeded. May 21 17:16:32 volumio2 systemd[1]: Stopped Music Player Daemon. May 21 17:16:32 volumio2 volumio[952]: info: QobuzConnect: setDeactiveState invoked May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:32 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:32 volumio2 systemd[1]: Starting Music Player Daemon... May 21 17:16:32 volumio2 volumio[952]: info: Output device has changed, restarting MPD May 21 17:16:32 volumio2 sudo[5066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 21 17:16:32 volumio2 sudo[5066]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:32 volumio2 sudo[5069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 17:16:32 volumio2 sudo[5069]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:32 volumio2 sudo[5069]: pam_unix(sudo:session): session closed for user root May 21 17:16:32 volumio2 sudo[5066]: pam_unix(sudo:session): session closed for user root May 21 17:16:32 volumio2 volumio[952]: info: Output device has changed, restarting Shairport Sync May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 17:16:32 volumio2 sudo[5062]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 17:16:32 volumio2 sudo[5062]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:32 volumio2 sudo[5072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 17:16:32 volumio2 sudo[5062]: pam_unix(sudo:session): session closed for user root May 21 17:16:32 volumio2 sudo[5072]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:32 volumio2 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 21 17:16:32 volumio2 systemd[1]: mpd.service: Succeeded. May 21 17:16:32 volumio2 systemd[1]: Stopped Music Player Daemon. May 21 17:16:32 volumio2 systemd[1]: Starting Music Player Daemon... May 21 17:16:32 volumio2 volumio[952]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 21 17:16:32 volumio2 volumio[952]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 17:16:32 volumio2 volumio[952]: info: QobuzConnect: setDeactiveState invoked May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:32 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:32 volumio2 sudo[5085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 21 17:16:32 volumio2 sudo[5085]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:32 volumio2 sudo[5081]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 17:16:32 volumio2 sudo[5081]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:32 volumio2 sudo[5081]: pam_unix(sudo:session): session closed for user root May 21 17:16:32 volumio2 sudo[5088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 21 17:16:32 volumio2 sudo[5088]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:32 volumio2 sudo[5093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 21 17:16:32 volumio2 sudo[5093]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:32 volumio2 sudo[5085]: pam_unix(sudo:session): session closed for user root May 21 17:16:32 volumio2 sudo[5099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 21 17:16:32 volumio2 sudo[5099]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:32 volumio2 sudo[5088]: pam_unix(sudo:session): session closed for user root May 21 17:16:32 volumio2 sudo[5093]: pam_unix(sudo:session): session closed for user root May 21 17:16:32 volumio2 sudo[5099]: pam_unix(sudo:session): session closed for user root May 21 17:16:32 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 17:16:32 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 17:16:32 volumio2 sudo[5109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 21 17:16:32 volumio2 sudo[5109]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:32 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 17:16:32 volumio2 sudo[5110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 21 17:16:32 volumio2 sudo[5110]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:32 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 17:16:32 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 17:16:32 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 17:16:32 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 17:16:32 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:32 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:32 volumio2 volumio[952]: info: MPD Permissions set May 21 17:16:32 volumio2 volumio[952]: info: MPD Permissions set May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:32 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 17:16:32 volumio2 systemd[1]: Stopping Volumio Qobuz Connect Service... May 21 17:16:32 volumio2 qobuz-connect[5037]: 20250521 17:16:32.418 [5037.5037] INFO SampleApp: Stopping Local configuration server May 21 17:16:32 volumio2 sudo[5110]: pam_unix(sudo:session): session closed for user root May 21 17:16:32 volumio2 qobuz-connect[5037]: 20250521 17:16:32.428 [5037.5037] INFO SampleApp: shat down connection on UNIX socket May 21 17:16:32 volumio2 sudo[5113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 21 17:16:32 volumio2 systemd[1]: qobuz-connect.service: Succeeded. May 21 17:16:32 volumio2 systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 17:16:32 volumio2 sudo[5113]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 17:16:32 volumio2 systemd[1]: Started Volumio Qobuz Connect Service. May 21 17:16:32 volumio2 sudo[5109]: pam_unix(sudo:session): session closed for user root May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 17:16:32 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 17:16:32 volumio2 systemd[1]: Stopping Volumio Qobuz Connect Service... May 21 17:16:32 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 17:16:32 volumio2 systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT May 21 17:16:32 volumio2 systemd[1]: qobuz-connect.service: Succeeded. May 21 17:16:32 volumio2 systemd[1]: Stopped Volumio Qobuz Connect Service. May 21 17:16:32 volumio2 volumio[952]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 21 17:16:32 volumio2 volumio[952]: info: QobuzConnect: setDeactiveState invoked May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:32 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:32 volumio2 systemd[1]: Started Volumio Qobuz Connect Service. May 21 17:16:32 volumio2 sudo[5113]: pam_unix(sudo:session): session closed for user root May 21 17:16:32 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 17:16:32 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 17:16:32 volumio2 volumio[952]: info: Starting Shairport Sync May 21 17:16:32 volumio2 volumio[952]: info: Starting Shairport Sync May 21 17:16:32 volumio2 sudo[5128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 21 17:16:32 volumio2 sudo[5128]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:32 volumio2 volumio[952]: info: Executing endpoint qc_getconfig May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 21 17:16:32 volumio2 qobuz-connect[5125]: 20250521 17:16:32.597 [5125.5125] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 21 17:16:32 volumio2 volumio[952]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 21 17:16:32 volumio2 volumio[952]: info: QobuzConnect: QOBUZ Connect daemon connected May 21 17:16:32 volumio2 qobuz-connect[5125]: 20250521 17:16:32.602 [5125.5125] INFO VolumeManager: [0x17af910]: Setting new playback volume: 75 May 21 17:16:32 volumio2 qobuz-connect[5125]: 20250521 17:16:32.603 [5125.5125] INFO VolumeManager: [0x17af910]: Setting new mute state: 0 May 21 17:16:32 volumio2 qobuz-connect[5125]: 20250521 17:16:32.603 [5125.5125] INFO QobuzConnect: [0x17b02e0]: Client initialized! May 21 17:16:32 volumio2 qobuz-connect[5125]: 20250521 17:16:32.603 [5125.5125] INFO SampleApp: Starting Avahi advertising, name: Volumio2, service name: _qobuz-connect._tcp May 21 17:16:32 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 21 17:16:32 volumio2 systemd[1]: shairport-sync.service: Succeeded. May 21 17:16:32 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 21 17:16:32 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 17:16:32 volumio2 sudo[5131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 21 17:16:32 volumio2 sudo[5131]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:32 volumio2 qobuz-connect[5125]: 20250521 17:16:32.626 [5125.5125] INFO LocalConfigManager: [0x17af1f0]: Starting Local Configuration server May 21 17:16:32 volumio2 qobuz-connect[5125]: 20250521 17:16:32.627 [5125.5125] INFO SampleApp: Starting Local configuration server May 21 17:16:32 volumio2 qobuz-connect[5125]: 20250521 17:16:32.627 [5125.5125] INFO SampleApp: Connected to UNIX socket client 0x17a4f60 May 21 17:16:32 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 17:16:32 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 21 17:16:32 volumio2 sudo[5128]: pam_unix(sudo:session): session closed for user root May 21 17:16:32 volumio2 volumio[952]: info: Shairport-Sync Started May 21 17:16:32 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 21 17:16:32 volumio2 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 21 17:16:32 volumio2 systemd[1]: shairport-sync.service: Succeeded. May 21 17:16:32 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 21 17:16:32 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: running May 21 17:16:32 volumio2 volumio[952]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 21 17:16:32 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 21 17:16:32 volumio2 sudo[5131]: pam_unix(sudo:session): session closed for user root May 21 17:16:32 volumio2 volumio[952]: info: Shairport-Sync Started May 21 17:16:32 volumio2 qobuz-connect[5125]: 20250521 17:16:32.747 [5125.5125] INFO SampleApp: Playback volume changed: 75 May 21 17:16:32 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:32 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:33 volumio2 mpd[5091]: May 21 17:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 21 17:16:33 volumio2 systemd[1]: Started Music Player Daemon. May 21 17:16:33 volumio2 sudo[5057]: pam_unix(sudo:session): session closed for user root May 21 17:16:33 volumio2 sudo[5072]: pam_unix(sudo:session): session closed for user root May 21 17:16:33 volumio2 volumio[952]: error: MPD error: The expression evaluated to a falsy value: May 21 17:16:33 volumio2 volumio[952]: assert.ok(self.idling) May 21 17:16:33 volumio2 volumio[952]: error: The expression evaluated to a falsy value: May 21 17:16:33 volumio2 volumio[952]: assert.ok(self.idling) May 21 17:16:33 volumio2 volumio[952]: error: updateQueue error: null May 21 17:16:34 volumio2 volumio[952]: info: TidalConnect service stoped! May 21 17:16:34 volumio2 volumio[952]: info: TidalConnect service stoped! May 21 17:16:34 volumio2 volumio[952]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 21 17:16:34 volumio2 volumio[952]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 21 17:16:34 volumio2 sudo[5149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 21 17:16:34 volumio2 sudo[5149]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:34 volumio2 systemd[1]: Started Volumio Tidal Connect Service. May 21 17:16:34 volumio2 sudo[5149]: pam_unix(sudo:session): session closed for user root May 21 17:16:34 volumio2 volumio[952]: info: Executing endpoint tc_getconfig May 21 17:16:34 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 21 17:16:34 volumio2 vtcs[5151]: STARTING TidalConnect services, version: 1.4.0.34 May 21 17:16:34 volumio2 vtcs[5151]: STARTED TidalConnect services. May 21 17:16:34 volumio2 volumio[952]: info: Executing endpoint tc_connect May 21 17:16:34 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 21 17:16:34 volumio2 volumio[952]: info: Connecting to TidalConnect May 21 17:16:34 volumio2 volumio[952]: info: CoreCommandRouter::servicePushState May 21 17:16:34 volumio2 volumio[952]: info: CoreStateMachine::pushState May 21 17:16:34 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:34 volumio2 volumio[952]: info: CoreCommandRouter::volumioPushState May 21 17:16:34 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:34 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:34 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:34 volumio2 volumio[952]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 21 17:16:34 volumio2 volumio[952]: info: CoreCommandRouter::servicePushState May 21 17:16:34 volumio2 volumio[952]: info: CoreStateMachine::pushState May 21 17:16:34 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:34 volumio2 volumio[952]: info: CoreCommandRouter::volumioPushState May 21 17:16:34 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:34 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:34 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:34 volumio2 volumio[952]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 21 17:16:35 volumio2 volumio[952]: info: TidalConnect service stoped! May 21 17:16:35 volumio2 volumio[952]: info: TidalConnect service stoped! May 21 17:16:35 volumio2 volumio[952]: info: TidalConnect service stoped! May 21 17:16:35 volumio2 volumio[952]: info: TidalConnect service stoped! May 21 17:16:35 volumio2 volumio[952]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 21 17:16:35 volumio2 volumio[952]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 21 17:16:35 volumio2 volumio[952]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 21 17:16:35 volumio2 volumio[952]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 21 17:16:35 volumio2 sudo[5228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 21 17:16:35 volumio2 sudo[5228]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:35 volumio2 sudo[5231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 21 17:16:35 volumio2 sudo[5228]: pam_unix(sudo:session): session closed for user root May 21 17:16:35 volumio2 sudo[5231]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 17:16:35 volumio2 sudo[5231]: pam_unix(sudo:session): session closed for user root May 21 17:16:35 volumio2 volumio[952]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 21 17:16:35 volumio2 volumio[952]: info: CoreStateMachine::getcurrentVolume May 21 17:16:35 volumio2 volumio[952]: info: CoreCommandRouter::volumioRetrievevolume May 21 17:16:35 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:35 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:35 volumio2 volumio[952]: info: CoreStateMachine::pushState May 21 17:16:35 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:35 volumio2 volumio[952]: info: CoreCommandRouter::volumioPushState May 21 17:16:35 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:35 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:35 volumio2 volumio[952]: info: CoreStateMachine::updateTrackBlock May 21 17:16:35 volumio2 volumio[952]: info: CorePlayQueue::getTrackBlock May 21 17:16:35 volumio2 volumio[952]: info: CoreCommandRouter::volumioRetrievevolume May 21 17:16:35 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:35 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:35 volumio2 volumio[952]: info: CoreStateMachine::pushState May 21 17:16:35 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:35 volumio2 volumio[952]: info: CoreCommandRouter::volumioPushState May 21 17:16:35 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetState May 21 17:16:35 volumio2 volumio[952]: info: CorePlayQueue::getTrack 0 May 21 17:16:37 volumio2 volumio[952]: info: TidalConnect service started! May 21 17:16:38 volumio2 volumio[952]: info: TidalConnect service started! May 21 17:16:38 volumio2 volumio[952]: info: TidalConnect service started! May 21 17:16:53 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetQueue May 21 17:16:53 volumio2 volumio[952]: info: CoreStateMachine::getQueue May 21 17:16:53 volumio2 volumio[952]: info: CorePlayQueue::getQueue May 21 17:16:53 volumio2 volumio[952]: info: Executing endpoint metavolumio May 21 17:16:53 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 21 17:16:53 volumio2 volumio[952]: info: Executing endpoint metavolumio May 21 17:16:53 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 21 17:17:05 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 21 17:17:05 volumio2 volumio[952]: info: CURURI: music-library May 21 17:17:05 volumio2 volumio[952]: info: Preload queue cleared May 21 17:17:07 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 21 17:17:07 volumio2 volumio[952]: info: CURURI: music-library/USB May 21 17:17:07 volumio2 volumio[952]: info: Preload queue cleared May 21 17:17:09 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 21 17:17:09 volumio2 volumio[952]: info: CURURI: music-library/USB/RECOVERY May 21 17:17:09 volumio2 volumio[952]: info: Preload queue cleared May 21 17:17:09 volumio2 volumio[952]: info: Preloading song: music-library/USB/RECOVERY/11k2_44k1.wav May 21 17:17:09 volumio2 volumio[952]: info: Preloading song: music-library/USB/RECOVERY/12 Sinfonie Nr. 9 d-Moll, op. 125- IVg. – Poco allegro, stringendo il tempo, sempre più allegro – Prestissimo – Maestoso – Prestissimo.wav May 21 17:17:09 volumio2 volumio[952]: info: Preloading song: music-library/USB/RECOVERY/12k_48k.wav May 21 17:17:09 volumio2 volumio[952]: info: Preloading song: music-library/USB/RECOVERY/997Hz_44k1.wav May 21 17:17:09 volumio2 volumio[952]: info: Preloading song: music-library/USB/RECOVERY/997Hz_48k.wav May 21 17:17:09 volumio2 volumio[952]: info: Preloading song: music-library/USB/RECOVERY/silence_44k1.wav May 21 17:17:09 volumio2 volumio[952]: info: Preloading song: music-library/USB/RECOVERY/silence_48k.wav May 21 17:17:09 volumio2 volumio[952]: info: Exploding uri music-library/USB/RECOVERY/11k2_44k1.wav in service mpd May 21 17:17:09 volumio2 volumio[952]: info: ALBUMART /albumart?cacheid=792&web=//extralarge&path=%2Fmnt%2FUSB%2FRECOVERY%2F11k2_44k1.wav&metadata=false May 21 17:17:09 volumio2 volumio[952]: info: URI /mnt/USB/RECOVERY/11k2_44k1.wav May 21 17:17:09 volumio2 volumio[952]: info: Exploding uri music-library/USB/RECOVERY/12 Sinfonie Nr. 9 d-Moll, op. 125- IVg. – Poco allegro, stringendo il tempo, sempre più allegro – Prestissimo – Maestoso – Prestissimo.wav in service mpd May 21 17:17:09 volumio2 volumio[952]: info: ALBUMART /albumart?cacheid=792&web=//extralarge&path=%2Fmnt%2FUSB%2FRECOVERY%2F12%20Sinfonie%20Nr.%209%20d-Moll%2C%20op.%20125-%20IVg.%20%E2%80%93%20Poco%20allegro%2C%20stringendo%20il%20tempo%2C%20sempre%20pi%C3%B9%20allegro%20%E2%80%93%20Prestissimo%20%E2%80%93%20Maestoso%20%E2%80%93%20Prestissimo.wav&metadata=false May 21 17:17:09 volumio2 volumio[952]: info: URI /mnt/USB/RECOVERY/12 Sinfonie Nr. 9 d-Moll, op. 125- IVg. – Poco allegro, stringendo il tempo, sempre più allegro – Prestissimo – Maestoso – Prestissimo.wav May 21 17:17:09 volumio2 volumio[952]: info: Exploding uri music-library/USB/RECOVERY/12k_48k.wav in service mpd May 21 17:17:09 volumio2 volumio[952]: info: ALBUMART /albumart?cacheid=792&web=//extralarge&path=%2Fmnt%2FUSB%2FRECOVERY%2F12k_48k.wav&metadata=false May 21 17:17:09 volumio2 volumio[952]: info: URI /mnt/USB/RECOVERY/12k_48k.wav May 21 17:17:10 volumio2 volumio[952]: info: Exploding uri music-library/USB/RECOVERY/997Hz_44k1.wav in service mpd May 21 17:17:10 volumio2 volumio[952]: info: ALBUMART /albumart?cacheid=792&web=//extralarge&path=%2Fmnt%2FUSB%2FRECOVERY%2F997Hz_44k1.wav&metadata=false May 21 17:17:10 volumio2 volumio[952]: info: URI /mnt/USB/RECOVERY/997Hz_44k1.wav May 21 17:17:10 volumio2 volumio[952]: info: Exploding uri music-library/USB/RECOVERY/997Hz_48k.wav in service mpd May 21 17:17:10 volumio2 volumio[952]: info: ALBUMART /albumart?cacheid=792&web=//extralarge&path=%2Fmnt%2FUSB%2FRECOVERY%2F997Hz_48k.wav&metadata=false May 21 17:17:10 volumio2 volumio[952]: info: URI /mnt/USB/RECOVERY/997Hz_48k.wav May 21 17:17:10 volumio2 volumio[952]: info: Exploding uri music-library/USB/RECOVERY/silence_44k1.wav in service mpd May 21 17:17:10 volumio2 volumio[952]: info: ALBUMART /albumart?cacheid=792&web=//extralarge&path=%2Fmnt%2FUSB%2FRECOVERY%2Fsilence_44k1.wav&metadata=false May 21 17:17:10 volumio2 volumio[952]: info: URI /mnt/USB/RECOVERY/silence_44k1.wav May 21 17:17:10 volumio2 volumio[952]: info: Exploding uri music-library/USB/RECOVERY/silence_48k.wav in service mpd May 21 17:17:10 volumio2 volumio[952]: info: ALBUMART /albumart?cacheid=792&web=//extralarge&path=%2Fmnt%2FUSB%2FRECOVERY%2Fsilence_48k.wav&metadata=false May 21 17:17:10 volumio2 volumio[952]: info: URI /mnt/USB/RECOVERY/silence_48k.wav May 21 17:17:48 volumio2 volumio[952]: info: Preload queue cleared May 21 17:17:48 volumio2 volumio[952]: info: CoreCommandRouter::volumioReplaceandPlayItems May 21 17:17:48 volumio2 volumio[952]: info: CoreStateMachine::ClearQueue May 21 17:17:48 volumio2 volumio[952]: info: CoreStateMachine::stop May 21 17:17:48 volumio2 volumio[952]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 17:17:48 volumio2 volumio[952]: info: CorePlayQueue::clearPlayQueue May 21 17:17:48 volumio2 volumio[952]: info: CorePlayQueue::saveQueue May 21 17:17:48 volumio2 volumio[952]: info: CoreCommandRouter::volumioPushQueue May 21 17:17:48 volumio2 volumio[952]: info: CoreStateMachine::addQueueItems May 21 17:17:48 volumio2 volumio[952]: info: CorePlayQueue::addQueueItems May 21 17:17:48 volumio2 volumio[952]: info: Preload queue cleared May 21 17:17:48 volumio2 volumio[952]: info: Adding Item to queue: music-library/USB/RECOVERY/11k2_44k1.wav May 21 17:17:48 volumio2 volumio[952]: info: Using cached record of: music-library/USB/RECOVERY/11k2_44k1.wav May 21 17:17:48 volumio2 volumio[952]: info: Adding Item to queue: music-library/USB/RECOVERY/12 Sinfonie Nr. 9 d-Moll, op. 125- IVg. – Poco allegro, stringendo il tempo, sempre più allegro – Prestissimo – Maestoso – Prestissimo.wav May 21 17:17:48 volumio2 volumio[952]: info: Using cached record of: music-library/USB/RECOVERY/12 Sinfonie Nr. 9 d-Moll, op. 125- IVg. – Poco allegro, stringendo il tempo, sempre più allegro – Prestissimo – Maestoso – Prestissimo.wav May 21 17:17:48 volumio2 volumio[952]: info: Adding Item to queue: music-library/USB/RECOVERY/12k_48k.wav May 21 17:17:48 volumio2 volumio[952]: info: Using cached record of: music-library/USB/RECOVERY/12k_48k.wav May 21 17:17:48 volumio2 volumio[952]: info: Adding Item to queue: music-library/USB/RECOVERY/997Hz_44k1.wav May 21 17:17:48 volumio2 volumio[952]: info: Using cached record of: music-library/USB/RECOVERY/997Hz_44k1.wav May 21 17:17:48 volumio2 volumio[952]: info: Adding Item to queue: music-library/USB/RECOVERY/997Hz_48k.wav May 21 17:17:48 volumio2 volumio[952]: info: Using cached record of: music-library/USB/RECOVERY/997Hz_48k.wav May 21 17:17:48 volumio2 volumio[952]: info: CoreCommandRouter::volumioPushQueue May 21 17:17:48 volumio2 volumio[952]: info: CorePlayQueue::saveQueue May 21 17:17:48 volumio2 volumio[952]: info: CoreStateMachine::updateTrackBlock May 21 17:17:48 volumio2 volumio[952]: info: CorePlayQueue::getTrackBlock May 21 17:17:48 volumio2 volumio[952]: info: CoreCommandRouter::volumioPlay May 21 17:17:48 volumio2 volumio[952]: info: CoreStateMachine::play index 4 May 21 17:17:48 volumio2 volumio[952]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 17:17:48 volumio2 volumio[952]: info: CoreStateMachine::addQueueItems May 21 17:17:48 volumio2 volumio[952]: info: CorePlayQueue::addQueueItems May 21 17:17:48 volumio2 volumio[952]: info: Preload queue cleared May 21 17:17:48 volumio2 volumio[952]: info: Adding Item to queue: music-library/USB/RECOVERY/silence_44k1.wav May 21 17:17:48 volumio2 volumio[952]: info: Using cached record of: music-library/USB/RECOVERY/silence_44k1.wav May 21 17:17:48 volumio2 volumio[952]: info: Adding Item to queue: music-library/USB/RECOVERY/silence_48k.wav May 21 17:17:48 volumio2 volumio[952]: info: Using cached record of: music-library/USB/RECOVERY/silence_48k.wav May 21 17:17:48 volumio2 volumio[952]: info: Adding Item to queue: music-library/USB/RECOVERY/HOME 13-06-2024 May 21 17:17:48 volumio2 volumio[952]: info: Exploding uri music-library/USB/RECOVERY/HOME 13-06-2024 in service mpd May 21 17:17:50 volumio2 volumio[952]: info: CoreStateMachine::stop May 21 17:17:50 volumio2 volumio[952]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 17:17:50 volumio2 volumio[952]: info: CoreStateMachine::play index undefined May 21 17:17:50 volumio2 volumio[952]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 17:17:50 volumio2 volumio[952]: info: CorePlayQueue::getTrack 4 May 21 17:17:50 volumio2 volumio[952]: info: CoreStateMachine::startPlaybackTimer May 21 17:17:50 volumio2 volumio[952]: info: CorePlayQueue::getTrack 4 May 21 17:17:50 volumio2 volumio[952]: info: CoreCommandRouter::volumioGetVisibleSources May 21 17:17:50 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 17:17:50 volumio2 volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 21 17:17:50 volumio2 volumio[952]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio May 21 17:17:50 volumio2 volumio[952]: verbose: ControllerMpd::clearAddPlayTracks USB/RECOVERY/997Hz_48k.wav May 21 17:17:50 volumio2 volumio[952]: verbose: ControllerMpd::sendMpdCommand stop May 21 17:17:50 volumio2 volumio[952]: info: ALBUMART /albumart?cacheid=792&web=//extralarge&path=%2Fmnt%2FUSB%2FRECOVERY%2FHOME%2013-06-2024%2FChris%2FDeree%2FGenEd%2FPresentation%20Skills%2FInformative%20Speech%2Fwetransfer-335b65.zip&metadata=false May 21 17:17:50 volumio2 volumio[952]: info: URI /mnt/USB/RECOVERY/HOME 13-06-2024/Chris/Deree/GenEd/Presentation Skills/Informative Speech/wetransfer-335b65.zip May 21 17:17:50 volumio2 volumio[952]: info: ALBUMART /albumart?cacheid=792&web=//extralarge&path=%2Fmnt%2FUSB%2FRECOVERY%2FHOME%2013-06-2024%2FChris%2FDeree%2FGenEd%2FPresentation%20Skills%2FInformative%20Speech%2Fwetransfer-335b65.zip&metadata=false May 21 17:17:50 volumio2 volumio[952]: info: URI /mnt/USB/RECOVERY/HOME 13-06-2024/Chris/Deree/GenEd/Presentation Skills/Informative Speech/wetransfer-335b65.zip May 21 17:17:50 volumio2 volumio[952]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 17:17:50 volumio2 volumio[952]: Error: Unable to resolve or reject the same promise twice May 21 17:17:50 volumio2 volumio[952]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 21 17:17:50 volumio2 volumio[952]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 May 21 17:17:50 volumio2 volumio[952]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) May 21 17:17:50 volumio2 volumio[952]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) May 21 17:17:50 volumio2 volumio[952]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) May 21 17:17:50 volumio2 volumio[952]: at Socket.emit (events.js:315:20) May 21 17:17:50 volumio2 volumio[952]: at addChunk (internal/streams/readable.js:309:12) May 21 17:17:50 volumio2 volumio[952]: at readableAddChunk (internal/streams/readable.js:280:11) May 21 17:17:50 volumio2 volumio[952]: at Socket.Readable.push (internal/streams/readable.js:223:10) May 21 17:17:50 volumio2 volumio[952]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) May 21 17:17:50 volumio2 volumio[952]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 17:17:50 volumio2 sudo[5426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 17:16 May 21 17:17:50 volumio2 sudo[5426]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"