Dec 22 23:09:02 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 23:09:02 volumiodj volumio[1381]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 22 23:09:02 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 22 23:09:02 volumiodj volumio[1381]: info: Received Get System Version Dec 22 23:09:02 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 23:09:02 volumiodj volumio[1381]: info: Received Get System Info Dec 22 23:09:02 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 23:09:02 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 23:09:02 volumiodj volumio[1381]: info: Discovery: Getting this device information Dec 22 23:09:02 volumiodj volumio[1381]: info: CoreCommandRouter::volumioGetState Dec 22 23:09:02 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:02 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 23:09:03 volumiodj volumio[1381]: info: Starting Uninstall of plugin user_interface - peppy_screensaver Dec 22 23:09:03 volumiodj volumio[1381]: info: Uninstalling plugin peppy_screensaver Dec 22 23:09:03 volumiodj volumio[1381]: info: Disabling plugin peppy_screensaver Dec 22 23:09:03 volumiodj volumio[1381]: info: Preparing to generate the ALSA configuration file Dec 22 23:09:03 volumiodj volumio[1381]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 22 23:09:03 volumiodj volumio[1381]: info: Reading ALSA contributions from plugins. Dec 22 23:09:03 volumiodj volumio[1381]: info: Asound.conf file unchanged, so no further update is needed Dec 22 23:09:03 volumiodj volumio[1381]: info: Output device has changed, restarting MPD Dec 22 23:09:03 volumiodj volumio[1381]: info: Output device has changed, restarting Shairport Sync Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 23:09:03 volumiodj sudo[3941]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 23:09:03 volumiodj sudo[3941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:03 volumiodj sudo[3943]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 23:09:03 volumiodj sudo[3943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:03 volumiodj sudo[3941]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:03 volumiodj volumio[1381]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 23:09:03 volumiodj volumio[1381]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: QobuzConnect: setDeactiveState invoked Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::volumioGetState Dec 22 23:09:03 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:03 volumiodj vtcs[3903]: [2025-12-22 23:09:03.443] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 22 23:09:03 volumiodj vtcs[3903]: [2025-12-22 23:09:03.443] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 22 23:09:03 volumiodj systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 22 23:09:03 volumiodj volumio[1381]: info: Checking if uninstall.sh is present Dec 22 23:09:03 volumiodj sudo[3953]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 23:09:03 volumiodj sudo[3953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:03 volumiodj sudo[3955]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 23:09:03 volumiodj sudo[3955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:03 volumiodj systemd[1]: mpd.service: Deactivated successfully. Dec 22 23:09:03 volumiodj volumio[1381]: info: Executing uninstall.sh Dec 22 23:09:03 volumiodj systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 23:09:03 volumiodj systemd[1]: mpd.socket: Deactivated successfully. Dec 22 23:09:03 volumiodj systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 23:09:03 volumiodj systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 23:09:03 volumiodj volumio[1381]: info: MPD Permissions set Dec 22 23:09:03 volumiodj sudo[3964]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 22 23:09:03 volumiodj sudo[3964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj sudo[3967]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/peppy_screensaver/uninstall.sh Dec 22 23:09:03 volumiodj sudo[3967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: Starting Shairport Sync Dec 22 23:09:03 volumiodj sudo[3982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 23:09:03 volumiodj sudo[3982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:03 volumiodj systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 23:09:03 volumiodj systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 23:09:03 volumiodj sudo[3964]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:03 volumiodj systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 23:09:03 volumiodj systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 22 23:09:03 volumiodj systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 23:09:03 volumiodj systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 23:09:03 volumiodj systemd[1]: shairport-sync.service: Consumed 1.530s CPU time. Dec 22 23:09:03 volumiodj systemd[1]: vtcs.service: Deactivated successfully. Dec 22 23:09:03 volumiodj systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 22 23:09:03 volumiodj sudo[3998]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 22 23:09:03 volumiodj sudo[3998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:03 volumiodj sudo[3953]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:03 volumiodj sudo[3955]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:03 volumiodj systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 23:09:03 volumiodj sudo[3996]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 23:09:03 volumiodj sudo[3996]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 23:09:03 volumiodj sudo[3982]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:03 volumiodj sudo[3996]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:03 volumiodj systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 22 23:09:03 volumiodj qobuz-connect[3885]: 20251222 23:09:03.574 [3885.3885] INFO SampleApp: Stopping Local configuration server Dec 22 23:09:03 volumiodj volumio[1381]: info: Shairport-Sync Started Dec 22 23:09:03 volumiodj volumio[1381]: (node:1381) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 22 23:09:03 volumiodj volumio[1381]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 22 23:09:03 volumiodj volumio[1381]: (node:1381) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 22 23:09:03 volumiodj volumio[1381]: (node:1381) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 22 23:09:03 volumiodj volumio[1381]: (node:1381) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 22 23:09:03 volumiodj volumio[1381]: (node:1381) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 22 23:09:03 volumiodj volumio[1381]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 22 23:09:03 volumiodj volumio[1381]: info: CALLMETHOD: music_service mpd createMPDFile Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Dec 22 23:09:03 volumiodj volumio[1381]: error: Failed callmethod call: TypeError: callback is not a function Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj sudo[4024]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 23:09:03 volumiodj sudo[4024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:03 volumiodj sudo[4024]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: MPD Permissions set Dec 22 23:09:03 volumiodj volumio[1381]: info: CALLMETHOD: music_service airplay_emulation outputDeviceCallback Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , outputDeviceCallback Dec 22 23:09:03 volumiodj volumio[1381]: info: Output device has changed, restarting Shairport Sync Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 23:09:03 volumiodj volumio[1381]: info: Starting Shairport Sync Dec 22 23:09:03 volumiodj sudo[4036]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 23:09:03 volumiodj sudo[4036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:03 volumiodj systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 23:09:03 volumiodj systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 23:09:03 volumiodj systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:03 volumiodj systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 23:09:03 volumiodj sudo[4036]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:03 volumiodj volumio[1381]: info: Shairport-Sync Started Dec 22 23:09:04 volumiodj mpd[4004]: 2025-12-22T23:09:04 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 22 23:09:04 volumiodj systemd[1]: Started mpd.service - Music Player Daemon. Dec 22 23:09:04 volumiodj sudo[3943]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:04 volumiodj volumio[1381]: error: updateQueue error: null Dec 22 23:09:04 volumiodj qobuz-connect[3885]: 20251222 23:09:04.585 [3885.3885] INFO SampleApp: shat down connection on UNIX socket Dec 22 23:09:04 volumiodj volumio[1381]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 22 23:09:04 volumiodj volumio[1381]: info: QobuzConnect: setDeactiveState invoked Dec 22 23:09:04 volumiodj volumio[1381]: info: CoreCommandRouter::volumioGetState Dec 22 23:09:04 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:04 volumiodj systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 22 23:09:04 volumiodj systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 23:09:04 volumiodj systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 23:09:04 volumiodj sudo[3998]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:04 volumiodj volumio[1381]: info: Executing endpoint qc_getconfig Dec 22 23:09:04 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 22 23:09:04 volumiodj qobuz-connect[4055]: 20251222 23:09:04.627 [4055.4055] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 22 23:09:04 volumiodj volumio[1381]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 22 23:09:04 volumiodj volumio[1381]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 22 23:09:04 volumiodj qobuz-connect[4055]: 20251222 23:09:04.628 [4055.4055] INFO VolumeManager: [0x758168]: Setting new playback volume: 75 Dec 22 23:09:04 volumiodj qobuz-connect[4055]: 20251222 23:09:04.628 [4055.4055] INFO VolumeManager: [0x758168]: Setting new mute state: 0 Dec 22 23:09:04 volumiodj qobuz-connect[4055]: 20251222 23:09:04.628 [4055.4055] INFO QobuzConnect: [0x758b38]: Client initialized! Dec 22 23:09:04 volumiodj qobuz-connect[4055]: 20251222 23:09:04.628 [4055.4055] INFO SampleApp: Starting Avahi advertising, name: Volumiodj, service name: _qobuz-connect._tcp Dec 22 23:09:04 volumiodj qobuz-connect[4055]: 20251222 23:09:04.636 [4055.4055] INFO LocalConfigManager: [0x757a48]: Starting Local Configuration server Dec 22 23:09:04 volumiodj qobuz-connect[4055]: 20251222 23:09:04.636 [4055.4055] INFO SampleApp: Starting Local configuration server Dec 22 23:09:04 volumiodj qobuz-connect[4055]: 20251222 23:09:04.636 [4055.4055] INFO SampleApp: Connected to UNIX socket client 0x742818 Dec 22 23:09:04 volumiodj qobuz-connect[4055]: 20251222 23:09:04.777 [4055.4055] INFO SampleApp: Playback volume changed: 75 Dec 22 23:09:04 volumiodj volumio[1381]: info: CoreCommandRouter::volumioGetState Dec 22 23:09:04 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:04 volumiodj volumio[1381]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 22 23:09:04 volumiodj volumio[1381]: info: CALLMETHOD: user_interface peppy_screensaver restoreAsoundConfig Dec 22 23:09:04 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , restoreAsoundConfig Dec 22 23:09:05 volumiodj sudo[3967]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:05 volumiodj volumio[1381]: info: Uninstall script completed Dec 22 23:09:05 volumiodj volumio[1381]: info: Removing plugin peppy_screensaver from configuration Dec 22 23:09:06 volumiodj volumio[1381]: info: Successfully removed peppy_screensaver configuration files Dec 22 23:09:06 volumiodj volumio[1381]: info: Plugin folders cleanup Dec 22 23:09:06 volumiodj volumio[1381]: info: Scanning into folder /volumio/app/plugins/ Dec 22 23:09:06 volumiodj volumio[1381]: info: Scanning category audio_interface Dec 22 23:09:06 volumiodj volumio[1381]: info: Scanning category miscellanea Dec 22 23:09:06 volumiodj volumio[1381]: info: Scanning category music_service Dec 22 23:09:06 volumiodj volumio[1381]: info: Scanning category plugins.json Dec 22 23:09:06 volumiodj volumio[1381]: info: Scanning category system_controller Dec 22 23:09:06 volumiodj volumio[1381]: info: Scanning category user_interface Dec 22 23:09:06 volumiodj volumio[1381]: info: Scanning into folder /data/plugins/ Dec 22 23:09:06 volumiodj volumio[1381]: info: Scanning category music_service Dec 22 23:09:06 volumiodj volumio[1381]: info: Scanning category user_interface Dec 22 23:09:06 volumiodj volumio[1381]: info: Cleaning folder for peppy_screensaver Dec 22 23:09:06 volumiodj volumio[1381]: info: Plugin folders cleanup completed Dec 22 23:09:06 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 23:09:06 volumiodj volumio[1381]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.3.0","icon":"fa-spotify","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.6.0","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}] Dec 22 23:09:06 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 22 23:09:06 volumiodj volumio[1381]: info: TidalConnect service stoped! Dec 22 23:09:06 volumiodj volumio[1381]: info: TidalConnect service stoped! Dec 22 23:09:06 volumiodj volumio[1381]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 22 23:09:06 volumiodj volumio[1381]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 22 23:09:06 volumiodj sudo[4096]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 22 23:09:06 volumiodj sudo[4096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:06 volumiodj systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 22 23:09:06 volumiodj sudo[4096]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:06 volumiodj volumio[1381]: info: Executing endpoint tc_getconfig Dec 22 23:09:06 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 22 23:09:06 volumiodj vtcs[4099]: STARTING TidalConnect services, version: 1.5.2.56 Dec 22 23:09:06 volumiodj vtcs[4099]: STARTED TidalConnect services. Dec 22 23:09:06 volumiodj volumio[1381]: info: Executing endpoint tc_connect Dec 22 23:09:06 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 22 23:09:06 volumiodj volumio[1381]: info: Connecting to TidalConnect Dec 22 23:09:06 volumiodj volumio[1381]: info: CoreCommandRouter::servicePushState Dec 22 23:09:06 volumiodj volumio[1381]: info: CoreStateMachine::pushState Dec 22 23:09:06 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:06 volumiodj volumio[1381]: info: CoreCommandRouter::volumioPushState Dec 22 23:09:06 volumiodj volumio[1381]: info: MRS: Pushing multiroomSync output update for this device Dec 22 23:09:06 volumiodj volumio[1381]: info: MRS: Pushing multiroomSync output Dec 22 23:09:06 volumiodj volumio[1381]: info: CoreCommandRouter::volumioGetState Dec 22 23:09:06 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:06 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:06 volumiodj volumio[1381]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 22 23:09:06 volumiodj volumio[1381]: info: CoreCommandRouter::servicePushState Dec 22 23:09:06 volumiodj volumio[1381]: info: CoreStateMachine::pushState Dec 22 23:09:06 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:06 volumiodj volumio[1381]: info: CoreCommandRouter::volumioPushState Dec 22 23:09:06 volumiodj volumio[1381]: info: MRS: Pushing multiroomSync output update for this device Dec 22 23:09:06 volumiodj volumio[1381]: info: MRS: Pushing multiroomSync output Dec 22 23:09:06 volumiodj volumio[1381]: info: CoreCommandRouter::volumioGetState Dec 22 23:09:06 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:06 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:06 volumiodj volumio[1381]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 22 23:09:07 volumiodj sudo[4116]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 23:09:07 volumiodj sudo[4116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:07 volumiodj systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 22 23:09:07 volumiodj systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 22 23:09:07 volumiodj systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 22 23:09:07 volumiodj systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 22 23:09:07 volumiodj mpd_monitor.sh[4118]: MPD Monitor Service: Starting MPD Monitor Service Dec 22 23:09:07 volumiodj sudo[4116]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:07 volumiodj volumio[1381]: info: Successfully started MPD Monitor Dec 22 23:09:09 volumiodj volumio[1381]: info: TidalConnect service started! Dec 22 23:09:16 volumiodj volumio[1381]: info: CoreCommandRouter::volumioGetState Dec 22 23:09:16 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:18 volumiodj volumio[1381]: info: Executing endpoint metavolumio Dec 22 23:09:18 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 22 23:09:18 volumiodj volumio[1381]: info: Executing endpoint metavolumio Dec 22 23:09:18 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 22 23:09:18 volumiodj volumio[1381]: info: Executing endpoint metavolumio Dec 22 23:09:18 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 22 23:09:24 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 23:09:24 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 22 23:09:31 volumiodj volumio[1381]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/peppymeterbasic/volumio/bookworm/armhf Dec 22 23:09:33 volumiodj volumio[1381]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/peppymeterbasic/volumio/bookworm/armhf Dec 22 23:09:33 volumiodj volumio[1381]: info: Folder /tmp/plugins removed Dec 22 23:09:33 volumiodj volumio[1381]: info: Check plugin dependencies Dec 22 23:09:33 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 23:09:33 volumiodj volumio[1381]: info: Checking if plugin already exists Dec 22 23:09:33 volumiodj volumio[1381]: info: Rename folder Dec 22 23:09:33 volumiodj volumio[1381]: info: Folder /tmp/downloaded_plugin.zip removed Dec 22 23:09:33 volumiodj volumio[1381]: info: Move to category Dec 22 23:09:34 volumiodj volumio[1381]: info: Checking if install.sh is present Dec 22 23:09:34 volumiodj volumio[1381]: info: Executing install.sh Dec 22 23:09:34 volumiodj sudo[4188]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/peppymeterbasic/install.sh Dec 22 23:09:34 volumiodj sudo[4188]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:34 volumiodj volumio[1381]: info: Installing peppyalsa plugin dependencies Dec 22 23:09:34 volumiodj sudo[4192]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get update Dec 22 23:09:34 volumiodj sudo[4192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 23:09:35 volumiodj volumio[1381]: info: Get:1 http://raspbian.raspberrypi.com/raspbian bookworm InRelease [15.0 kB] Dec 22 23:09:35 volumiodj volumio[1381]: info: Hit:2 https://deb.nodesource.com/node_20.x nodistro InRelease Dec 22 23:09:35 volumiodj volumio[1381]: info: Hit:3 http://archive.raspberrypi.com/debian bookworm InRelease Dec 22 23:09:35 volumiodj volumio[1381]: info: Get:4 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf Packages [14.5 MB] Dec 22 23:09:40 volumiodj volumio[1381]: info: Fetched 14.5 MB in 5s (2743 kB/s) Dec 22 23:09:41 volumiodj volumio[1381]: info: Reading package lists... Dec 22 23:09:41 volumiodj sudo[4192]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:41 volumiodj volumio[1381]: info: cloning peppymeter repo Dec 22 23:09:47 volumiodj sudo[4395]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chown -R volumio /data/plugins/user_interface/peppymeterbasic /data/INTERNAL/PeppyMeterBasic/Templates Dec 22 23:09:47 volumiodj sudo[4395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 23:09:47 volumiodj sudo[4395]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:47 volumiodj sudo[4400]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chgrp -R volumio /data/plugins/user_interface/peppymeterbasic /data/INTERNAL/PeppyMeterBasic/Templates Dec 22 23:09:47 volumiodj sudo[4400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 23:09:47 volumiodj sudo[4400]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:47 volumiodj volumio[1381]: info: installing apt packages Dec 22 23:09:47 volumiodj sudo[4402]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get -yf install python3-pygame python3-pip python3-dev libjpeg-dev zlib1g-dev libfftw3-dev Dec 22 23:09:47 volumiodj sudo[4402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 23:09:47 volumiodj volumio[1381]: info: Reading package lists... Dec 22 23:09:47 volumiodj volumio[1381]: info: Building dependency tree... Dec 22 23:09:47 volumiodj volumio[1381]: info: Reading state information... Dec 22 23:09:48 volumiodj volumio[1381]: info: python3-pygame is already the newest version (2.1.2+dfsg-5). Dec 22 23:09:48 volumiodj sudo[4402]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:48 volumiodj volumio[1381]: info: python3-pip is already the newest version (23.0.1+dfsg-1+rpt1). Dec 22 23:09:48 volumiodj volumio[1381]: info: python3-dev is already the newest version (3.11.2-1). Dec 22 23:09:48 volumiodj volumio[1381]: info: libjpeg-dev is already the newest version (1:2.1.5-2). Dec 22 23:09:48 volumiodj volumio[1381]: info: zlib1g-dev is already the newest version (1:1.2.13.dfsg-1+rpt1). Dec 22 23:09:48 volumiodj volumio[1381]: info: libfftw3-dev is already the newest version (3.3.10-1). Dec 22 23:09:48 volumiodj volumio[1381]: info: 0 upgraded, 0 newly installed, 0 to remove and 13 not upgraded. Dec 22 23:09:48 volumiodj sudo[4408]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Dec 22 23:09:48 volumiodj sudo[4408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 23:09:48 volumiodj systemd[1]: Reloading. Dec 22 23:09:48 volumiodj systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 22 23:09:48 volumiodj systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 22 23:09:48 volumiodj sudo[4408]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:48 volumiodj volumio[1381]: info: Linked /data/plugins/user_interface/peppymeterbasic/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppymeterbasic/alsa-lib/libpeppyalsa.so Dec 22 23:09:48 volumiodj volumio[1381]: info: Linked /data/plugins/user_interface/peppymeterbasic/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppymeterbasic/alsa-lib/libpeppyalsa.so.0 Dec 22 23:09:49 volumiodj sudo[4431]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/user_interface/peppymeterbasic/startpeppymeterbasic.sh Dec 22 23:09:49 volumiodj sudo[4431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 23:09:49 volumiodj sudo[4431]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:49 volumiodj sudo[4188]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:49 volumiodj volumio[1381]: Plugin install end detected on script Dec 22 23:09:49 volumiodj volumio[1381]: info: Install script completed Dec 22 23:09:49 volumiodj volumio[1381]: info: Adding reference to registry Dec 22 23:09:49 volumiodj volumio[1381]: info: Done installing plugin. Dec 22 23:09:49 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 23:09:49 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 22 23:09:49 volumiodj volumio[1381]: info: Folder /tmp/plugins removed Dec 22 23:09:49 volumiodj volumio[1381]: info: Folder /tmp/downloaded_plugin.zip removed Dec 22 23:09:49 volumiodj volumio[1381]: info: Folder /data/temp removed Dec 22 23:09:51 volumiodj volumio[1381]: info: Enabling plugin peppymeterbasic Dec 22 23:09:51 volumiodj volumio[1381]: info: Loading plugin "peppymeterbasic"... Dec 22 23:09:51 volumiodj volumio[1381]: info: Preparing to generate the ALSA configuration file Dec 22 23:09:51 volumiodj volumio[1381]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 22 23:09:51 volumiodj volumio[1381]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 22 23:09:51 volumiodj volumio[1381]: info: Reading ALSA contributions from plugins. Dec 22 23:09:51 volumiodj volumio[1381]: info: Asound.conf file written Dec 22 23:09:51 volumiodj sudo[4450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 22 23:09:51 volumiodj sudo[4450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:51 volumiodj sudo[4450]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:51 volumiodj volumio[1381]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 22 23:09:51 volumiodj volumio[1381]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 22 23:09:51 volumiodj volumio[1381]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Dec 22 23:09:51 volumiodj volumio[1381]: No state is present for card Dummy Dec 22 23:09:51 volumiodj volumio[1381]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Dec 22 23:09:51 volumiodj volumio[1381]: Found hardware: "Dummy" "Dummy Mixer" "" "" "" Dec 22 23:09:51 volumiodj volumio[1381]: Hardware is initialized using a generic method Dec 22 23:09:51 volumiodj volumio[1381]: No state is present for card Dummy Dec 22 23:09:51 volumiodj volumio[1381]: info: Output device has changed, restarting MPD Dec 22 23:09:51 volumiodj volumio[1381]: info: Output device has changed, restarting Shairport Sync Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 23:09:51 volumiodj sudo[4456]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 23:09:51 volumiodj sudo[4456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:51 volumiodj sudo[4458]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 23:09:51 volumiodj sudo[4458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:51 volumiodj sudo[4456]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:51 volumiodj volumio[1381]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 23:09:51 volumiodj volumio[1381]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: QobuzConnect: setDeactiveState invoked Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::volumioGetState Dec 22 23:09:51 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:51 volumiodj vtcs[4099]: [2025-12-22 23:09:51.529] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 22 23:09:51 volumiodj vtcs[4099]: [2025-12-22 23:09:51.530] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 22 23:09:51 volumiodj systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 22 23:09:51 volumiodj volumio[1381]: info: PLUGIN START: peppymeterbasic Dec 22 23:09:51 volumiodj sudo[4470]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 23:09:51 volumiodj sudo[4470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 22 23:09:51 volumiodj volumio[1381]: info: Preparing to generate the ALSA configuration file Dec 22 23:09:51 volumiodj systemd[1]: mpd.service: Deactivated successfully. Dec 22 23:09:51 volumiodj systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 23:09:51 volumiodj volumio[1381]: info: Done. Dec 22 23:09:51 volumiodj sudo[4473]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 Dec 22 23:09:51 volumiodj systemd[1]: mpd.socket: Deactivated successfully. Dec 22 23:09:51 volumiodj sudo[4473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:51 volumiodj systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 23:09:51 volumiodj systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 23:09:51 volumiodj kernel: snd_aloop snd_aloop.0: cannot find the slot for index 7 (range 0-7), error: -16 Dec 22 23:09:51 volumiodj kernel: snd_aloop snd_aloop.0: probe with driver snd_aloop failed with error -16 Dec 22 23:09:51 volumiodj kernel: aloop: No loopback enabled Dec 22 23:09:51 volumiodj sudo[4477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 23:09:51 volumiodj sudo[4477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:51 volumiodj volumio[1381]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 22 23:09:51 volumiodj volumio[1381]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 22 23:09:51 volumiodj volumio[1381]: info: Reading ALSA contributions from plugins. Dec 22 23:09:51 volumiodj volumio[1381]: info: MPD Permissions set Dec 22 23:09:51 volumiodj sudo[4486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 22 23:09:51 volumiodj sudo[4486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:51 volumiodj volumio[1381]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 22 23:09:51 volumiodj systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 23:09:51 volumiodj systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 23:09:51 volumiodj systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj systemd[1]: vtcs.service: Deactivated successfully. Dec 22 23:09:51 volumiodj systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: Starting Shairport Sync Dec 22 23:09:51 volumiodj volumio[1381]: info: Asound.conf file unchanged, so no further update is needed Dec 22 23:09:51 volumiodj volumio[1381]: info: Output device has changed, restarting MPD Dec 22 23:09:51 volumiodj sudo[4470]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:51 volumiodj sudo[4486]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:51 volumiodj sudo[4477]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:51 volumiodj sudo[4496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 23:09:51 volumiodj sudo[4473]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:51 volumiodj sudo[4498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 22 23:09:51 volumiodj sudo[4498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:51 volumiodj sudo[4496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:51 volumiodj volumio[1381]: info: Output device has changed, restarting Shairport Sync Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 23:09:51 volumiodj sudo[4488]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 23:09:51 volumiodj sudo[4488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 23:09:51 volumiodj sudo[4501]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 23:09:51 volumiodj sudo[4501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:51 volumiodj sudo[4488]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:51 volumiodj sudo[4501]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:51 volumiodj sudo[4503]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 23:09:51 volumiodj sudo[4503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:51 volumiodj volumio[1381]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 23:09:51 volumiodj volumio[1381]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: QobuzConnect: setDeactiveState invoked Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::volumioGetState Dec 22 23:09:51 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:51 volumiodj sudo[4517]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 23:09:51 volumiodj sudo[4517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:51 volumiodj sudo[4521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 23:09:51 volumiodj sudo[4521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:51 volumiodj qobuz-connect[4055]: 20251222 23:09:51.676 [4055.4055] INFO SampleApp: Stopping Local configuration server Dec 22 23:09:51 volumiodj systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 22 23:09:51 volumiodj systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 23:09:51 volumiodj systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 23:09:51 volumiodj systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 23:09:51 volumiodj systemd[1]: shairport-sync.service: Consumed 1.583s CPU time. Dec 22 23:09:51 volumiodj systemd[1]: mpd.service: Deactivated successfully. Dec 22 23:09:51 volumiodj systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 23:09:51 volumiodj volumio[1381]: info: MPD Permissions set Dec 22 23:09:51 volumiodj volumio[1381]: error: failed to load snd_dummy: Error: Command failed: /usr/bin/sudo /sbin/modprobe snd_aloop index=7 pcm_substreams=2 Dec 22 23:09:51 volumiodj volumio[1381]: modprobe: ERROR: could not insert 'snd_aloop': No such device Dec 22 23:09:51 volumiodj sudo[4530]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 22 23:09:51 volumiodj sudo[4530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 23:09:51 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:51 volumiodj systemd[1]: mpd.socket: Deactivated successfully. Dec 22 23:09:51 volumiodj sudo[4530]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:51 volumiodj systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 23:09:51 volumiodj systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 23:09:51 volumiodj volumio[1381]: info: Starting Shairport Sync Dec 22 23:09:51 volumiodj sudo[4538]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 22 23:09:51 volumiodj sudo[4538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:51 volumiodj sudo[4541]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 23:09:51 volumiodj sudo[4541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:51 volumiodj systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 23:09:51 volumiodj systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 23:09:51 volumiodj systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 23:09:51 volumiodj sudo[4496]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:51 volumiodj volumio[1381]: info: Shairport-Sync Started Dec 22 23:09:51 volumiodj sudo[4521]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:51 volumiodj sudo[4517]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:51 volumiodj sudo[4544]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 23:09:51 volumiodj sudo[4544]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 23:09:51 volumiodj systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 23:09:51 volumiodj systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 23:09:51 volumiodj systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 23:09:51 volumiodj sudo[4544]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:51 volumiodj systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 23:09:51 volumiodj sudo[4541]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:51 volumiodj volumio[1381]: info: Shairport-Sync Started Dec 22 23:09:52 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:52 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:52 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:52 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:52 volumiodj mpd[4563]: 2025-12-22T23:09:52 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 22 23:09:52 volumiodj systemd[1]: Started mpd.service - Music Player Daemon. Dec 22 23:09:52 volumiodj sudo[4458]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:52 volumiodj sudo[4503]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:52 volumiodj volumio[1381]: error: MPD error: The expression evaluated to a falsy value: Dec 22 23:09:52 volumiodj volumio[1381]: assert.ok(self.idling) Dec 22 23:09:52 volumiodj volumio[1381]: error: The expression evaluated to a falsy value: Dec 22 23:09:52 volumiodj volumio[1381]: assert.ok(self.idling) Dec 22 23:09:52 volumiodj volumio[1381]: error: updateQueue error: null Dec 22 23:09:52 volumiodj qobuz-connect[4055]: 20251222 23:09:52.689 [4055.4055] INFO SampleApp: shat down connection on UNIX socket Dec 22 23:09:52 volumiodj volumio[1381]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 22 23:09:52 volumiodj volumio[1381]: info: QobuzConnect: setDeactiveState invoked Dec 22 23:09:52 volumiodj volumio[1381]: info: CoreCommandRouter::volumioGetState Dec 22 23:09:52 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:52 volumiodj systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 22 23:09:52 volumiodj systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 23:09:52 volumiodj systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 23:09:52 volumiodj sudo[4498]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:52 volumiodj sudo[4538]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:52 volumiodj volumio[1381]: info: Executing endpoint qc_getconfig Dec 22 23:09:52 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 22 23:09:52 volumiodj qobuz-connect[4582]: 20251222 23:09:52.747 [4582.4582] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 22 23:09:52 volumiodj qobuz-connect[4582]: 20251222 23:09:52.749 [4582.4582] INFO VolumeManager: [0x13e5168]: Setting new playback volume: 75 Dec 22 23:09:52 volumiodj qobuz-connect[4582]: 20251222 23:09:52.749 [4582.4582] INFO VolumeManager: [0x13e5168]: Setting new mute state: 0 Dec 22 23:09:52 volumiodj qobuz-connect[4582]: 20251222 23:09:52.749 [4582.4582] INFO QobuzConnect: [0x13e5b38]: Client initialized! Dec 22 23:09:52 volumiodj qobuz-connect[4582]: 20251222 23:09:52.749 [4582.4582] INFO SampleApp: Starting Avahi advertising, name: Volumiodj, service name: _qobuz-connect._tcp Dec 22 23:09:52 volumiodj volumio[1381]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 22 23:09:52 volumiodj volumio[1381]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 22 23:09:52 volumiodj qobuz-connect[4582]: 20251222 23:09:52.756 [4582.4582] INFO LocalConfigManager: [0x13e4a48]: Starting Local Configuration server Dec 22 23:09:52 volumiodj qobuz-connect[4582]: 20251222 23:09:52.756 [4582.4582] INFO SampleApp: Starting Local configuration server Dec 22 23:09:52 volumiodj qobuz-connect[4582]: 20251222 23:09:52.757 [4582.4582] INFO SampleApp: Connected to UNIX socket client 0x13cf818 Dec 22 23:09:52 volumiodj qobuz-connect[4582]: 20251222 23:09:52.897 [4582.4582] INFO SampleApp: Playback volume changed: 75 Dec 22 23:09:52 volumiodj volumio[1381]: info: CoreCommandRouter::volumioGetState Dec 22 23:09:52 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:53 volumiodj volumio[1381]: info: Enabling plugin peppymeterbasic Dec 22 23:09:53 volumiodj volumio[1381]: info: Loading plugin "peppymeterbasic"... Dec 22 23:09:53 volumiodj volumio[1381]: info: Preparing to generate the ALSA configuration file Dec 22 23:09:53 volumiodj volumio[1381]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 22 23:09:53 volumiodj volumio[1381]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 22 23:09:53 volumiodj volumio[1381]: info: Reading ALSA contributions from plugins. Dec 22 23:09:53 volumiodj volumio[1381]: info: Asound.conf file unchanged, so no further update is needed Dec 22 23:09:53 volumiodj volumio[1381]: info: Output device has changed, restarting MPD Dec 22 23:09:53 volumiodj volumio[1381]: info: Output device has changed, restarting Shairport Sync Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 23:09:53 volumiodj sudo[4590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 23:09:53 volumiodj sudo[4590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:53 volumiodj sudo[4590]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:53 volumiodj sudo[4593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 23:09:53 volumiodj sudo[4593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:53 volumiodj volumio[1381]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 23:09:53 volumiodj volumio[1381]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: QobuzConnect: setDeactiveState invoked Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::volumioGetState Dec 22 23:09:53 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:53 volumiodj systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 22 23:09:53 volumiodj volumio[1381]: info: PLUGIN START: peppymeterbasic Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 22 23:09:53 volumiodj sudo[4602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 23:09:53 volumiodj sudo[4602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:53 volumiodj volumio[1381]: info: Preparing to generate the ALSA configuration file Dec 22 23:09:53 volumiodj sudo[4604]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 Dec 22 23:09:53 volumiodj sudo[4604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:53 volumiodj volumio[1381]: info: Done. Dec 22 23:09:53 volumiodj kernel: snd_aloop snd_aloop.0: cannot find the slot for index 7 (range 0-7), error: -16 Dec 22 23:09:53 volumiodj kernel: snd_aloop snd_aloop.0: probe with driver snd_aloop failed with error -16 Dec 22 23:09:53 volumiodj kernel: aloop: No loopback enabled Dec 22 23:09:53 volumiodj sudo[4608]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 23:09:53 volumiodj sudo[4608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:53 volumiodj systemd[1]: mpd.service: Deactivated successfully. Dec 22 23:09:53 volumiodj systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 23:09:53 volumiodj volumio[1381]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 22 23:09:53 volumiodj sudo[4602]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:53 volumiodj systemd[1]: mpd.socket: Deactivated successfully. Dec 22 23:09:53 volumiodj systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 23:09:53 volumiodj systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 23:09:53 volumiodj volumio[1381]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 22 23:09:53 volumiodj volumio[1381]: info: Reading ALSA contributions from plugins. Dec 22 23:09:53 volumiodj sudo[4615]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 22 23:09:53 volumiodj sudo[4615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:53 volumiodj volumio[1381]: info: MPD Permissions set Dec 22 23:09:53 volumiodj volumio[1381]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: Starting Shairport Sync Dec 22 23:09:53 volumiodj systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 23:09:53 volumiodj systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 23:09:53 volumiodj volumio[1381]: info: Asound.conf file unchanged, so no further update is needed Dec 22 23:09:53 volumiodj volumio[1381]: info: Output device has changed, restarting MPD Dec 22 23:09:53 volumiodj sudo[4626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 23:09:53 volumiodj sudo[4626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:53 volumiodj sudo[4630]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 23:09:53 volumiodj sudo[4615]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:53 volumiodj sudo[4630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:53 volumiodj sudo[4630]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:53 volumiodj sudo[4632]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 22 23:09:53 volumiodj sudo[4632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:53 volumiodj systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 23:09:53 volumiodj sudo[4608]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:53 volumiodj systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 23:09:53 volumiodj systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 23:09:53 volumiodj systemd[1]: shairport-sync.service: Consumed 1.488s CPU time. Dec 22 23:09:53 volumiodj sudo[4604]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:53 volumiodj sudo[4627]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 23:09:53 volumiodj sudo[4627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 23:09:53 volumiodj volumio[1381]: info: Output device has changed, restarting Shairport Sync Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 23:09:53 volumiodj sudo[4627]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:53 volumiodj sudo[4635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 23:09:53 volumiodj sudo[4635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:53 volumiodj volumio[1381]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 23:09:53 volumiodj volumio[1381]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: QobuzConnect: setDeactiveState invoked Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::volumioGetState Dec 22 23:09:53 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:53 volumiodj systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 23:09:53 volumiodj sudo[4647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 23:09:53 volumiodj sudo[4647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:53 volumiodj sudo[4626]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:53 volumiodj sudo[4655]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 23:09:53 volumiodj sudo[4655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:53 volumiodj systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 22 23:09:53 volumiodj qobuz-connect[4582]: 20251222 23:09:53.796 [4582.4582] INFO SampleApp: Stopping Local configuration server Dec 22 23:09:53 volumiodj systemd[1]: mpd.service: Deactivated successfully. Dec 22 23:09:53 volumiodj systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 23:09:53 volumiodj sudo[4676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 22 23:09:53 volumiodj volumio[1381]: info: MPD Permissions set Dec 22 23:09:53 volumiodj volumio[1381]: info: Shairport-Sync Started Dec 22 23:09:53 volumiodj volumio[1381]: error: failed to load snd_dummy: Error: Command failed: /usr/bin/sudo /sbin/modprobe snd_aloop index=7 pcm_substreams=2 Dec 22 23:09:53 volumiodj volumio[1381]: modprobe: ERROR: could not insert 'snd_aloop': No such device Dec 22 23:09:53 volumiodj systemd[1]: mpd.socket: Deactivated successfully. Dec 22 23:09:53 volumiodj systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 23:09:53 volumiodj systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj sudo[4676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:53 volumiodj volumio[1381]: info: Starting Shairport Sync Dec 22 23:09:53 volumiodj systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 23:09:53 volumiodj systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 23:09:53 volumiodj sudo[4685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 23:09:53 volumiodj sudo[4685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:53 volumiodj sudo[4647]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:53 volumiodj sudo[4676]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:53 volumiodj systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 23:09:53 volumiodj systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 23:09:53 volumiodj systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 23:09:53 volumiodj sudo[4689]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 22 23:09:53 volumiodj sudo[4655]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:53 volumiodj sudo[4689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:53 volumiodj sudo[4686]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 23:09:53 volumiodj sudo[4686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 23:09:53 volumiodj systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 23:09:53 volumiodj sudo[4686]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:53 volumiodj sudo[4685]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:53 volumiodj volumio[1381]: info: Shairport-Sync Started Dec 22 23:09:54 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:54 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:54 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:54 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 23:09:54 volumiodj mpd[4694]: 2025-12-22T23:09:54 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 22 23:09:54 volumiodj systemd[1]: Started mpd.service - Music Player Daemon. Dec 22 23:09:54 volumiodj sudo[4635]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:54 volumiodj sudo[4593]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:54 volumiodj volumio[1381]: error: MPD error: The expression evaluated to a falsy value: Dec 22 23:09:54 volumiodj volumio[1381]: assert.ok(self.idling) Dec 22 23:09:54 volumiodj volumio[1381]: error: The expression evaluated to a falsy value: Dec 22 23:09:54 volumiodj volumio[1381]: assert.ok(self.idling) Dec 22 23:09:54 volumiodj volumio[1381]: error: updateQueue error: null Dec 22 23:09:54 volumiodj volumio[1381]: info: TidalConnect service stoped! Dec 22 23:09:54 volumiodj volumio[1381]: info: TidalConnect service stoped! Dec 22 23:09:54 volumiodj volumio[1381]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 22 23:09:54 volumiodj volumio[1381]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 22 23:09:54 volumiodj sudo[4720]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 22 23:09:54 volumiodj sudo[4720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:54 volumiodj systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 22 23:09:54 volumiodj sudo[4720]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:54 volumiodj qobuz-connect[4582]: 20251222 23:09:54.760 [4582.4582] INFO SampleApp: shat down connection on UNIX socket Dec 22 23:09:54 volumiodj volumio[1381]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 22 23:09:54 volumiodj volumio[1381]: info: QobuzConnect: setDeactiveState invoked Dec 22 23:09:54 volumiodj volumio[1381]: info: CoreCommandRouter::volumioGetState Dec 22 23:09:54 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:54 volumiodj systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 22 23:09:54 volumiodj systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 23:09:54 volumiodj volumio[1381]: info: Executing endpoint tc_getconfig Dec 22 23:09:54 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 22 23:09:54 volumiodj vtcs[4723]: STARTING TidalConnect services, version: 1.5.2.56 Dec 22 23:09:54 volumiodj vtcs[4723]: STARTED TidalConnect services. Dec 22 23:09:54 volumiodj volumio[1381]: info: Executing endpoint tc_connect Dec 22 23:09:54 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 22 23:09:54 volumiodj volumio[1381]: info: Connecting to TidalConnect Dec 22 23:09:54 volumiodj volumio[1381]: info: CoreCommandRouter::servicePushState Dec 22 23:09:54 volumiodj volumio[1381]: info: CoreStateMachine::pushState Dec 22 23:09:54 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:54 volumiodj volumio[1381]: info: CoreCommandRouter::volumioPushState Dec 22 23:09:54 volumiodj volumio[1381]: info: MRS: Pushing multiroomSync output update for this device Dec 22 23:09:54 volumiodj volumio[1381]: info: MRS: Pushing multiroomSync output Dec 22 23:09:54 volumiodj volumio[1381]: info: CoreCommandRouter::volumioGetState Dec 22 23:09:54 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:54 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:54 volumiodj volumio[1381]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 22 23:09:54 volumiodj volumio[1381]: info: CoreCommandRouter::servicePushState Dec 22 23:09:54 volumiodj volumio[1381]: info: CoreStateMachine::pushState Dec 22 23:09:54 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:54 volumiodj volumio[1381]: info: CoreCommandRouter::volumioPushState Dec 22 23:09:54 volumiodj volumio[1381]: info: MRS: Pushing multiroomSync output update for this device Dec 22 23:09:54 volumiodj volumio[1381]: info: MRS: Pushing multiroomSync output Dec 22 23:09:54 volumiodj volumio[1381]: info: CoreCommandRouter::volumioGetState Dec 22 23:09:54 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:54 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:54 volumiodj volumio[1381]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Dec 22 23:09:54 volumiodj systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 23:09:54 volumiodj sudo[4632]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:54 volumiodj sudo[4689]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:54 volumiodj volumio[1381]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 22 23:09:54 volumiodj volumio[1381]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 22 23:09:54 volumiodj sudo[4741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 22 23:09:54 volumiodj sudo[4741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:54 volumiodj volumio[1381]: info: TidalConnect service stoped! Dec 22 23:09:54 volumiodj volumio[1381]: info: TidalConnect service stoped! Dec 22 23:09:54 volumiodj sudo[4743]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 22 23:09:54 volumiodj sudo[4743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:54 volumiodj volumio[1381]: info: Executing endpoint qc_getconfig Dec 22 23:09:54 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 22 23:09:54 volumiodj qobuz-connect[4725]: 20251222 23:09:54.838 [4725.4725] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 22 23:09:54 volumiodj qobuz-connect[4725]: 20251222 23:09:54.839 [4725.4725] INFO VolumeManager: [0x29f168]: Setting new playback volume: 75 Dec 22 23:09:54 volumiodj qobuz-connect[4725]: 20251222 23:09:54.839 [4725.4725] INFO VolumeManager: [0x29f168]: Setting new mute state: 0 Dec 22 23:09:54 volumiodj qobuz-connect[4725]: 20251222 23:09:54.839 [4725.4725] INFO QobuzConnect: [0x29fb38]: Client initialized! Dec 22 23:09:54 volumiodj qobuz-connect[4725]: 20251222 23:09:54.839 [4725.4725] INFO SampleApp: Starting Avahi advertising, name: Volumiodj, service name: _qobuz-connect._tcp Dec 22 23:09:54 volumiodj volumio[1381]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 22 23:09:54 volumiodj volumio[1381]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 22 23:09:54 volumiodj qobuz-connect[4725]: 20251222 23:09:54.851 [4725.4725] INFO LocalConfigManager: [0x29ea48]: Starting Local Configuration server Dec 22 23:09:54 volumiodj qobuz-connect[4725]: 20251222 23:09:54.851 [4725.4725] INFO SampleApp: Starting Local configuration server Dec 22 23:09:54 volumiodj qobuz-connect[4725]: 20251222 23:09:54.852 [4725.4725] INFO SampleApp: Connected to UNIX socket client 0x289818 Dec 22 23:09:54 volumiodj sudo[4743]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:54 volumiodj volumio[1381]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 22 23:09:54 volumiodj volumio[1381]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 22 23:09:54 volumiodj volumio[1381]: info: peppymeterbasic Daemon Stop Dec 22 23:09:54 volumiodj sudo[4741]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:54 volumiodj volumio[1381]: info: peppymeterbasic Daemon Stop Dec 22 23:09:54 volumiodj sudo[4760]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 22 23:09:54 volumiodj sudo[4760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:54 volumiodj sudo[4760]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:54 volumiodj qobuz-connect[4725]: 20251222 23:09:54.988 [4725.4725] INFO SampleApp: Playback volume changed: 75 Dec 22 23:09:54 volumiodj volumio[1381]: info: CoreCommandRouter::volumioGetState Dec 22 23:09:54 volumiodj volumio[1381]: info: CorePlayQueue::getTrack 2 Dec 22 23:09:55 volumiodj sudo[4764]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 23:09:55 volumiodj sudo[4764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:55 volumiodj sudo[4766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 23:09:55 volumiodj sudo[4766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:55 volumiodj systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 22 23:09:55 volumiodj systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 22 23:09:55 volumiodj systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 22 23:09:55 volumiodj systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 22 23:09:55 volumiodj mpd_monitor.sh[4769]: MPD Monitor Service: Starting MPD Monitor Service Dec 22 23:09:55 volumiodj sudo[4766]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:55 volumiodj sudo[4764]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:55 volumiodj volumio[1381]: info: Successfully started MPD Monitor Dec 22 23:09:55 volumiodj volumio[1381]: info: Successfully started MPD Monitor Dec 22 23:09:56 volumiodj volumio[1381]: info: TidalConnect service stoped! Dec 22 23:09:56 volumiodj volumio[1381]: info: TidalConnect service stoped! Dec 22 23:09:56 volumiodj volumio[1381]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 22 23:09:56 volumiodj volumio[1381]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 22 23:09:56 volumiodj sudo[4782]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 22 23:09:56 volumiodj sudo[4782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:56 volumiodj sudo[4782]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:56 volumiodj volumio[1381]: info: TidalConnect service stoped! Dec 22 23:09:56 volumiodj volumio[1381]: info: TidalConnect service stoped! Dec 22 23:09:56 volumiodj volumio[1381]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 22 23:09:56 volumiodj volumio[1381]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 22 23:09:56 volumiodj sudo[4794]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 22 23:09:56 volumiodj sudo[4794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:56 volumiodj sudo[4794]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:57 volumiodj sudo[4798]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 23:09:57 volumiodj sudo[4798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:57 volumiodj sudo[4800]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 23:09:57 volumiodj sudo[4800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 23:09:57 volumiodj systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 22 23:09:57 volumiodj systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 22 23:09:57 volumiodj systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 22 23:09:57 volumiodj systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 22 23:09:57 volumiodj mpd_monitor.sh[4803]: MPD Monitor Service: Starting MPD Monitor Service Dec 22 23:09:57 volumiodj sudo[4800]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:57 volumiodj sudo[4798]: pam_unix(sudo:session): session closed for user root Dec 22 23:09:57 volumiodj volumio[1381]: info: Successfully started MPD Monitor Dec 22 23:09:57 volumiodj volumio[1381]: info: Successfully started MPD Monitor Dec 22 23:09:57 volumiodj volumio[1381]: info: TidalConnect service started! Dec 22 23:09:57 volumiodj volumio[1381]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 23:09:57 volumiodj volumio[1381]: info: TidalConnect service started! Dec 22 23:09:59 volumiodj volumio[1381]: info: TidalConnect service started! Dec 22 23:09:59 volumiodj volumio[1381]: info: TidalConnect service started! Dec 22 23:10:08 volumiodj volumio[1381]: info: CALLMETHOD: user_interface peppymeterbasic dlmeter [object Object] Dec 22 23:10:08 volumiodj volumio[1381]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , dlmeter Dec 22 23:10:27 volumiodj volumio[1381]: --2025-12-22 23:10:08-- https://github.com/balbuze/Meter-peppymeter/raw/main/Zipped-folders/Choose Dec 22 23:10:27 volumiodj volumio[1381]: Resolving github.com (github.com)... 140.82.114.3, 140.82.113.4, 140.82.113.3, ... Dec 22 23:10:27 volumiodj volumio[1381]: Connecting to github.com (github.com)|140.82.114.3|:443... connected. Dec 22 23:10:27 volumiodj volumio[1381]: HTTP request sent, awaiting response... Read error (The request is invalid.) in headers. Dec 22 23:10:27 volumiodj volumio[1381]: Retrying. Dec 22 23:10:27 volumiodj volumio[1381]: --2025-12-22 23:10:12-- (try: 2) https://github.com/balbuze/Meter-peppymeter/raw/main/Zipped-folders/Choose Dec 22 23:10:27 volumiodj volumio[1381]: Connecting to github.com (github.com)|140.82.114.3|:443... connected. Dec 22 23:10:27 volumiodj volumio[1381]: HTTP request sent, awaiting response... Read error (The request is invalid.) in headers. Dec 22 23:10:27 volumiodj volumio[1381]: Retrying. Dec 22 23:10:27 volumiodj volumio[1381]: --2025-12-22 23:10:16-- (try: 3) https://github.com/balbuze/Meter-peppymeter/raw/main/Zipped-folders/Choose Dec 22 23:10:27 volumiodj volumio[1381]: Connecting to github.com (github.com)|140.82.114.3|:443... connected. Dec 22 23:10:27 volumiodj volumio[1381]: HTTP request sent, awaiting response... 404 Not Found Dec 22 23:10:27 volumiodj volumio[1381]: 2025-12-22 23:10:26 ERROR 404: Not Found. Dec 22 23:10:27 volumiodj volumio[1381]: --2025-12-22 23:10:26-- http://a/ Dec 22 23:10:27 volumiodj volumio[1381]: Resolving a (a)... failed: Device or resource busy. Dec 22 23:10:27 volumiodj volumio[1381]: wget: unable to resolve host address ‘a’ Dec 22 23:10:27 volumiodj volumio[1381]: --2025-12-22 23:10:26-- http://packet.zip/ Dec 22 23:10:27 volumiodj volumio[1381]: Resolving packet.zip (packet.zip)... 162.255.119.167 Dec 22 23:10:27 volumiodj volumio[1381]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Dec 22 23:10:27 volumiodj volumio[1381]: HTTP request sent, awaiting response... 302 Found Dec 22 23:10:27 volumiodj volumio[1381]: Location: http://www.packet.zip/ [following] Dec 22 23:10:27 volumiodj volumio[1381]: --2025-12-22 23:10:27-- http://www.packet.zip/ Dec 22 23:10:27 volumiodj volumio[1381]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Dec 22 23:10:27 volumiodj volumio[1381]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Dec 22 23:10:27 volumiodj volumio[1381]: HTTP request sent, awaiting response... 403 Forbidden Dec 22 23:10:27 volumiodj volumio[1381]: 2025-12-22 23:10:27 ERROR 403: Forbidden. Dec 22 23:10:27 volumiodj volumio[1381]: error: PeppyMeterBasic --- An error occurs while downloading or installing Meters Dec 22 23:10:27 volumiodj volumio[1381]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 22 23:10:27 volumiodj volumio[1381]: /bin/rm: cannot remove 'a': No such file or directory Dec 22 23:10:27 volumiodj volumio[1381]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 22 23:10:27 volumiodj volumio[1381]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 23:10:27 volumiodj volumio[1381]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Dec 22 23:10:27 volumiodj volumio[1381]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 22 23:10:27 volumiodj volumio[1381]: /bin/rm: cannot remove 'a': No such file or directory Dec 22 23:10:27 volumiodj volumio[1381]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 22 23:10:27 volumiodj volumio[1381]: at checkExecSyncError (node:child_process:887:11) Dec 22 23:10:27 volumiodj volumio[1381]: at execSync (node:child_process:959:15) Dec 22 23:10:27 volumiodj volumio[1381]: at /data/plugins/user_interface/peppymeterbasic/index.js:710:19 Dec 22 23:10:27 volumiodj volumio[1381]: at new Promise () Dec 22 23:10:27 volumiodj volumio[1381]: at peppymeterbasic.dlmeter (/data/plugins/user_interface/peppymeterbasic/index.js:685:12) Dec 22 23:10:27 volumiodj volumio[1381]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Dec 22 23:10:27 volumiodj volumio[1381]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Dec 22 23:10:27 volumiodj volumio[1381]: at Socket.emit (node:events:514:28) Dec 22 23:10:27 volumiodj volumio[1381]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 22 23:10:27 volumiodj volumio[1381]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Dec 22 23:10:27 volumiodj volumio[1381]: status: 1, Dec 22 23:10:27 volumiodj volumio[1381]: signal: null, Dec 22 23:10:27 volumiodj volumio[1381]: output: [ Dec 22 23:10:27 volumiodj volumio[1381]: null, Dec 22 23:10:27 volumiodj volumio[1381]: , Dec 22 23:10:27 volumiodj volumio[1381]: Dec 22 23:10:27 volumiodj volumio[1381]: ], Dec 22 23:10:27 volumiodj volumio[1381]: pid: 4859, Dec 22 23:10:27 volumiodj volumio[1381]: stdout: , Dec 22 23:10:27 volumiodj volumio[1381]: stderr: Dec 22 23:10:27 volumiodj volumio[1381]: } Dec 22 23:10:27 volumiodj volumio[1381]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 23:10:27 volumiodj sudo[4875]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-22 23:09' Dec 22 23:10:27 volumiodj sudo[4875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"