-- Logs begin at Sat 2025-07-26 11:31:15 IDT, end at Fri 2025-10-17 19:19:49 IDT. -- Oct 17 19:18:00 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:00 yechezkel volumio[1088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 19:18:00 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:00 yechezkel volumio[1088]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 17 19:18:00 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:00 yechezkel volumio[1088]: info: QobuzConnect: setDeactiveState invoked Oct 17 19:18:00 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:00 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 10 Oct 17 19:18:00 yechezkel vtcs[10818]: [2025-10-17 19:18:00.025] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Oct 17 19:18:00 yechezkel vtcs[10818]: [2025-10-17 19:18:00.025] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 17 19:18:00 yechezkel sudo[15503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:00 yechezkel sudo[15503]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:00 yechezkel systemd[1]: mpd.service: Succeeded. Oct 17 19:18:00 yechezkel systemd[1]: Stopped Music Player Daemon. Oct 17 19:18:00 yechezkel sudo[15507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:00 yechezkel sudo[15507]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:00 yechezkel systemd[1]: Starting Music Player Daemon... Oct 17 19:18:00 yechezkel systemd[1]: Stopping Volumio Tidal Connect Service... Oct 17 19:18:00 yechezkel systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 17 19:18:00 yechezkel systemd[1]: vtcs.service: Succeeded. Oct 17 19:18:00 yechezkel systemd[1]: Stopped Volumio Tidal Connect Service. Oct 17 19:18:00 yechezkel sudo[15507]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:00 yechezkel sudo[15503]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:00 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:00 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:00 yechezkel sudo[15516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 19:18:00 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:00 yechezkel sudo[15516]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:00 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:00 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:00 yechezkel sudo[15506]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 19:18:00 yechezkel volumio[1088]: info: MPD Permissions set Oct 17 19:18:00 yechezkel sudo[15506]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:00 yechezkel sudo[15516]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:00 yechezkel sudo[15506]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:00 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:00 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:00 yechezkel sudo[15519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 19:18:00 yechezkel sudo[15519]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:00 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:00 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:00 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:00 yechezkel sudo[15519]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:00 yechezkel volumio[1088]: info: Starting Shairport Sync Oct 17 19:18:00 yechezkel sudo[15529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 19:18:00 yechezkel sudo[15529]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:00 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:00 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:00 yechezkel qobuz-connect[15526]: 20251017 19:18:00.156 [15526.15526] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:18:00 yechezkel systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 19:18:00 yechezkel qobuz-connect[15526]: 20251017 19:18:00.158 [15526.15526] INFO VolumeManager: [0x1c72948]: Setting new playback volume: 75 Oct 17 19:18:00 yechezkel qobuz-connect[15526]: 20251017 19:18:00.158 [15526.15526] INFO VolumeManager: [0x1c72948]: Setting new mute state: 0 Oct 17 19:18:00 yechezkel qobuz-connect[15526]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:18:00 yechezkel systemd[1]: shairport-sync.service: Succeeded. Oct 17 19:18:00 yechezkel systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 19:18:00 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:00 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:18:00 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:18:00 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:00 yechezkel systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 19:18:00 yechezkel sudo[15529]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:00 yechezkel volumio[1088]: info: Shairport-Sync Started Oct 17 19:18:00 yechezkel mpd[15520]: Oct 17 19:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 19:18:00 yechezkel systemd[1]: Started Music Player Daemon. Oct 17 19:18:00 yechezkel sudo[15495]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:00 yechezkel volumio[1088]: error: updateQueue error: null Oct 17 19:18:02 yechezkel go-librespot[2373]: time="2025-10-17T19:18:02+03:00" level=trace msg="sent dealer ping" Oct 17 19:18:02 yechezkel go-librespot[2373]: time="2025-10-17T19:18:02+03:00" level=trace msg="received dealer pong" Oct 17 19:18:03 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:03 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:03 yechezkel volumio[1088]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:03 yechezkel volumio[1088]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:03 yechezkel sudo[15559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 17 19:18:03 yechezkel sudo[15559]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:03 yechezkel systemd[1]: Started Volumio Tidal Connect Service. Oct 17 19:18:03 yechezkel sudo[15559]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:03 yechezkel volumio[1088]: info: Executing endpoint tc_getconfig Oct 17 19:18:03 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 17 19:18:03 yechezkel vtcs[15561]: STARTING TidalConnect services, version: 1.5.1-beta1 Oct 17 19:18:03 yechezkel vtcs[15561]: STARTED TidalConnect services. Oct 17 19:18:03 yechezkel volumio[1088]: info: Executing endpoint tc_connect Oct 17 19:18:03 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 17 19:18:03 yechezkel volumio[1088]: info: Connecting to TidalConnect Oct 17 19:18:03 yechezkel volumio[1088]: info: CoreCommandRouter::servicePushState Oct 17 19:18:03 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:03 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 10 Oct 17 19:18:03 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:18:03 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:03 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:03 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:03 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:03 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 10 Oct 17 19:18:03 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 10 Oct 17 19:18:03 yechezkel volumio[1088]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 17 19:18:03 yechezkel volumio[1088]: info: CoreCommandRouter::servicePushState Oct 17 19:18:03 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:03 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 10 Oct 17 19:18:03 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:03 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:03 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:03 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:03 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 10 Oct 17 19:18:03 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 10 Oct 17 19:18:03 yechezkel volumio[1088]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 17 19:18:03 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:18:05 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:18:05 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 1. Oct 17 19:18:05 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:05 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:05 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:05 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:05 yechezkel qobuz-connect[15580]: 20251017 19:18:05.199 [15580.15580] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:18:05 yechezkel qobuz-connect[15580]: 20251017 19:18:05.201 [15580.15580] INFO VolumeManager: [0x27ed948]: Setting new playback volume: 75 Oct 17 19:18:05 yechezkel qobuz-connect[15580]: 20251017 19:18:05.201 [15580.15580] INFO VolumeManager: [0x27ed948]: Setting new mute state: 0 Oct 17 19:18:05 yechezkel qobuz-connect[15580]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:18:05 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:18:05 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:18:06 yechezkel volumio[1088]: info: TidalConnect service started! Oct 17 19:18:06 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , setRemoteDeviceVolume Oct 17 19:18:06 yechezkel volumio[1088]: info: Setting Remote Device Volume: http://10.0.0.13 Oct 17 19:18:06 yechezkel volumio[1088]: info: Oct 17 19:18:06 yechezkel volumio[1088]: ---------------------------- Client requests Volume 17 Oct 17 19:18:06 yechezkel volumio[1088]: info: VolumeController::SetAlsaVolume17 Oct 17 19:18:06 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:06 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 10 Oct 17 19:18:06 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:18:06 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:06 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:06 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:06 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:06 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 10 Oct 17 19:18:06 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:06 yechezkel volumio[1088]: SPOTIFY: SPOTIFY VOLUME 100 Oct 17 19:18:06 yechezkel volumio[1088]: SPOTIFY: VOLUMIO VOLUME 17 Oct 17 19:18:06 yechezkel volumio[1088]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 17 19:18:06 yechezkel volumio[1088]: info: Setting Spotify Volume from Volumio: 17 Oct 17 19:18:06 yechezkel volumio[1088]: info: Done setting volume on: Oct 17 19:18:06 yechezkel volumio[1088]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Invalid command! Oct 17 19:18:07 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:18:08 yechezkel volumio[1088]: SPOTIFY: SETTING SPOTIFY VOLUME 17 Oct 17 19:18:08 yechezkel volumio[1088]: info: Sending Spotify command with payload to local API: /player/volume Oct 17 19:18:08 yechezkel go-librespot[2373]: time="2025-10-17T19:18:08+03:00" level=debug msg="update volume requested to 11140/65535" Oct 17 19:18:08 yechezkel go-librespot[2373]: time="2025-10-17T19:18:08+03:00" level=debug msg="renewing login5 access token" Oct 17 19:18:08 yechezkel go-librespot[2373]: time="2025-10-17T19:18:08+03:00" level=info msg="authenticated Login5" username="ny*********************ke" Oct 17 19:18:08 yechezkel go-librespot[2373]: time="2025-10-17T19:18:08+03:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 17 19:18:08 yechezkel go-librespot[2373]: time="2025-10-17T19:18:08+03:00" level=trace msg="emitting websocket event: volume" Oct 17 19:18:08 yechezkel volumio[1088]: SPOTIFY: received: {"type":"volume","data":{"value":17,"max":100}} Oct 17 19:18:08 yechezkel volumio[1088]: SPOTIFY: RECEIVED SPOTIFY VOLUME 17 Oct 17 19:18:10 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:18:10 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 2. Oct 17 19:18:10 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:10 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:10 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:10 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:10 yechezkel qobuz-connect[15597]: 20251017 19:18:10.447 [15597.15597] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:18:10 yechezkel qobuz-connect[15597]: 20251017 19:18:10.449 [15597.15597] INFO VolumeManager: [0xde0948]: Setting new playback volume: 75 Oct 17 19:18:10 yechezkel qobuz-connect[15597]: 20251017 19:18:10.449 [15597.15597] INFO VolumeManager: [0xde0948]: Setting new mute state: 0 Oct 17 19:18:10 yechezkel qobuz-connect[15597]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:18:10 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:18:10 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:18:11 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:18:15 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:18:15 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:18:15 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 3. Oct 17 19:18:15 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:15 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:15 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:15 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:15 yechezkel qobuz-connect[15602]: 20251017 19:18:15.706 [15602.15602] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:18:15 yechezkel qobuz-connect[15602]: 20251017 19:18:15.708 [15602.15602] INFO VolumeManager: [0x1e9a948]: Setting new playback volume: 75 Oct 17 19:18:15 yechezkel qobuz-connect[15602]: 20251017 19:18:15.708 [15602.15602] INFO VolumeManager: [0x1e9a948]: Setting new mute state: 0 Oct 17 19:18:15 yechezkel qobuz-connect[15602]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:18:15 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:18:15 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:18:17 yechezkel volumio[1088]: info: Disabling MyMusic plugin bluetooth Oct 17 19:18:17 yechezkel volumio[1088]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface bluetooth Oct 17 19:18:17 yechezkel sudo[15605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumiobt.service Oct 17 19:18:17 yechezkel sudo[15605]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:17 yechezkel systemd[1]: Stopping Volumio Bluetooth Module... Oct 17 19:18:17 yechezkel volumiobt[15607]: Killing pulse Oct 17 19:18:17 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:17 yechezkel bluetoothd[929]: Endpoint unregistered: sender=:1.48 path=/MediaEndpoint/A2DPSource Oct 17 19:18:17 yechezkel volumiobt[15607]: Killing BT Pair agent Oct 17 19:18:17 yechezkel systemd[1]: volumiobt.service: Main process exited, code=killed, status=15/TERM Oct 17 19:18:17 yechezkel systemd[1]: volumiobt.service: Succeeded. Oct 17 19:18:17 yechezkel systemd[1]: Stopped Volumio Bluetooth Module. Oct 17 19:18:17 yechezkel bluetoothd[929]: Endpoint unregistered: sender=:1.48 path=/MediaEndpoint/A2DPSink Oct 17 19:18:17 yechezkel sudo[15605]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:17 yechezkel volumio[1088]: info: Volumio BT Module successfully stopped Oct 17 19:18:17 yechezkel volumio[1088]: info: Disabling plugin bluetooth Oct 17 19:18:19 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:18:20 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:18:20 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 4. Oct 17 19:18:20 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:20 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:20 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:20 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:20 yechezkel qobuz-connect[15625]: 20251017 19:18:20.944 [15625.15625] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:18:20 yechezkel qobuz-connect[15625]: 20251017 19:18:20.946 [15625.15625] INFO VolumeManager: [0x1756948]: Setting new playback volume: 75 Oct 17 19:18:20 yechezkel qobuz-connect[15625]: 20251017 19:18:20.946 [15625.15625] INFO VolumeManager: [0x1756948]: Setting new mute state: 0 Oct 17 19:18:20 yechezkel qobuz-connect[15625]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:18:20 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:18:20 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:18:23 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:18:26 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:18:26 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 5. Oct 17 19:18:26 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:26 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:26 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:26 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:26 yechezkel qobuz-connect[15628]: 20251017 19:18:26.199 [15628.15628] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:18:26 yechezkel qobuz-connect[15628]: 20251017 19:18:26.201 [15628.15628] INFO VolumeManager: [0x172d948]: Setting new playback volume: 75 Oct 17 19:18:26 yechezkel qobuz-connect[15628]: 20251017 19:18:26.201 [15628.15628] INFO VolumeManager: [0x172d948]: Setting new mute state: 0 Oct 17 19:18:26 yechezkel qobuz-connect[15628]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:18:26 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:18:26 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:18:27 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:18:31 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:18:31 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:18:31 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 6. Oct 17 19:18:31 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:31 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:31 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:31 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:31 yechezkel qobuz-connect[15647]: 20251017 19:18:31.452 [15647.15647] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:18:31 yechezkel qobuz-connect[15647]: 20251017 19:18:31.454 [15647.15647] INFO VolumeManager: [0x1b1c948]: Setting new playback volume: 75 Oct 17 19:18:31 yechezkel qobuz-connect[15647]: 20251017 19:18:31.454 [15647.15647] INFO VolumeManager: [0x1b1c948]: Setting new mute state: 0 Oct 17 19:18:31 yechezkel qobuz-connect[15647]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:18:31 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:18:31 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:18:32 yechezkel go-librespot[2373]: time="2025-10-17T19:18:32+03:00" level=trace msg="sent dealer ping" Oct 17 19:18:32 yechezkel go-librespot[2373]: time="2025-10-17T19:18:32+03:00" level=trace msg="received dealer pong" Oct 17 19:18:33 yechezkel go-librespot[2373]: time="2025-10-17T19:18:33+03:00" level=trace msg="received accesspoint ping" Oct 17 19:18:33 yechezkel go-librespot[2373]: time="2025-10-17T19:18:33+03:00" level=trace msg="received accesspoint pong ack" Oct 17 19:18:35 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:18:36 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:18:36 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 7. Oct 17 19:18:36 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:36 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:36 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:36 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:36 yechezkel qobuz-connect[15651]: 20251017 19:18:36.699 [15651.15651] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:18:36 yechezkel qobuz-connect[15651]: 20251017 19:18:36.701 [15651.15651] INFO VolumeManager: [0x23a2948]: Setting new playback volume: 75 Oct 17 19:18:36 yechezkel qobuz-connect[15651]: 20251017 19:18:36.701 [15651.15651] INFO VolumeManager: [0x23a2948]: Setting new mute state: 0 Oct 17 19:18:36 yechezkel qobuz-connect[15651]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:18:36 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:18:36 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:18:37 yechezkel volumio[1088]: info: CALLMETHOD: miscellanea my_music updateMusicLibrarySupersearchSources [object Object] Oct 17 19:18:37 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibrarySupersearchSources Oct 17 19:18:37 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetBrowseSources Oct 17 19:18:37 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetBrowseSources Oct 17 19:18:37 yechezkel volumio[1088]: info: CoreCommandRouter::updateGlobalSearchableBrowseSources Oct 17 19:18:38 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 10 Oct 17 19:18:38 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:38 yechezkel volumio[1088]: info: Prefetching next song Oct 17 19:18:38 yechezkel volumio[1088]: info: DOING PREFETCH IN MPD Oct 17 19:18:38 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand add "USB/18music/religiosa/z/zelenka/Zelenka - Lamentations (Hyperion)/12 Zelenka - Lamentations 2=1 - 04.mp3" Oct 17 19:18:38 yechezkel volumio[1088]: info: Oct 17 19:18:38 yechezkel volumio[1088]: ---------------------------- MPD announces system playlist update Oct 17 19:18:38 yechezkel volumio[1088]: info: Ignoring MPD Status Update Oct 17 19:18:38 yechezkel volumio[1088]: info: sendMpdCommand add "USB/18music/religiosa/z/zelenka/Zelenka - Lamentations (Hyperion)/12 Zelenka - Lamentations 2=1 - 04.mp3" took 1 milliseconds Oct 17 19:18:38 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 17 19:18:38 yechezkel volumio[1088]: info: Oct 17 19:18:38 yechezkel volumio[1088]: ---------------------------- MPD announces state update: options Oct 17 19:18:38 yechezkel volumio[1088]: info: ------------------------------ 1ms Oct 17 19:18:38 yechezkel volumio[1088]: info: sendMpdCommand consume 1 took 0 milliseconds Oct 17 19:18:38 yechezkel volumio[1088]: info: ControllerMpd::getState Oct 17 19:18:38 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:18:38 yechezkel volumio[1088]: info: sendMpdCommand status took 0 milliseconds Oct 17 19:18:38 yechezkel volumio[1088]: verbose: ControllerMpd::parseState Oct 17 19:18:38 yechezkel volumio[1088]: info: ControllerMpd::pushState Oct 17 19:18:38 yechezkel volumio[1088]: info: CoreCommandRouter::servicePushState Oct 17 19:18:38 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:38 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 10 Oct 17 19:18:38 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:18:38 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:38 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:38 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:38 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:38 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 10 Oct 17 19:18:38 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 10 Oct 17 19:18:38 yechezkel volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 17 19:18:38 yechezkel volumio[1088]: verbose: CURRENT POSITION 10 Oct 17 19:18:38 yechezkel volumio[1088]: info: CoreStateMachine::syncState stateService stop Oct 17 19:18:38 yechezkel volumio[1088]: info: CoreStateMachine::syncState currentStatus play Oct 17 19:18:38 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:38 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:38 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:18:38 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:38 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:38 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:38 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:38 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:38 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:38 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:38 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:38 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:38 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:38 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:38 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:38 yechezkel volumio[1088]: info: ------------------------------ 11ms Oct 17 19:18:38 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:38 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:38 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:39 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 19:18:41 yechezkel volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 19:18:41 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:41 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 17 19:18:41 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:41 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 19:18:41 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:41 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:41 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:41 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:41 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:41 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:41 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:41 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:41 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 17 19:18:41 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 17 19:18:41 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:18:41 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 8. Oct 17 19:18:41 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:41 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:41 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:41 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:41 yechezkel qobuz-connect[15672]: 20251017 19:18:41.943 [15672.15672] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:18:41 yechezkel qobuz-connect[15672]: 20251017 19:18:41.945 [15672.15672] INFO VolumeManager: [0x8eb948]: Setting new playback volume: 75 Oct 17 19:18:41 yechezkel qobuz-connect[15672]: 20251017 19:18:41.945 [15672.15672] INFO VolumeManager: [0x8eb948]: Setting new mute state: 0 Oct 17 19:18:41 yechezkel qobuz-connect[15672]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:18:41 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:18:41 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:18:44 yechezkel volumio[1088]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 17 19:18:44 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 17 19:18:44 yechezkel volumio[1088]: info: Preparing to save Alsa Options, stopping services first Oct 17 19:18:44 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:44 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:44 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPause Oct 17 19:18:44 yechezkel volumio[1088]: info: CoreStateMachine::pause Oct 17 19:18:44 yechezkel volumio[1088]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"HDMI 1 Out"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Oct 17 19:18:44 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 19:18:44 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:44 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:44 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 19:18:44 yechezkel volumio[1088]: info: Found match in Cards Database: setting mixer for card HDMI 1 Out Oct 17 19:18:44 yechezkel volumio[1088]: info: QobuzConnect: setDeactiveState invoked Oct 17 19:18:44 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:44 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:44 yechezkel vtcs[15561]: [2025-10-17 19:18:44.958] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 17 19:18:44 yechezkel vtcs[15561]: [2025-10-17 19:18:44.958] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Oct 17 19:18:44 yechezkel sudo[15679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:44 yechezkel sudo[15679]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:44 yechezkel systemd[1]: Stopping Volumio Tidal Connect Service... Oct 17 19:18:44 yechezkel systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 17 19:18:44 yechezkel systemd[1]: vtcs.service: Succeeded. Oct 17 19:18:44 yechezkel systemd[1]: Stopped Volumio Tidal Connect Service. Oct 17 19:18:44 yechezkel sudo[15679]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:44 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:44 yechezkel volumio[1088]: info: Device 1 does not have any Mixer Control Available Oct 17 19:18:44 yechezkel volumio[1088]: info: QobuzConnect: setDeactiveState invoked Oct 17 19:18:44 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:44 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:45 yechezkel sudo[15685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:45 yechezkel sudo[15685]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15685]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 17 19:18:45 yechezkel volumio[1088]: info: Updating Volume Controller Parameters: Device: 1 Name: HDMI 1 Out Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 17 19:18:45 yechezkel volumio[1088]: info: Disabling external Volume Control Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:45 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:45 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:45 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:45 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:45 yechezkel volumio[1088]: info: Preparing to generate the ALSA configuration file Oct 17 19:18:45 yechezkel sudo[15692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:45 yechezkel sudo[15692]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15692]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:45 yechezkel sudo[15695]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15695]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 19:18:45 yechezkel sudo[15703]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15703]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 19:18:45 yechezkel sudo[15708]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:45 yechezkel sudo[15712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 19:18:45 yechezkel sudo[15712]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel volumio[1088]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Oct 17 19:18:45 yechezkel volumio[1088]: amixer: Invalid command! Oct 17 19:18:45 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:45 yechezkel sudo[15708]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15712]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 19:18:45 yechezkel sudo[15717]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 19:18:45 yechezkel systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 17 19:18:45 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Oct 17 19:18:45 yechezkel systemd[1]: qobuz-connect.service: Succeeded. Oct 17 19:18:45 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:45 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:45 yechezkel sudo[15717]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:45 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:45 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:45 yechezkel volumio[1088]: info: Asound.conf file written Oct 17 19:18:45 yechezkel qobuz-connect[15719]: 20251017 19:18:45.216 [15719.15719] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:18:45 yechezkel qobuz-connect[15719]: 20251017 19:18:45.218 [15719.15719] INFO VolumeManager: [0x1e5b948]: Setting new playback volume: 75 Oct 17 19:18:45 yechezkel qobuz-connect[15719]: 20251017 19:18:45.218 [15719.15719] INFO VolumeManager: [0x1e5b948]: Setting new mute state: 0 Oct 17 19:18:45 yechezkel qobuz-connect[15719]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:18:45 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:18:45 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:18:45 yechezkel sudo[15724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 17 19:18:45 yechezkel sudo[15724]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15724]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:45 yechezkel volumio[1088]: No state is present for card vc4hdmi0 Oct 17 19:18:45 yechezkel volumio[1088]: Found hardware: "vc4-hdmi" "" "" "" "" Oct 17 19:18:45 yechezkel volumio[1088]: Hardware is initialized using a generic method Oct 17 19:18:45 yechezkel volumio[1088]: No state is present for card vc4hdmi0 Oct 17 19:18:45 yechezkel volumio[1088]: No state is present for card vc4hdmi1 Oct 17 19:18:45 yechezkel volumio[1088]: Found hardware: "vc4-hdmi" "" "" "" "" Oct 17 19:18:45 yechezkel volumio[1088]: Hardware is initialized using a generic method Oct 17 19:18:45 yechezkel volumio[1088]: No state is present for card vc4hdmi1 Oct 17 19:18:45 yechezkel volumio[1088]: No state is present for card DAC52061225 Oct 17 19:18:45 yechezkel volumio[1088]: Found hardware: "USB-Audio" "USB Mixer" "USB2a39:3fd3" "" "" Oct 17 19:18:45 yechezkel volumio[1088]: Hardware is initialized using a generic method Oct 17 19:18:45 yechezkel volumio[1088]: No state is present for card DAC52061225 Oct 17 19:18:45 yechezkel volumio[1088]: info: Output device has changed, restarting MPD Oct 17 19:18:45 yechezkel sudo[15729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 19:18:45 yechezkel sudo[15729]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15729]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel volumio[1088]: info: Output device has changed, restarting Shairport Sync Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:45 yechezkel sudo[15732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 19:18:45 yechezkel sudo[15732]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel systemd[1]: Stopping Music Player Daemon... Oct 17 19:18:45 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:45 yechezkel volumio[1088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 19:18:45 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:45 yechezkel volumio[1088]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: QobuzConnect: setDeactiveState invoked Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:45 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:45 yechezkel volumio[1088]: info: Output device has changed, restarting MPD Oct 17 19:18:45 yechezkel sudo[15740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:45 yechezkel sudo[15740]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel systemd[1]: mpd.service: Succeeded. Oct 17 19:18:45 yechezkel sudo[15743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 19:18:45 yechezkel systemd[1]: Stopped Music Player Daemon. Oct 17 19:18:45 yechezkel sudo[15743]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15743]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel volumio[1088]: info: Output device has changed, restarting Shairport Sync Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:45 yechezkel sudo[15747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 19:18:45 yechezkel sudo[15747]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:45 yechezkel volumio[1088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 19:18:45 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:45 yechezkel volumio[1088]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel systemd[1]: Starting Music Player Daemon... Oct 17 19:18:45 yechezkel sudo[15740]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel volumio[1088]: info: QobuzConnect: setDeactiveState invoked Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:45 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:45 yechezkel systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 17 19:18:45 yechezkel systemd[1]: mpd.service: Succeeded. Oct 17 19:18:45 yechezkel systemd[1]: Stopped Music Player Daemon. Oct 17 19:18:45 yechezkel systemd[1]: Starting Music Player Daemon... Oct 17 19:18:45 yechezkel sudo[15757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:45 yechezkel sudo[15757]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15755]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 19:18:45 yechezkel sudo[15755]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15755]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:45 yechezkel sudo[15757]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15761]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15761]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:45 yechezkel sudo[15767]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15767]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 19:18:45 yechezkel sudo[15774]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15774]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 19:18:45 yechezkel sudo[15779]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:45 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:45 yechezkel sudo[15782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:45 yechezkel sudo[15782]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:45 yechezkel volumio[1088]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 17 19:18:45 yechezkel volumio[1088]: info: MPD Permissions set Oct 17 19:18:45 yechezkel volumio[1088]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 17 19:18:45 yechezkel volumio[1088]: info: MPD Permissions set Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel sudo[15782]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15779]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 19:18:45 yechezkel sudo[15790]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 17 19:18:45 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Oct 17 19:18:45 yechezkel systemd[1]: qobuz-connect.service: Succeeded. Oct 17 19:18:45 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:45 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:45 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:45 yechezkel volumio[1088]: info: Starting Shairport Sync Oct 17 19:18:45 yechezkel sudo[15790]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel volumio[1088]: info: Starting Shairport Sync Oct 17 19:18:45 yechezkel sudo[15799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 19:18:45 yechezkel sudo[15799]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 19:18:45 yechezkel sudo[15802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 19:18:45 yechezkel volumio[1088]: info: Preparing to generate the ALSA configuration file Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:45 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:45 yechezkel volumio[1088]: info: Asound.conf file unchanged, so no further update is needed Oct 17 19:18:45 yechezkel volumio[1088]: info: Output device has changed, restarting MPD Oct 17 19:18:45 yechezkel systemd[1]: shairport-sync.service: Succeeded. Oct 17 19:18:45 yechezkel qobuz-connect[15796]: 20251017 19:18:45.639 [15796.15796] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:18:45 yechezkel qobuz-connect[15796]: 20251017 19:18:45.641 [15796.15796] INFO VolumeManager: [0x13b7948]: Setting new playback volume: 75 Oct 17 19:18:45 yechezkel qobuz-connect[15796]: 20251017 19:18:45.641 [15796.15796] INFO VolumeManager: [0x13b7948]: Setting new mute state: 0 Oct 17 19:18:45 yechezkel qobuz-connect[15796]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:18:45 yechezkel sudo[15802]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 19:18:45 yechezkel systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 19:18:45 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:18:45 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:18:45 yechezkel sudo[15806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 19:18:45 yechezkel sudo[15806]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15806]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15799]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 19:18:45 yechezkel systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 17 19:18:45 yechezkel systemd[1]: shairport-sync.service: Succeeded. Oct 17 19:18:45 yechezkel systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 19:18:45 yechezkel volumio[1088]: info: Output device has changed, restarting Shairport Sync Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:45 yechezkel systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 19:18:45 yechezkel sudo[15802]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 19:18:45 yechezkel sudo[15811]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 17 19:18:45 yechezkel systemd[1]: mpd.service: Succeeded. Oct 17 19:18:45 yechezkel systemd[1]: Stopped Music Player Daemon. Oct 17 19:18:45 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:45 yechezkel volumio[1088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 19:18:45 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:45 yechezkel volumio[1088]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 17 19:18:45 yechezkel systemd[1]: Starting Music Player Daemon... Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: QobuzConnect: setDeactiveState invoked Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:45 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:45 yechezkel sudo[15818]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 19:18:45 yechezkel sudo[15818]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15818]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel volumio[1088]: info: Preparing to generate the ALSA configuration file Oct 17 19:18:45 yechezkel sudo[15823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:45 yechezkel sudo[15823]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:45 yechezkel sudo[15827]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15823]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15827]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:45 yechezkel sudo[15835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 19:18:45 yechezkel sudo[15835]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel volumio[1088]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 17 19:18:45 yechezkel volumio[1088]: info: MPD Permissions set Oct 17 19:18:45 yechezkel volumio[1088]: info: Shairport-Sync Started Oct 17 19:18:45 yechezkel volumio[1088]: info: Shairport-Sync Started Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel sudo[15835]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 19:18:45 yechezkel sudo[15838]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:45 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: Asound.conf file unchanged, so no further update is needed Oct 17 19:18:45 yechezkel volumio[1088]: info: Output device has changed, restarting MPD Oct 17 19:18:45 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:45 yechezkel sudo[15838]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 19:18:45 yechezkel volumio[1088]: info: Output device has changed, restarting Shairport Sync Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:45 yechezkel sudo[15848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 19:18:45 yechezkel sudo[15848]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15846]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15846]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 17 19:18:45 yechezkel systemd[1]: mpd.service: Succeeded. Oct 17 19:18:45 yechezkel systemd[1]: Stopped Music Player Daemon. Oct 17 19:18:45 yechezkel systemd[1]: Starting Music Player Daemon... Oct 17 19:18:45 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:45 yechezkel volumio[1088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 19:18:45 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:45 yechezkel volumio[1088]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: QobuzConnect: setDeactiveState invoked Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:45 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:45 yechezkel sudo[15855]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 19:18:45 yechezkel sudo[15855]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15855]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:45 yechezkel sudo[15860]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:45 yechezkel sudo[15864]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:45 yechezkel volumio[1088]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Oct 17 19:18:45 yechezkel volumio[1088]: info: MPD Permissions set Oct 17 19:18:45 yechezkel sudo[15870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 19:18:45 yechezkel sudo[15860]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15864]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:45 yechezkel sudo[15870]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:45 yechezkel volumio[1088]: info: Starting Shairport Sync Oct 17 19:18:45 yechezkel qobuz-connect[15844]: 20251017 19:18:45.957 [15844.15844] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:18:45 yechezkel qobuz-connect[15844]: 20251017 19:18:45.959 [15844.15844] INFO VolumeManager: [0x1ef4948]: Setting new playback volume: 75 Oct 17 19:18:45 yechezkel qobuz-connect[15844]: 20251017 19:18:45.959 [15844.15844] INFO VolumeManager: [0x1ef4948]: Setting new mute state: 0 Oct 17 19:18:45 yechezkel qobuz-connect[15844]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:18:45 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:18:45 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:18:45 yechezkel sudo[15870]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel sudo[15875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:45 yechezkel sudo[15875]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:45 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:45 yechezkel sudo[15876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 19:18:45 yechezkel sudo[15876]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:45 yechezkel sudo[15875]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:45 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:46 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:46 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:46 yechezkel qobuz-connect[15878]: 20251017 19:18:46.002 [15878.15878] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:18:46 yechezkel qobuz-connect[15878]: 20251017 19:18:46.004 [15878.15878] INFO VolumeManager: [0x11a5948]: Setting new playback volume: 75 Oct 17 19:18:46 yechezkel qobuz-connect[15878]: 20251017 19:18:46.004 [15878.15878] INFO VolumeManager: [0x11a5948]: Setting new mute state: 0 Oct 17 19:18:46 yechezkel qobuz-connect[15878]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:18:46 yechezkel systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 19:18:46 yechezkel volumio[1088]: info: Starting Shairport Sync Oct 17 19:18:46 yechezkel systemd[1]: shairport-sync.service: Succeeded. Oct 17 19:18:46 yechezkel systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 19:18:46 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:18:46 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:18:46 yechezkel systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 19:18:46 yechezkel sudo[15876]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:46 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:46 yechezkel sudo[15887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 19:18:46 yechezkel sudo[15887]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:46 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:46 yechezkel volumio[1088]: info: Shairport-Sync Started Oct 17 19:18:46 yechezkel systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 19:18:46 yechezkel systemd[1]: shairport-sync.service: Succeeded. Oct 17 19:18:46 yechezkel systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 19:18:46 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:46 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:46 yechezkel systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 19:18:46 yechezkel sudo[15887]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:46 yechezkel volumio[1088]: info: Shairport-Sync Started Oct 17 19:18:46 yechezkel mpd[15862]: Oct 17 19:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 19:18:46 yechezkel systemd[1]: Started Music Player Daemon. Oct 17 19:18:46 yechezkel sudo[15811]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:46 yechezkel sudo[15732]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:46 yechezkel sudo[15848]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:46 yechezkel sudo[15747]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:46 yechezkel volumio[1088]: error: MPD error: The expression evaluated to a falsy value: Oct 17 19:18:46 yechezkel volumio[1088]: assert.ok(self.idling) Oct 17 19:18:46 yechezkel volumio[1088]: error: The expression evaluated to a falsy value: Oct 17 19:18:46 yechezkel volumio[1088]: assert.ok(self.idling) Oct 17 19:18:46 yechezkel volumio[1088]: error: MPD error: The expression evaluated to a falsy value: Oct 17 19:18:46 yechezkel volumio[1088]: assert.ok(self.idling) Oct 17 19:18:46 yechezkel volumio[1088]: error: The expression evaluated to a falsy value: Oct 17 19:18:46 yechezkel volumio[1088]: assert.ok(self.idling) Oct 17 19:18:46 yechezkel volumio[1088]: error: MPD error: The expression evaluated to a falsy value: Oct 17 19:18:46 yechezkel volumio[1088]: assert.ok(self.idling) Oct 17 19:18:46 yechezkel volumio[1088]: error: The expression evaluated to a falsy value: Oct 17 19:18:46 yechezkel volumio[1088]: assert.ok(self.idling) Oct 17 19:18:46 yechezkel volumio[1088]: error: updateQueue error: null Oct 17 19:18:47 yechezkel volumio[1088]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 17 19:18:47 yechezkel volumio[1088]: info: Preparing to save Alsa Options, stopping services first Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:47 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPause Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreStateMachine::pause Oct 17 19:18:47 yechezkel volumio[1088]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"ADI-2 DAC (52061225)"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 19:18:47 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:47 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 19:18:47 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:47 yechezkel volumio[1088]: info: Setting mixer AES Rate for card ADI-2 DAC (52061225) Oct 17 19:18:47 yechezkel volumio[1088]: info: QobuzConnect: setDeactiveState invoked Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:47 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:47 yechezkel sudo[15903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:47 yechezkel sudo[15903]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:47 yechezkel sudo[15903]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:47 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 17 19:18:47 yechezkel volumio[1088]: info: Updating Volume Controller Parameters: Device: 5 Name: ADI-2 DAC (52061225) Mixer: AES Rate Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 17 19:18:47 yechezkel volumio[1088]: info: Disabling external Volume Control Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 19:18:47 yechezkel volumio[1088]: info: Preparing to generate the ALSA configuration file Oct 17 19:18:47 yechezkel sudo[15910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:47 yechezkel sudo[15910]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:47 yechezkel sudo[15910]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:47 yechezkel volumio[1088]: Unable to parse: Oct 17 19:18:47 yechezkel volumio[1088]: Simple mixer control 'AES Rate',0 Oct 17 19:18:47 yechezkel volumio[1088]: Capabilities: volume volume-joined Oct 17 19:18:47 yechezkel volumio[1088]: Playback channels: Mono Oct 17 19:18:47 yechezkel volumio[1088]: Capture channels: Mono Oct 17 19:18:47 yechezkel volumio[1088]: Limits: 0 - 200000 Oct 17 19:18:47 yechezkel volumio[1088]: Mono: 0 [0%] Oct 17 19:18:47 yechezkel volumio[1088]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 17 19:18:47 yechezkel sudo[15917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 19:18:47 yechezkel sudo[15917]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:47 yechezkel sudo[15917]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:47 yechezkel sudo[15920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 19:18:47 yechezkel sudo[15920]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:47 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:47 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 19:18:47 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:47 yechezkel sudo[15920]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:47 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 17 19:18:47 yechezkel volumio[1088]: Unable to parse: Oct 17 19:18:47 yechezkel volumio[1088]: Simple mixer control 'AES Rate',0 Oct 17 19:18:47 yechezkel volumio[1088]: Capabilities: volume volume-joined Oct 17 19:18:47 yechezkel volumio[1088]: Playback channels: Mono Oct 17 19:18:47 yechezkel volumio[1088]: Capture channels: Mono Oct 17 19:18:47 yechezkel volumio[1088]: Limits: 0 - 200000 Oct 17 19:18:47 yechezkel volumio[1088]: Mono: 0 [0%] Oct 17 19:18:47 yechezkel volumio[1088]: info: VolumeController:: Volume=undefined Mute =false Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:47 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:47 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:47 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:47 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:47 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:47 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:47 yechezkel qobuz-connect[15922]: 20251017 19:18:47.664 [15922.15922] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:18:47 yechezkel volumio[1088]: info: Asound.conf file written Oct 17 19:18:47 yechezkel qobuz-connect[15922]: 20251017 19:18:47.666 [15922.15922] INFO VolumeManager: [0x12ce948]: Setting new playback volume: 75 Oct 17 19:18:47 yechezkel qobuz-connect[15922]: 20251017 19:18:47.666 [15922.15922] INFO VolumeManager: [0x12ce948]: Setting new mute state: 0 Oct 17 19:18:47 yechezkel qobuz-connect[15922]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:18:47 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:18:47 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:18:47 yechezkel sudo[15929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 17 19:18:47 yechezkel sudo[15929]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:47 yechezkel sudo[15929]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:47 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:47 yechezkel volumio[1088]: No state is present for card vc4hdmi0 Oct 17 19:18:47 yechezkel volumio[1088]: Found hardware: "vc4-hdmi" "" "" "" "" Oct 17 19:18:47 yechezkel volumio[1088]: Hardware is initialized using a generic method Oct 17 19:18:47 yechezkel volumio[1088]: No state is present for card vc4hdmi0 Oct 17 19:18:47 yechezkel volumio[1088]: No state is present for card vc4hdmi1 Oct 17 19:18:47 yechezkel volumio[1088]: Found hardware: "vc4-hdmi" "" "" "" "" Oct 17 19:18:47 yechezkel volumio[1088]: Hardware is initialized using a generic method Oct 17 19:18:47 yechezkel volumio[1088]: No state is present for card vc4hdmi1 Oct 17 19:18:47 yechezkel volumio[1088]: No state is present for card DAC52061225 Oct 17 19:18:47 yechezkel volumio[1088]: Found hardware: "USB-Audio" "USB Mixer" "USB2a39:3fd3" "" "" Oct 17 19:18:47 yechezkel volumio[1088]: Hardware is initialized using a generic method Oct 17 19:18:47 yechezkel volumio[1088]: No state is present for card DAC52061225 Oct 17 19:18:47 yechezkel volumio[1088]: info: Output device has changed, restarting MPD Oct 17 19:18:47 yechezkel sudo[15934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 19:18:47 yechezkel sudo[15934]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:47 yechezkel sudo[15934]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:47 yechezkel volumio[1088]: info: Output device has changed, restarting Shairport Sync Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:47 yechezkel sudo[15947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 19:18:47 yechezkel sudo[15947]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:47 yechezkel systemd[1]: Stopping Music Player Daemon... Oct 17 19:18:47 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:47 yechezkel volumio[1088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 19:18:47 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:47 yechezkel volumio[1088]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:47 yechezkel volumio[1088]: info: QobuzConnect: setDeactiveState invoked Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:47 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:47 yechezkel volumio[1088]: info: Output device has changed, restarting MPD Oct 17 19:18:47 yechezkel sudo[15983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:47 yechezkel sudo[15983]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:47 yechezkel systemd[1]: mpd.service: Succeeded. Oct 17 19:18:47 yechezkel systemd[1]: Stopped Music Player Daemon. Oct 17 19:18:47 yechezkel sudo[15986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 19:18:47 yechezkel sudo[15986]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:47 yechezkel sudo[15986]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:47 yechezkel systemd[1]: Starting Music Player Daemon... Oct 17 19:18:47 yechezkel volumio[1088]: info: Output device has changed, restarting Shairport Sync Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:47 yechezkel sudo[15983]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:47 yechezkel sudo[15990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 19:18:47 yechezkel sudo[15990]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:47 yechezkel systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 17 19:18:47 yechezkel systemd[1]: mpd.service: Succeeded. Oct 17 19:18:47 yechezkel systemd[1]: Stopped Music Player Daemon. Oct 17 19:18:47 yechezkel systemd[1]: Starting Music Player Daemon... Oct 17 19:18:47 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:47 yechezkel volumio[1088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 19:18:47 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:47 yechezkel volumio[1088]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:47 yechezkel volumio[1088]: info: QobuzConnect: setDeactiveState invoked Oct 17 19:18:47 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:47 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:47 yechezkel sudo[15996]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 19:18:47 yechezkel sudo[15996]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:47 yechezkel sudo[15996]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:47 yechezkel sudo[16002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:47 yechezkel sudo[16002]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:47 yechezkel sudo[16005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:47 yechezkel sudo[16005]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:47 yechezkel sudo[16002]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:47 yechezkel sudo[16005]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:47 yechezkel sudo[16009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:47 yechezkel sudo[16009]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:47 yechezkel sudo[16009]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:47 yechezkel sudo[16017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 19:18:47 yechezkel sudo[16017]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:47 yechezkel sudo[16017]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:47 yechezkel sudo[16022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 19:18:47 yechezkel sudo[16022]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:47 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:47 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:47 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:47 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:47 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:47 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:47 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:47 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:47 yechezkel sudo[16025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 19:18:47 yechezkel sudo[16025]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:48 yechezkel volumio[1088]: info: MPD Permissions set Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:48 yechezkel sudo[16022]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel sudo[16025]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:48 yechezkel sudo[16036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 19:18:48 yechezkel sudo[16036]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:48 yechezkel systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 17 19:18:48 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Oct 17 19:18:48 yechezkel systemd[1]: qobuz-connect.service: Succeeded. Oct 17 19:18:48 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:48 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:48 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:48 yechezkel sudo[16036]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:48 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:48 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:48 yechezkel volumio[1088]: info: Starting Shairport Sync Oct 17 19:18:48 yechezkel volumio[1088]: info: Starting Shairport Sync Oct 17 19:18:48 yechezkel sudo[16042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 19:18:48 yechezkel sudo[16042]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 19:18:48 yechezkel volumio[1088]: info: Preparing to generate the ALSA configuration file Oct 17 19:18:48 yechezkel sudo[16045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 19:18:48 yechezkel sudo[16045]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel systemd[1]: shairport-sync.service: Succeeded. Oct 17 19:18:48 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:48 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:48 yechezkel qobuz-connect[16039]: 20251017 19:18:48.091 [16039.16039] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:18:48 yechezkel systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 19:18:48 yechezkel qobuz-connect[16039]: 20251017 19:18:48.092 [16039.16039] INFO VolumeManager: [0x183f948]: Setting new playback volume: 75 Oct 17 19:18:48 yechezkel qobuz-connect[16039]: 20251017 19:18:48.092 [16039.16039] INFO VolumeManager: [0x183f948]: Setting new mute state: 0 Oct 17 19:18:48 yechezkel qobuz-connect[16039]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:18:48 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:48 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:48 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:18:48 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:18:48 yechezkel volumio[1088]: info: Asound.conf file unchanged, so no further update is needed Oct 17 19:18:48 yechezkel volumio[1088]: info: Output device has changed, restarting MPD Oct 17 19:18:48 yechezkel systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 19:18:48 yechezkel systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 19:18:48 yechezkel sudo[16042]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 17 19:18:48 yechezkel systemd[1]: shairport-sync.service: Succeeded. Oct 17 19:18:48 yechezkel systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 19:18:48 yechezkel systemd[1]: shairport-sync.service: Start request repeated too quickly. Oct 17 19:18:48 yechezkel systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Oct 17 19:18:48 yechezkel systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Oct 17 19:18:48 yechezkel sudo[16054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 19:18:48 yechezkel sudo[16054]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel sudo[16045]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel volumio[1088]: info: Output device has changed, restarting Shairport Sync Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:48 yechezkel sudo[16054]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel sudo[16057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 19:18:48 yechezkel sudo[16057]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 17 19:18:48 yechezkel systemd[1]: mpd.service: Succeeded. Oct 17 19:18:48 yechezkel systemd[1]: Stopped Music Player Daemon. Oct 17 19:18:48 yechezkel systemd[1]: Starting Music Player Daemon... Oct 17 19:18:48 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:48 yechezkel volumio[1088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 19:18:48 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:48 yechezkel volumio[1088]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:48 yechezkel volumio[1088]: info: QobuzConnect: setDeactiveState invoked Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:48 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:48 yechezkel sudo[16062]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 19:18:48 yechezkel sudo[16062]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel sudo[16062]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel volumio[1088]: info: Preparing to generate the ALSA configuration file Oct 17 19:18:48 yechezkel sudo[16068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:48 yechezkel sudo[16068]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel sudo[16068]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel sudo[16072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:48 yechezkel sudo[16072]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel sudo[16072]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:48 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:48 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:48 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:48 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:48 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:48 yechezkel sudo[16079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 19:18:48 yechezkel sudo[16079]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel sudo[16079]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel sudo[16082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 19:18:48 yechezkel sudo[16082]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel volumio[1088]: info: MPD Permissions set Oct 17 19:18:48 yechezkel volumio[1088]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Oct 17 19:18:48 yechezkel volumio[1088]: Job for shairport-sync.service failed. Oct 17 19:18:48 yechezkel volumio[1088]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Oct 17 19:18:48 yechezkel volumio[1088]: info: Shairport-Sync Started Oct 17 19:18:48 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:48 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:48 yechezkel volumio[1088]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:48 yechezkel volumio[1088]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:48 yechezkel sudo[16082]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:48 yechezkel volumio[1088]: info: Asound.conf file unchanged, so no further update is needed Oct 17 19:18:48 yechezkel volumio[1088]: info: Output device has changed, restarting MPD Oct 17 19:18:48 yechezkel sudo[16102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 19:18:48 yechezkel sudo[16102]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel sudo[16102]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel volumio[1088]: info: Output device has changed, restarting Shairport Sync Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:48 yechezkel sudo[16105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 19:18:48 yechezkel sudo[16105]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 17 19:18:48 yechezkel systemd[1]: mpd.service: Succeeded. Oct 17 19:18:48 yechezkel systemd[1]: Stopped Music Player Daemon. Oct 17 19:18:48 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:48 yechezkel volumio[1088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 19:18:48 yechezkel volumio[1088]: xcb_connection_has_error() returned true Oct 17 19:18:48 yechezkel volumio[1088]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:48 yechezkel volumio[1088]: info: QobuzConnect: setDeactiveState invoked Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:48 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:48 yechezkel systemd[1]: Starting Music Player Daemon... Oct 17 19:18:48 yechezkel sudo[16114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:48 yechezkel sudo[16114]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel sudo[16114]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel sudo[16110]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 19:18:48 yechezkel sudo[16110]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel sudo[16117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 19:18:48 yechezkel sudo[16117]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel sudo[16110]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel sudo[16126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 19:18:48 yechezkel sudo[16126]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:48 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:48 yechezkel sudo[16117]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:48 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:48 yechezkel sudo[16126]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel volumio[1088]: info: MPD Permissions set Oct 17 19:18:48 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:48 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:48 yechezkel volumio[1088]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:48 yechezkel volumio[1088]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:48 yechezkel sudo[16129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 19:18:48 yechezkel sudo[16129]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 17 19:18:48 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 17 19:18:48 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:48 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:48 yechezkel sudo[16132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 17 19:18:48 yechezkel sudo[16132]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Oct 17 19:18:48 yechezkel systemd[1]: qobuz-connect.service: Succeeded. Oct 17 19:18:48 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:48 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:48 yechezkel sudo[16129]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel volumio[1088]: info: Starting Shairport Sync Oct 17 19:18:48 yechezkel volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 17 19:18:48 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:48 yechezkel sudo[16142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 19:18:48 yechezkel sudo[16142]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:48 yechezkel systemd[1]: Started Volumio Tidal Connect Service. Oct 17 19:18:48 yechezkel systemd[1]: shairport-sync.service: Start request repeated too quickly. Oct 17 19:18:48 yechezkel systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Oct 17 19:18:48 yechezkel systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Oct 17 19:18:48 yechezkel sudo[16132]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel sudo[16142]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:48 yechezkel sudo[16155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 17 19:18:48 yechezkel sudo[16155]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel sudo[16155]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:48 yechezkel qobuz-connect[16134]: 20251017 19:18:48.612 [16134.16134] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:18:48 yechezkel volumio[1088]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Oct 17 19:18:48 yechezkel volumio[1088]: Job for shairport-sync.service failed. Oct 17 19:18:48 yechezkel volumio[1088]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Oct 17 19:18:48 yechezkel qobuz-connect[16134]: 20251017 19:18:48.614 [16134.16134] INFO VolumeManager: [0xe54948]: Setting new playback volume: 75 Oct 17 19:18:48 yechezkel qobuz-connect[16134]: 20251017 19:18:48.614 [16134.16134] INFO VolumeManager: [0xe54948]: Setting new mute state: 0 Oct 17 19:18:48 yechezkel qobuz-connect[16134]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:18:48 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:18:48 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:18:48 yechezkel volumio[1088]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:48 yechezkel volumio[1088]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:48 yechezkel volumio[1088]: info: Starting Shairport Sync Oct 17 19:18:48 yechezkel volumio[1088]: info: Executing endpoint tc_getconfig Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 17 19:18:48 yechezkel volumio[1088]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:48 yechezkel volumio[1088]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:48 yechezkel vtcs[16141]: STARTING TidalConnect services, version: 1.5.1-beta1 Oct 17 19:18:48 yechezkel vtcs[16141]: STARTED TidalConnect services. Oct 17 19:18:48 yechezkel sudo[16168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 19:18:48 yechezkel sudo[16168]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel systemd[1]: shairport-sync.service: Start request repeated too quickly. Oct 17 19:18:48 yechezkel systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Oct 17 19:18:48 yechezkel systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Oct 17 19:18:48 yechezkel sudo[16168]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel sudo[16178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 17 19:18:48 yechezkel sudo[16178]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel volumio[1088]: info: Executing endpoint tc_connect Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 17 19:18:48 yechezkel volumio[1088]: info: Connecting to TidalConnect Oct 17 19:18:48 yechezkel sudo[16178]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel volumio[1088]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Oct 17 19:18:48 yechezkel volumio[1088]: Job for shairport-sync.service failed. Oct 17 19:18:48 yechezkel volumio[1088]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::servicePushState Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:48 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:48 yechezkel sudo[16181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 17 19:18:48 yechezkel sudo[16181]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:48 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:48 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:48 yechezkel volumio[1088]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::servicePushState Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:48 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:48 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:48 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:48 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:48 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:48 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:48 yechezkel volumio[1088]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 17 19:18:48 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:48 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:48 yechezkel sudo[16181]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel mpd[16122]: Oct 17 19:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 19:18:48 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:48 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:48 yechezkel volumio[1088]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:48 yechezkel volumio[1088]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:48 yechezkel sudo[16202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 17 19:18:48 yechezkel sudo[16202]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:48 yechezkel sudo[16202]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:48 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:48 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:48 yechezkel volumio[1088]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:48 yechezkel volumio[1088]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:49 yechezkel sudo[16214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 17 19:18:49 yechezkel sudo[16214]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:49 yechezkel sudo[16214]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:49 yechezkel volumio[1088]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 17 19:18:49 yechezkel volumio[1088]: info: CoreStateMachine::getcurrentVolume Oct 17 19:18:49 yechezkel volumio[1088]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 19:18:49 yechezkel volumio[1088]: Unable to parse: Oct 17 19:18:49 yechezkel volumio[1088]: Simple mixer control 'AES Rate',0 Oct 17 19:18:49 yechezkel volumio[1088]: Capabilities: volume volume-joined Oct 17 19:18:49 yechezkel volumio[1088]: Playback channels: Mono Oct 17 19:18:49 yechezkel volumio[1088]: Capture channels: Mono Oct 17 19:18:49 yechezkel volumio[1088]: Limits: 0 - 200000 Oct 17 19:18:49 yechezkel volumio[1088]: Mono: 0 [0%] Oct 17 19:18:49 yechezkel volumio[1088]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 17 19:18:49 yechezkel systemd[1]: Started Music Player Daemon. Oct 17 19:18:49 yechezkel sudo[16105]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:49 yechezkel sudo[16057]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:49 yechezkel sudo[15990]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:49 yechezkel sudo[15947]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:49 yechezkel volumio[1088]: error: MPD error: The expression evaluated to a falsy value: Oct 17 19:18:49 yechezkel volumio[1088]: assert.ok(self.idling) Oct 17 19:18:49 yechezkel volumio[1088]: error: The expression evaluated to a falsy value: Oct 17 19:18:49 yechezkel volumio[1088]: assert.ok(self.idling) Oct 17 19:18:49 yechezkel volumio[1088]: error: MPD error: The expression evaluated to a falsy value: Oct 17 19:18:49 yechezkel volumio[1088]: assert.ok(self.idling) Oct 17 19:18:49 yechezkel volumio[1088]: error: The expression evaluated to a falsy value: Oct 17 19:18:49 yechezkel volumio[1088]: assert.ok(self.idling) Oct 17 19:18:49 yechezkel volumio[1088]: error: MPD error: The expression evaluated to a falsy value: Oct 17 19:18:49 yechezkel volumio[1088]: assert.ok(self.idling) Oct 17 19:18:49 yechezkel volumio[1088]: error: The expression evaluated to a falsy value: Oct 17 19:18:49 yechezkel volumio[1088]: assert.ok(self.idling) Oct 17 19:18:49 yechezkel volumio[1088]: error: updateQueue error: null Oct 17 19:18:49 yechezkel volumio[1088]: Unable to parse: Oct 17 19:18:49 yechezkel volumio[1088]: Simple mixer control 'AES Rate',0 Oct 17 19:18:49 yechezkel volumio[1088]: Capabilities: volume volume-joined Oct 17 19:18:49 yechezkel volumio[1088]: Playback channels: Mono Oct 17 19:18:49 yechezkel volumio[1088]: Capture channels: Mono Oct 17 19:18:49 yechezkel volumio[1088]: Limits: 0 - 200000 Oct 17 19:18:49 yechezkel volumio[1088]: Mono: 0 [0%] Oct 17 19:18:49 yechezkel volumio[1088]: info: VolumeController:: Volume=undefined Mute =false Oct 17 19:18:49 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:49 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:49 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:18:49 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:49 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:49 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:49 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:49 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:49 yechezkel volumio[1088]: info: CoreStateMachine::updateTrackBlock Oct 17 19:18:49 yechezkel volumio[1088]: info: CorePlayQueue::getTrackBlock Oct 17 19:18:49 yechezkel volumio[1088]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 19:18:49 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:49 yechezkel volumio[1088]: Unable to parse: Oct 17 19:18:49 yechezkel volumio[1088]: Simple mixer control 'AES Rate',0 Oct 17 19:18:49 yechezkel volumio[1088]: Capabilities: volume volume-joined Oct 17 19:18:49 yechezkel volumio[1088]: Playback channels: Mono Oct 17 19:18:49 yechezkel volumio[1088]: Capture channels: Mono Oct 17 19:18:49 yechezkel volumio[1088]: Limits: 0 - 200000 Oct 17 19:18:49 yechezkel volumio[1088]: Mono: 0 [0%] Oct 17 19:18:49 yechezkel volumio[1088]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 17 19:18:49 yechezkel volumio[1088]: Unable to parse: Oct 17 19:18:49 yechezkel volumio[1088]: Simple mixer control 'AES Rate',0 Oct 17 19:18:49 yechezkel volumio[1088]: Capabilities: volume volume-joined Oct 17 19:18:49 yechezkel volumio[1088]: Playback channels: Mono Oct 17 19:18:49 yechezkel volumio[1088]: Capture channels: Mono Oct 17 19:18:49 yechezkel volumio[1088]: Limits: 0 - 200000 Oct 17 19:18:49 yechezkel volumio[1088]: Mono: 0 [0%] Oct 17 19:18:49 yechezkel volumio[1088]: info: VolumeController:: Volume=undefined Mute =false Oct 17 19:18:49 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:49 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:49 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:49 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:49 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:49 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:49 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:49 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:50 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPlay Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::play index undefined Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::startPlaybackTimer Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::clearAddPlayTracks USB/18music/religiosa/z/zelenka/Zelenka - Lamentations (Hyperion)/12 Zelenka - Lamentations 2=1 - 04.mp3 Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand stop took 0 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 19:18:50 yechezkel volumio[1088]: info: Oct 17 19:18:50 yechezkel volumio[1088]: ---------------------------- MPD announces system playlist update Oct 17 19:18:50 yechezkel volumio[1088]: info: Ignoring MPD Status Update Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand clear took 1 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand add "USB/18music/religiosa/z/zelenka/Zelenka - Lamentations (Hyperion)/12 Zelenka - Lamentations 2=1 - 04.mp3" Oct 17 19:18:50 yechezkel volumio[1088]: info: Oct 17 19:18:50 yechezkel volumio[1088]: ---------------------------- MPD announces system playlist update Oct 17 19:18:50 yechezkel volumio[1088]: info: Ignoring MPD Status Update Oct 17 19:18:50 yechezkel volumio[1088]: info: Oct 17 19:18:50 yechezkel volumio[1088]: ---------------------------- MPD announces system playlist update Oct 17 19:18:50 yechezkel volumio[1088]: info: Ignoring MPD Status Update Oct 17 19:18:50 yechezkel volumio[1088]: info: Oct 17 19:18:50 yechezkel volumio[1088]: ---------------------------- MPD announces system playlist update Oct 17 19:18:50 yechezkel volumio[1088]: info: Ignoring MPD Status Update Oct 17 19:18:50 yechezkel volumio[1088]: error: updateQueue error: null Oct 17 19:18:50 yechezkel volumio[1088]: info: Oct 17 19:18:50 yechezkel volumio[1088]: ---------------------------- MPD announces system playlist update Oct 17 19:18:50 yechezkel volumio[1088]: info: Ignoring MPD Status Update Oct 17 19:18:50 yechezkel volumio[1088]: info: ------------------------------ 2ms Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand add "USB/18music/religiosa/z/zelenka/Zelenka - Lamentations (Hyperion)/12 Zelenka - Lamentations 2=1 - 04.mp3" took 1 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: info: ------------------------------ 1ms Oct 17 19:18:50 yechezkel volumio[1088]: info: ------------------------------ 1ms Oct 17 19:18:50 yechezkel volumio[1088]: info: ------------------------------ 0ms Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand play Oct 17 19:18:50 yechezkel volumio[1088]: info: Oct 17 19:18:50 yechezkel volumio[1088]: ---------------------------- MPD announces system playlist update Oct 17 19:18:50 yechezkel volumio[1088]: info: Ignoring MPD Status Update Oct 17 19:18:50 yechezkel volumio[1088]: info: Oct 17 19:18:50 yechezkel volumio[1088]: ---------------------------- MPD announces system playlist update Oct 17 19:18:50 yechezkel volumio[1088]: info: Ignoring MPD Status Update Oct 17 19:18:50 yechezkel volumio[1088]: info: Oct 17 19:18:50 yechezkel volumio[1088]: ---------------------------- MPD announces system playlist update Oct 17 19:18:50 yechezkel volumio[1088]: info: Ignoring MPD Status Update Oct 17 19:18:50 yechezkel volumio[1088]: info: ------------------------------ 2ms Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand play took 1 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: info: ------------------------------ 1ms Oct 17 19:18:50 yechezkel volumio[1088]: info: ------------------------------ 1ms Oct 17 19:18:50 yechezkel volumio[1088]: info: ------------------------------ 1ms Oct 17 19:18:50 yechezkel volumio[1088]: info: Oct 17 19:18:50 yechezkel volumio[1088]: ---------------------------- MPD announces state update: player Oct 17 19:18:50 yechezkel volumio[1088]: info: ControllerMpd::getState Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:18:50 yechezkel volumio[1088]: info: Oct 17 19:18:50 yechezkel volumio[1088]: ---------------------------- MPD announces state update: player Oct 17 19:18:50 yechezkel volumio[1088]: info: ControllerMpd::getState Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:18:50 yechezkel volumio[1088]: info: Oct 17 19:18:50 yechezkel volumio[1088]: ---------------------------- MPD announces state update: player Oct 17 19:18:50 yechezkel volumio[1088]: info: ControllerMpd::getState Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:18:50 yechezkel volumio[1088]: info: Oct 17 19:18:50 yechezkel volumio[1088]: ---------------------------- MPD announces state update: player Oct 17 19:18:50 yechezkel volumio[1088]: info: ControllerMpd::getState Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:18:50 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:50 yechezkel volumio[1088]: info: Oct 17 19:18:50 yechezkel volumio[1088]: ---------------------------- MPD announces state update: player Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand status took 21 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: info: ControllerMpd::getState Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand status took 22 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand status took 21 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::parseState Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::parseState Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::parseState Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::parseState Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:18:50 yechezkel volumio[1088]: info: Oct 17 19:18:50 yechezkel volumio[1088]: ---------------------------- MPD announces state update: player Oct 17 19:18:50 yechezkel volumio[1088]: info: ControllerMpd::getState Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:18:50 yechezkel volumio[1088]: info: Oct 17 19:18:50 yechezkel volumio[1088]: ---------------------------- MPD announces state update: player Oct 17 19:18:50 yechezkel volumio[1088]: info: ControllerMpd::getState Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:18:50 yechezkel volumio[1088]: info: Oct 17 19:18:50 yechezkel volumio[1088]: ---------------------------- MPD announces state update: player Oct 17 19:18:50 yechezkel volumio[1088]: info: ControllerMpd::getState Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand status took 3 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand status took 2 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::parseState Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::parseState Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:18:50 yechezkel volumio[1088]: info: ControllerMpd::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::servicePushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":134,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"04 Sederunt in terra","artist":"Zelenka - Lamentations 2=1","album":"Zelenka Lamentations(Hyperion)","uri":"USB/18music/religiosa/z/zelenka/Zelenka - Lamentations (Hyperion)/12 Zelenka - Lamentations 2=1 - 04.mp3","trackType":"mp3"} Oct 17 19:18:50 yechezkel volumio[1088]: verbose: CURRENT POSITION 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::syncState stateService play Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Oct 17 19:18:50 yechezkel volumio[1088]: info: ControllerMpd::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::servicePushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":134,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"04 Sederunt in terra","artist":"Zelenka - Lamentations 2=1","album":"Zelenka Lamentations(Hyperion)","uri":"USB/18music/religiosa/z/zelenka/Zelenka - Lamentations (Hyperion)/12 Zelenka - Lamentations 2=1 - 04.mp3","trackType":"mp3"} Oct 17 19:18:50 yechezkel volumio[1088]: verbose: CURRENT POSITION 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::syncState stateService play Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::syncState currentStatus play Oct 17 19:18:50 yechezkel volumio[1088]: info: Received an update from plugin. extracting info from payload Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: ControllerMpd::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::servicePushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":134,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"04 Sederunt in terra","artist":"Zelenka - Lamentations 2=1","album":"Zelenka Lamentations(Hyperion)","uri":"USB/18music/religiosa/z/zelenka/Zelenka - Lamentations (Hyperion)/12 Zelenka - Lamentations 2=1 - 04.mp3","trackType":"mp3"} Oct 17 19:18:50 yechezkel volumio[1088]: verbose: CURRENT POSITION 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::syncState stateService play Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::syncState currentStatus play Oct 17 19:18:50 yechezkel volumio[1088]: info: Received an update from plugin. extracting info from payload Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: ControllerMpd::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::servicePushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":134,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"04 Sederunt in terra","artist":"Zelenka - Lamentations 2=1","album":"Zelenka Lamentations(Hyperion)","uri":"USB/18music/religiosa/z/zelenka/Zelenka - Lamentations (Hyperion)/12 Zelenka - Lamentations 2=1 - 04.mp3","trackType":"mp3"} Oct 17 19:18:50 yechezkel volumio[1088]: verbose: CURRENT POSITION 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::syncState stateService play Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::syncState currentStatus play Oct 17 19:18:50 yechezkel volumio[1088]: info: Received an update from plugin. extracting info from payload Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: ------------------------------ 33ms Oct 17 19:18:50 yechezkel volumio[1088]: info: ------------------------------ 42ms Oct 17 19:18:50 yechezkel volumio[1088]: info: ------------------------------ 42ms Oct 17 19:18:50 yechezkel volumio[1088]: info: ------------------------------ 42ms Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand status took 19 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand status took 19 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand playlistinfo took 17 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::parseState Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::parseState Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:18:50 yechezkel volumio[1088]: info: ControllerMpd::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::servicePushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":134,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"04 Sederunt in terra","artist":"Zelenka - Lamentations 2=1","album":"Zelenka Lamentations(Hyperion)","uri":"USB/18music/religiosa/z/zelenka/Zelenka - Lamentations (Hyperion)/12 Zelenka - Lamentations 2=1 - 04.mp3","trackType":"mp3"} Oct 17 19:18:50 yechezkel volumio[1088]: verbose: CURRENT POSITION 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::syncState stateService play Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::syncState currentStatus play Oct 17 19:18:50 yechezkel volumio[1088]: info: Received an update from plugin. extracting info from payload Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: ControllerMpd::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::servicePushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":134,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"04 Sederunt in terra","artist":"Zelenka - Lamentations 2=1","album":"Zelenka Lamentations(Hyperion)","uri":"USB/18music/religiosa/z/zelenka/Zelenka - Lamentations (Hyperion)/12 Zelenka - Lamentations 2=1 - 04.mp3","trackType":"mp3"} Oct 17 19:18:50 yechezkel volumio[1088]: verbose: CURRENT POSITION 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::syncState stateService play Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::syncState currentStatus play Oct 17 19:18:50 yechezkel volumio[1088]: info: Received an update from plugin. extracting info from payload Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: ------------------------------ 31ms Oct 17 19:18:50 yechezkel volumio[1088]: info: ------------------------------ 29ms Oct 17 19:18:50 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:50 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:50 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:50 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:50 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:50 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 17 19:18:50 yechezkel volumio[1088]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:18:50 yechezkel volumio[1088]: info: ControllerMpd::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::servicePushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":134,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"04 Sederunt in terra","artist":"Zelenka - Lamentations 2=1","album":"Zelenka Lamentations(Hyperion)","uri":"USB/18music/religiosa/z/zelenka/Zelenka - Lamentations (Hyperion)/12 Zelenka - Lamentations 2=1 - 04.mp3","trackType":"mp3"} Oct 17 19:18:50 yechezkel volumio[1088]: verbose: CURRENT POSITION 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::syncState stateService play Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::syncState currentStatus play Oct 17 19:18:50 yechezkel volumio[1088]: info: Received an update from plugin. extracting info from payload Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: ControllerMpd::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::servicePushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":134,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"04 Sederunt in terra","artist":"Zelenka - Lamentations 2=1","album":"Zelenka Lamentations(Hyperion)","uri":"USB/18music/religiosa/z/zelenka/Zelenka - Lamentations (Hyperion)/12 Zelenka - Lamentations 2=1 - 04.mp3","trackType":"mp3"} Oct 17 19:18:50 yechezkel volumio[1088]: verbose: CURRENT POSITION 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::syncState stateService play Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::syncState currentStatus play Oct 17 19:18:50 yechezkel volumio[1088]: info: Received an update from plugin. extracting info from payload Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:50 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:50 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:50 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:50 yechezkel volumio[1088]: info: ------------------------------ 61ms Oct 17 19:18:50 yechezkel volumio[1088]: info: ------------------------------ 61ms Oct 17 19:18:50 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:50 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:50 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:50 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:50 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:50 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:50 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:50 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:50 yechezkel volumio[1088]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:50 yechezkel volumio[1088]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:50 yechezkel sudo[16250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 17 19:18:50 yechezkel sudo[16250]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:50 yechezkel sudo[16250]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:50 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:50 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:50 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:50 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:50 yechezkel volumio[1088]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:50 yechezkel volumio[1088]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:50 yechezkel sudo[16266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 17 19:18:50 yechezkel sudo[16266]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:51 yechezkel sudo[16266]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:51 yechezkel volumio[1088]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:51 yechezkel volumio[1088]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:51 yechezkel sudo[16274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 17 19:18:51 yechezkel sudo[16274]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:51 yechezkel sudo[16274]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:51 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:51 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:51 yechezkel volumio[1088]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:51 yechezkel volumio[1088]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:51 yechezkel sudo[16289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 17 19:18:51 yechezkel sudo[16289]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:51 yechezkel sudo[16289]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:51 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:51 yechezkel volumio[1088]: info: TidalConnect service stoped! Oct 17 19:18:51 yechezkel volumio[1088]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:51 yechezkel volumio[1088]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 17 19:18:51 yechezkel volumio[1088]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 17 19:18:51 yechezkel volumio[1088]: info: CoreStateMachine::getcurrentVolume Oct 17 19:18:51 yechezkel volumio[1088]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 19:18:51 yechezkel sudo[16301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 17 19:18:51 yechezkel sudo[16301]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 19:18:51 yechezkel sudo[16301]: pam_unix(sudo:session): session closed for user root Oct 17 19:18:51 yechezkel volumio[1088]: Unable to parse: Oct 17 19:18:51 yechezkel volumio[1088]: Simple mixer control 'AES Rate',0 Oct 17 19:18:51 yechezkel volumio[1088]: Capabilities: volume volume-joined Oct 17 19:18:51 yechezkel volumio[1088]: Playback channels: Mono Oct 17 19:18:51 yechezkel volumio[1088]: Capture channels: Mono Oct 17 19:18:51 yechezkel volumio[1088]: Limits: 0 - 200000 Oct 17 19:18:51 yechezkel volumio[1088]: Mono: 0 [0%] Oct 17 19:18:51 yechezkel volumio[1088]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 17 19:18:51 yechezkel volumio[1088]: info: TidalConnect service started! Oct 17 19:18:51 yechezkel volumio[1088]: Unable to parse: Oct 17 19:18:51 yechezkel volumio[1088]: Simple mixer control 'AES Rate',0 Oct 17 19:18:51 yechezkel volumio[1088]: Capabilities: volume volume-joined Oct 17 19:18:51 yechezkel volumio[1088]: Playback channels: Mono Oct 17 19:18:51 yechezkel volumio[1088]: Capture channels: Mono Oct 17 19:18:51 yechezkel volumio[1088]: Limits: 0 - 200000 Oct 17 19:18:51 yechezkel volumio[1088]: Mono: 0 [0%] Oct 17 19:18:51 yechezkel volumio[1088]: info: VolumeController:: Volume=undefined Mute =false Oct 17 19:18:51 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:51 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:51 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:18:51 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:51 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:51 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:51 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:51 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:51 yechezkel volumio[1088]: info: CoreStateMachine::updateTrackBlock Oct 17 19:18:51 yechezkel volumio[1088]: info: CorePlayQueue::getTrackBlock Oct 17 19:18:51 yechezkel volumio[1088]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 19:18:51 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:51 yechezkel volumio[1088]: Unable to parse: Oct 17 19:18:51 yechezkel volumio[1088]: Simple mixer control 'AES Rate',0 Oct 17 19:18:51 yechezkel volumio[1088]: Capabilities: volume volume-joined Oct 17 19:18:51 yechezkel volumio[1088]: Playback channels: Mono Oct 17 19:18:51 yechezkel volumio[1088]: Capture channels: Mono Oct 17 19:18:51 yechezkel volumio[1088]: Limits: 0 - 200000 Oct 17 19:18:51 yechezkel volumio[1088]: Mono: 0 [0%] Oct 17 19:18:51 yechezkel volumio[1088]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 17 19:18:51 yechezkel volumio[1088]: info: TidalConnect service started! Oct 17 19:18:51 yechezkel volumio[1088]: Unable to parse: Oct 17 19:18:51 yechezkel volumio[1088]: Simple mixer control 'AES Rate',0 Oct 17 19:18:51 yechezkel volumio[1088]: Capabilities: volume volume-joined Oct 17 19:18:51 yechezkel volumio[1088]: Playback channels: Mono Oct 17 19:18:51 yechezkel volumio[1088]: Capture channels: Mono Oct 17 19:18:51 yechezkel volumio[1088]: Limits: 0 - 200000 Oct 17 19:18:51 yechezkel volumio[1088]: Mono: 0 [0%] Oct 17 19:18:51 yechezkel volumio[1088]: info: VolumeController:: Volume=undefined Mute =false Oct 17 19:18:51 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:51 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:51 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:51 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:51 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:51 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:51 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:51 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:51 yechezkel volumio[1088]: info: TidalConnect service started! Oct 17 19:18:51 yechezkel volumio[1088]: info: TidalConnect service started! Oct 17 19:18:51 yechezkel volumio[1088]: info: TidalConnect service started! Oct 17 19:18:52 yechezkel volumio[1088]: info: TidalConnect service started! Oct 17 19:18:52 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 19:18:53 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:18:53 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 1. Oct 17 19:18:53 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:53 yechezkel volumio[1088]: info: TidalConnect service started! Oct 17 19:18:53 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:53 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:53 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:53 yechezkel qobuz-connect[16308]: 20251017 19:18:53.703 [16308.16308] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:18:53 yechezkel qobuz-connect[16308]: 20251017 19:18:53.705 [16308.16308] INFO VolumeManager: [0x1685948]: Setting new playback volume: 75 Oct 17 19:18:53 yechezkel qobuz-connect[16308]: 20251017 19:18:53.705 [16308.16308] INFO VolumeManager: [0x1685948]: Setting new mute state: 0 Oct 17 19:18:53 yechezkel qobuz-connect[16308]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:18:53 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:18:53 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:18:54 yechezkel volumio[1088]: info: TidalConnect service started! Oct 17 19:18:54 yechezkel volumio[1088]: info: TidalConnect service started! Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioSeek Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::seek Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: TRACKBLOCK {"uri":"mnt/USB/18music/religiosa/z/zelenka/Zelenka - Lamentations (Hyperion)/12 Zelenka - Lamentations 2=1 - 04.mp3","service":"mpd","name":"04 Sederunt in terra","artist":"Zelenka - Lamentations 2=1","album":"Zelenka Lamentations(Hyperion)","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=475&web=Zelenka%20-%20Lamentations%202%3D1/Zelenka%20Lamentations(Hyperion)/extralarge&path=%2FUSB%2F18music%2Freligiosa%2Fz%2Fzelenka%2FZelenka%20-%20Lamentations%20(Hyperion)&metadata=false","duration":134,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::startPlaybackTimer Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: ControllerMpd::seek Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:54 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:54 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:54 yechezkel volumio[1088]: error: null Oct 17 19:18:54 yechezkel volumio[1088]: info: Oct 17 19:18:54 yechezkel volumio[1088]: ---------------------------- MPD announces state update: player Oct 17 19:18:54 yechezkel volumio[1088]: info: ControllerMpd::getState Oct 17 19:18:54 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:18:54 yechezkel volumio[1088]: info: Oct 17 19:18:54 yechezkel volumio[1088]: ---------------------------- MPD announces state update: player Oct 17 19:18:54 yechezkel volumio[1088]: info: ControllerMpd::getState Oct 17 19:18:54 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:18:54 yechezkel volumio[1088]: info: Oct 17 19:18:54 yechezkel volumio[1088]: ---------------------------- MPD announces state update: player Oct 17 19:18:54 yechezkel volumio[1088]: info: ControllerMpd::getState Oct 17 19:18:54 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:18:54 yechezkel volumio[1088]: info: Oct 17 19:18:54 yechezkel volumio[1088]: ---------------------------- MPD announces state update: player Oct 17 19:18:54 yechezkel volumio[1088]: info: ControllerMpd::getState Oct 17 19:18:54 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Oct 17 19:18:54 yechezkel volumio[1088]: info: sendMpdCommand status took 1 milliseconds Oct 17 19:18:54 yechezkel volumio[1088]: info: sendMpdCommand status took 1 milliseconds Oct 17 19:18:54 yechezkel volumio[1088]: info: sendMpdCommand status took 1 milliseconds Oct 17 19:18:54 yechezkel volumio[1088]: info: sendMpdCommand status took 0 milliseconds Oct 17 19:18:54 yechezkel volumio[1088]: verbose: ControllerMpd::parseState Oct 17 19:18:54 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:18:54 yechezkel volumio[1088]: verbose: ControllerMpd::parseState Oct 17 19:18:54 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:18:54 yechezkel volumio[1088]: verbose: ControllerMpd::parseState Oct 17 19:18:54 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:18:54 yechezkel volumio[1088]: verbose: ControllerMpd::parseState Oct 17 19:18:54 yechezkel volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 19:18:54 yechezkel volumio[1088]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 17 19:18:54 yechezkel volumio[1088]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 17 19:18:54 yechezkel volumio[1088]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 17 19:18:54 yechezkel volumio[1088]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 17 19:18:54 yechezkel volumio[1088]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:18:54 yechezkel volumio[1088]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:18:54 yechezkel volumio[1088]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:18:54 yechezkel volumio[1088]: verbose: ControllerMpd::parseTrackInfo Oct 17 19:18:54 yechezkel volumio[1088]: info: ControllerMpd::pushState Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::servicePushState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68000,"duration":134,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"04 Sederunt in terra","artist":"Zelenka - Lamentations 2=1","album":"Zelenka Lamentations(Hyperion)","uri":"USB/18music/religiosa/z/zelenka/Zelenka - Lamentations (Hyperion)/12 Zelenka - Lamentations 2=1 - 04.mp3","trackType":"mp3"} Oct 17 19:18:54 yechezkel volumio[1088]: verbose: CURRENT POSITION 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::syncState stateService play Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::syncState currentStatus play Oct 17 19:18:54 yechezkel volumio[1088]: info: Received an update from plugin. extracting info from payload Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:54 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:54 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:54 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:54 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: ControllerMpd::pushState Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::servicePushState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68000,"duration":134,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"04 Sederunt in terra","artist":"Zelenka - Lamentations 2=1","album":"Zelenka Lamentations(Hyperion)","uri":"USB/18music/religiosa/z/zelenka/Zelenka - Lamentations (Hyperion)/12 Zelenka - Lamentations 2=1 - 04.mp3","trackType":"mp3"} Oct 17 19:18:54 yechezkel volumio[1088]: verbose: CURRENT POSITION 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::syncState stateService play Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::syncState currentStatus play Oct 17 19:18:54 yechezkel volumio[1088]: info: Received an update from plugin. extracting info from payload Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:54 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:54 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:54 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:54 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: ControllerMpd::pushState Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::servicePushState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68000,"duration":134,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"04 Sederunt in terra","artist":"Zelenka - Lamentations 2=1","album":"Zelenka Lamentations(Hyperion)","uri":"USB/18music/religiosa/z/zelenka/Zelenka - Lamentations (Hyperion)/12 Zelenka - Lamentations 2=1 - 04.mp3","trackType":"mp3"} Oct 17 19:18:54 yechezkel volumio[1088]: verbose: CURRENT POSITION 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::syncState stateService play Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::syncState currentStatus play Oct 17 19:18:54 yechezkel volumio[1088]: info: Received an update from plugin. extracting info from payload Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:54 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:54 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:54 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:54 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: ControllerMpd::pushState Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::servicePushState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68000,"duration":134,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"04 Sederunt in terra","artist":"Zelenka - Lamentations 2=1","album":"Zelenka Lamentations(Hyperion)","uri":"USB/18music/religiosa/z/zelenka/Zelenka - Lamentations (Hyperion)/12 Zelenka - Lamentations 2=1 - 04.mp3","trackType":"mp3"} Oct 17 19:18:54 yechezkel volumio[1088]: verbose: CURRENT POSITION 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::syncState stateService play Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::syncState currentStatus play Oct 17 19:18:54 yechezkel volumio[1088]: info: Received an update from plugin. extracting info from payload Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:54 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:54 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreStateMachine::pushState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioPushState Oct 17 19:18:54 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output update for this device Oct 17 19:18:54 yechezkel volumio[1088]: info: MRS: Pushing multiroomSync output Oct 17 19:18:54 yechezkel volumio[1088]: info: CoreCommandRouter::volumioGetState Oct 17 19:18:54 yechezkel volumio[1088]: info: CorePlayQueue::getTrack 11 Oct 17 19:18:54 yechezkel volumio[1088]: info: ------------------------------ 24ms Oct 17 19:18:54 yechezkel volumio[1088]: info: ------------------------------ 23ms Oct 17 19:18:54 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:54 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:54 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:54 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:54 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:54 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:54 yechezkel volumio[1088]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 17 19:18:54 yechezkel volumio[1088]: info: TidalConnect service started! Oct 17 19:18:54 yechezkel volumio[1088]: info: TidalConnect service started! Oct 17 19:18:58 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:18:58 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 2. Oct 17 19:18:58 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:18:58 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:18:58 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:18:58 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:18:58 yechezkel qobuz-connect[16310]: 20251017 19:18:58.950 [16310.16310] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:18:58 yechezkel qobuz-connect[16310]: 20251017 19:18:58.952 [16310.16310] INFO VolumeManager: [0x145c948]: Setting new playback volume: 75 Oct 17 19:18:58 yechezkel qobuz-connect[16310]: 20251017 19:18:58.952 [16310.16310] INFO VolumeManager: [0x145c948]: Setting new mute state: 0 Oct 17 19:18:58 yechezkel qobuz-connect[16310]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:18:58 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:18:58 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:19:02 yechezkel go-librespot[2373]: time="2025-10-17T19:19:02+03:00" level=trace msg="sent dealer ping" Oct 17 19:19:02 yechezkel go-librespot[2373]: time="2025-10-17T19:19:02+03:00" level=trace msg="received dealer pong" Oct 17 19:19:04 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:19:04 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 3. Oct 17 19:19:04 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:19:04 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:19:04 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:19:04 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:19:04 yechezkel qobuz-connect[16326]: 20251017 19:19:04.198 [16326.16326] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:19:04 yechezkel qobuz-connect[16326]: 20251017 19:19:04.200 [16326.16326] INFO VolumeManager: [0x24c5948]: Setting new playback volume: 75 Oct 17 19:19:04 yechezkel qobuz-connect[16326]: 20251017 19:19:04.201 [16326.16326] INFO VolumeManager: [0x24c5948]: Setting new mute state: 0 Oct 17 19:19:04 yechezkel qobuz-connect[16326]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:19:04 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:19:04 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:19:09 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:19:09 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 4. Oct 17 19:19:09 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:19:09 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:19:09 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:19:09 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:19:09 yechezkel qobuz-connect[16328]: 20251017 19:19:09.446 [16328.16328] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:19:09 yechezkel qobuz-connect[16328]: 20251017 19:19:09.448 [16328.16328] INFO VolumeManager: [0x83b948]: Setting new playback volume: 75 Oct 17 19:19:09 yechezkel qobuz-connect[16328]: 20251017 19:19:09.448 [16328.16328] INFO VolumeManager: [0x83b948]: Setting new mute state: 0 Oct 17 19:19:09 yechezkel qobuz-connect[16328]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:19:09 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:19:09 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:19:09 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 17 19:19:09 yechezkel volumio[1088]: info: CURURI: music-library/USB Oct 17 19:19:09 yechezkel volumio[1088]: info: Preload queue cleared Oct 17 19:19:14 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:19:14 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 5. Oct 17 19:19:14 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:19:14 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:19:14 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:19:14 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:19:14 yechezkel qobuz-connect[16348]: 20251017 19:19:14.698 [16348.16348] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:19:14 yechezkel qobuz-connect[16348]: 20251017 19:19:14.700 [16348.16348] INFO VolumeManager: [0x135e948]: Setting new playback volume: 75 Oct 17 19:19:14 yechezkel qobuz-connect[16348]: 20251017 19:19:14.700 [16348.16348] INFO VolumeManager: [0x135e948]: Setting new mute state: 0 Oct 17 19:19:14 yechezkel qobuz-connect[16348]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:19:14 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:19:14 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:19:18 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 17 19:19:18 yechezkel volumio[1088]: info: CURURI: music-library/USB/18music Oct 17 19:19:18 yechezkel volumio[1088]: info: Preload queue cleared Oct 17 19:19:19 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:19:19 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 6. Oct 17 19:19:19 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:19:19 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:19:19 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:19:19 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:19:19 yechezkel qobuz-connect[16350]: 20251017 19:19:19.942 [16350.16350] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:19:19 yechezkel qobuz-connect[16350]: 20251017 19:19:19.944 [16350.16350] INFO VolumeManager: [0x19ea948]: Setting new playback volume: 75 Oct 17 19:19:19 yechezkel qobuz-connect[16350]: 20251017 19:19:19.944 [16350.16350] INFO VolumeManager: [0x19ea948]: Setting new mute state: 0 Oct 17 19:19:19 yechezkel qobuz-connect[16350]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:19:19 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:19:19 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:19:25 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:19:25 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 7. Oct 17 19:19:25 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:19:25 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:19:25 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:19:25 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:19:25 yechezkel qobuz-connect[16366]: 20251017 19:19:25.206 [16366.16366] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:19:25 yechezkel qobuz-connect[16366]: 20251017 19:19:25.209 [16366.16366] INFO VolumeManager: [0x13f7948]: Setting new playback volume: 75 Oct 17 19:19:25 yechezkel qobuz-connect[16366]: 20251017 19:19:25.209 [16366.16366] INFO VolumeManager: [0x13f7948]: Setting new mute state: 0 Oct 17 19:19:25 yechezkel qobuz-connect[16366]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:19:25 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:19:25 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:19:28 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 17 19:19:28 yechezkel volumio[1088]: info: Preload queue cleared Oct 17 19:19:29 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 17 19:19:30 yechezkel volumio[1088]: info: Preload queue cleared Oct 17 19:19:30 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:19:30 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 8. Oct 17 19:19:30 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:19:30 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:19:30 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:19:30 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:19:30 yechezkel qobuz-connect[16383]: 20251017 19:19:30.455 [16383.16383] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:19:30 yechezkel qobuz-connect[16383]: 20251017 19:19:30.457 [16383.16383] INFO VolumeManager: [0x1a6c948]: Setting new playback volume: 75 Oct 17 19:19:30 yechezkel qobuz-connect[16383]: 20251017 19:19:30.458 [16383.16383] INFO VolumeManager: [0x1a6c948]: Setting new mute state: 0 Oct 17 19:19:30 yechezkel qobuz-connect[16383]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:19:30 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:19:30 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:19:32 yechezkel go-librespot[2373]: time="2025-10-17T19:19:32+03:00" level=trace msg="sent dealer ping" Oct 17 19:19:32 yechezkel go-librespot[2373]: time="2025-10-17T19:19:32+03:00" level=trace msg="received dealer pong" Oct 17 19:19:35 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:19:35 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 9. Oct 17 19:19:35 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:19:35 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:19:35 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:19:35 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:19:35 yechezkel qobuz-connect[16388]: 20251017 19:19:35.698 [16388.16388] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:19:35 yechezkel qobuz-connect[16388]: 20251017 19:19:35.700 [16388.16388] INFO VolumeManager: [0xd08948]: Setting new playback volume: 75 Oct 17 19:19:35 yechezkel qobuz-connect[16388]: 20251017 19:19:35.700 [16388.16388] INFO VolumeManager: [0xd08948]: Setting new mute state: 0 Oct 17 19:19:35 yechezkel qobuz-connect[16388]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:19:35 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:19:35 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:19:38 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 17 19:19:39 yechezkel volumio[1088]: error: Error browsing0:Error: Did not get expected response from server:{"Envelope":{"$":{"xmlns:s":"http://schemas.xmlsoap.org/soap/envelope/","s:encodingStyle":"http://schemas.xmlsoap.org/soap/encoding/"},"Body":[{"Fault":[{"faultcode":[{"_":"s:Client"}],"faultstring":[{"_":"UPnPError"}],"detail":[{"UPnPError":[{"$":{"xmlns":"urn:schemas-upnp-org:control-1-0"},"errorCode":[{"_":"501"}],"errorDescription":[{"_":"Action Failed"}]}]}]}]}]}} Oct 17 19:19:39 yechezkel volumio[1088]: error: Failed to execute browseSource: Oct 17 19:19:40 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:19:40 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 10. Oct 17 19:19:40 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:19:40 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:19:40 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:19:40 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:19:40 yechezkel qobuz-connect[16404]: 20251017 19:19:40.946 [16404.16404] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:19:40 yechezkel qobuz-connect[16404]: 20251017 19:19:40.948 [16404.16404] INFO VolumeManager: [0x227c948]: Setting new playback volume: 75 Oct 17 19:19:40 yechezkel qobuz-connect[16404]: 20251017 19:19:40.948 [16404.16404] INFO VolumeManager: [0x227c948]: Setting new mute state: 0 Oct 17 19:19:40 yechezkel qobuz-connect[16404]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:19:40 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:19:40 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:19:44 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 17 19:19:44 yechezkel volumio[1088]: error: Error browsing0:Error: Did not get expected response from server:{"Envelope":{"$":{"xmlns:s":"http://schemas.xmlsoap.org/soap/envelope/","s:encodingStyle":"http://schemas.xmlsoap.org/soap/encoding/"},"Body":[{"Fault":[{"faultcode":[{"_":"s:Client"}],"faultstring":[{"_":"UPnPError"}],"detail":[{"UPnPError":[{"$":{"xmlns":"urn:schemas-upnp-org:control-1-0"},"errorCode":[{"_":"501"}],"errorDescription":[{"_":"Action Failed"}]}]}]}]}]}} Oct 17 19:19:44 yechezkel volumio[1088]: error: Failed to execute browseSource: Oct 17 19:19:44 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 17 19:19:44 yechezkel volumio[1088]: error: Error browsing0:Error: Did not get expected response from server:{"Envelope":{"$":{"xmlns:s":"http://schemas.xmlsoap.org/soap/envelope/","s:encodingStyle":"http://schemas.xmlsoap.org/soap/encoding/"},"Body":[{"Fault":[{"faultcode":[{"_":"s:Client"}],"faultstring":[{"_":"UPnPError"}],"detail":[{"UPnPError":[{"$":{"xmlns":"urn:schemas-upnp-org:control-1-0"},"errorCode":[{"_":"501"}],"errorDescription":[{"_":"Action Failed"}]}]}]}]}]}} Oct 17 19:19:44 yechezkel volumio[1088]: error: Failed to execute browseSource: Oct 17 19:19:46 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 17 19:19:46 yechezkel volumio[1088]: info: Preload queue cleared Oct 17 19:19:46 yechezkel systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Oct 17 19:19:46 yechezkel systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 11. Oct 17 19:19:46 yechezkel systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 17 19:19:46 yechezkel systemd[1]: Started Volumio Qobuz Connect Service. Oct 17 19:19:46 yechezkel volumio[1088]: info: Executing endpoint qc_getconfig Oct 17 19:19:46 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 19:19:46 yechezkel qobuz-connect[16406]: 20251017 19:19:46.194 [16406.16406] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 19:19:46 yechezkel qobuz-connect[16406]: 20251017 19:19:46.196 [16406.16406] INFO VolumeManager: [0xe4f948]: Setting new playback volume: 75 Oct 17 19:19:46 yechezkel qobuz-connect[16406]: 20251017 19:19:46.196 [16406.16406] INFO VolumeManager: [0xe4f948]: Setting new mute state: 0 Oct 17 19:19:46 yechezkel qobuz-connect[16406]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Oct 17 19:19:46 yechezkel systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Oct 17 19:19:46 yechezkel systemd[1]: qobuz-connect.service: Failed with result 'signal'. Oct 17 19:19:48 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 17 19:19:48 yechezkel volumio[1088]: info: Preload queue cleared Oct 17 19:19:49 yechezkel volumio[1088]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 17 19:19:49 yechezkel volumio[1088]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 19:19:49 yechezkel volumio[1088]: TypeError: Cannot read property 'length' of undefined Oct 17 19:19:49 yechezkel volumio[1088]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 17 19:19:49 yechezkel volumio[1088]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 17 19:19:49 yechezkel volumio[1088]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 17 19:19:49 yechezkel volumio[1088]: at Parser.emit (events.js:315:20) Oct 17 19:19:49 yechezkel volumio[1088]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 17 19:19:49 yechezkel volumio[1088]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 17 19:19:49 yechezkel volumio[1088]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 17 19:19:49 yechezkel volumio[1088]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 17 19:19:49 yechezkel volumio[1088]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 17 19:19:49 yechezkel volumio[1088]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 17 19:19:49 yechezkel volumio[1088]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 17 19:19:49 yechezkel volumio[1088]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 17 19:19:49 yechezkel volumio[1088]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 17 19:19:49 yechezkel volumio[1088]: at IncomingMessage.emit (events.js:327:22) Oct 17 19:19:49 yechezkel volumio[1088]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 17 19:19:49 yechezkel volumio[1088]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 17 19:19:49 yechezkel volumio[1088]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 19:19:49 yechezkel sudo[16455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-17 19:18 Oct 17 19:19:49 yechezkel sudo[16455]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"