Jan 25 22:42:03 volumio go-librespot[1590]: time="2026-01-25T22:42:03+09:00" level=trace msg="sent dealer ping" Jan 25 22:42:03 volumio go-librespot[1590]: time="2026-01-25T22:42:03+09:00" level=trace msg="received dealer pong" Jan 25 22:42:33 volumio go-librespot[1590]: time="2026-01-25T22:42:33+09:00" level=trace msg="sent dealer ping" Jan 25 22:42:33 volumio go-librespot[1590]: time="2026-01-25T22:42:33+09:00" level=trace msg="received dealer pong" Jan 25 22:43:03 volumio go-librespot[1590]: time="2026-01-25T22:43:03+09:00" level=trace msg="sent dealer ping" Jan 25 22:43:03 volumio go-librespot[1590]: time="2026-01-25T22:43:03+09:00" level=trace msg="received dealer pong" Jan 25 22:43:05 volumio go-librespot[1590]: time="2026-01-25T22:43:05+09:00" level=trace msg="received accesspoint ping" Jan 25 22:43:05 volumio go-librespot[1590]: time="2026-01-25T22:43:05+09:00" level=trace msg="received accesspoint pong ack" Jan 25 22:43:28 volumio kernel: usb 1-1.1.2: new high-speed USB device number 5 using dwc_otg Jan 25 22:43:28 volumio kernel: usb 1-1.1.2: New USB device found, idVendor=279d, idProduct=0003, bcdDevice= 1.06 Jan 25 22:43:28 volumio kernel: usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jan 25 22:43:28 volumio kernel: usb 1-1.1.2: Product: Olasonic USB DAC(HD) Jan 25 22:43:28 volumio kernel: usb 1-1.1.2: Manufacturer: TOWA Electronics Jan 25 22:43:28 volumio kernel: hid-generic 0003:279D:0003.0001: No inputs registered, leaving Jan 25 22:43:28 volumio kernel: hid-generic 0003:279D:0003.0001: hidraw0: USB HID v1.00 Device [TOWA Electronics Olasonic USB DAC(HD)] on usb-3f980000.usb-1.1.2/input2 Jan 25 22:43:29 volumio kernel: usbcore: registered new interface driver snd-usb-audio Jan 25 22:43:29 volumio (udev-worker)[20975]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Jan 25 22:43:29 volumio volumio[1112]: info: Jan 25 22:43:29 volumio volumio[1112]: ---------------------------- USB Audio Device Attached Jan 25 22:43:29 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jan 25 22:43:29 volumio volumio[1112]: info: CoreCommandRouter::Close All Modals sent Jan 25 22:43:29 volumio volumio[1112]: info: Preparing to save Alsa Options, stopping services first Jan 25 22:43:29 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jan 25 22:43:29 volumio volumio[1112]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Olasonic USB DAC(HD)","alsacard":"DACHD"},"i2s":false} Jan 25 22:43:29 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 22:43:29 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 22:43:29 volumio volumio[1112]: info: Setting mixer PCM for card Olasonic USB DAC(HD) Jan 25 22:43:29 volumio volumio[1112]: info: QobuzConnect: setDeactiveState invoked Jan 25 22:43:29 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jan 25 22:43:29 volumio vtcs[2453]: [2026-01-25 22:43:29.849] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jan 25 22:43:29 volumio vtcs[2453]: [2026-01-25 22:43:29.850] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jan 25 22:43:29 volumio sudo[21052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 25 22:43:29 volumio sudo[21052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:29 volumio volumio[1112]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 25 22:43:29 volumio volumio[1112]: info: Updating Volume Controller Parameters: Device: 5 Name: Olasonic USB DAC(HD) Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 25 22:43:29 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 25 22:43:29 volumio volumio[1112]: info: Disabling external Volume Control Jan 25 22:43:29 volumio volumio[1112]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 25 22:43:29 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jan 25 22:43:30 volumio volumio[1112]: info: Preparing to generate the ALSA configuration file Jan 25 22:43:30 volumio systemd[1]: vtcs.service: Deactivated successfully. Jan 25 22:43:30 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jan 25 22:43:30 volumio systemd[1]: vtcs.service: Consumed 1min 29.552s CPU time. Jan 25 22:43:30 volumio sudo[21052]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:30 volumio sudo[21061]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 25 22:43:30 volumio sudo[21061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:30 volumio volumio[1112]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 25 22:43:30 volumio volumio[1112]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 25 22:43:30 volumio volumio[1112]: info: Reading ALSA contributions from plugins. Jan 25 22:43:30 volumio volumio[1112]: Unable to parse: Jan 25 22:43:30 volumio volumio[1112]: Simple mixer control 'PCM',0 Jan 25 22:43:30 volumio volumio[1112]: Capabilities: pswitch pswitch-joined Jan 25 22:43:30 volumio volumio[1112]: Playback channels: Mono Jan 25 22:43:30 volumio volumio[1112]: Mono: Playback [on] Jan 25 22:43:30 volumio volumio[1112]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jan 25 22:43:30 volumio sudo[21068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 25 22:43:30 volumio sudo[21068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:30 volumio sudo[21061]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:30 volumio sudo[21068]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 25 22:43:30 volumio sudo[21076]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 25 22:43:30 volumio sudo[21076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 22:43:30 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 25 22:43:30 volumio qobuz-connect[2339]: 20260125 22:43:30.560 [2339.2339] INFO SampleApp: Stopping Local configuration server Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jan 25 22:43:30 volumio volumio[1112]: Unable to parse: Jan 25 22:43:30 volumio volumio[1112]: Simple mixer control 'PCM',0 Jan 25 22:43:30 volumio volumio[1112]: Capabilities: pswitch pswitch-joined Jan 25 22:43:30 volumio volumio[1112]: Playback channels: Mono Jan 25 22:43:30 volumio volumio[1112]: Mono: Playback [on] Jan 25 22:43:30 volumio volumio[1112]: info: VolumeController:: Volume=undefined Mute =false Jan 25 22:43:30 volumio volumio[1112]: info: CoreStateMachine::pushState Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jan 25 22:43:30 volumio volumio[1112]: info: MRS: Pushing multiroomSync output update for this device Jan 25 22:43:30 volumio volumio[1112]: info: MRS: Pushing multiroomSync output Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jan 25 22:43:30 volumio volumio[1112]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.zcj6LGJjqmbLzc1eitSt2oJqs7D3.c827ea0af3730513cfc294d1116504df.state.status' Jan 25 22:43:30 volumio volumio[1112]: info: Asound.conf file unchanged, so no further update is needed Jan 25 22:43:30 volumio volumio[1112]: info: Output device has changed, restarting MPD Jan 25 22:43:30 volumio volumio[1112]: info: Output device has changed, restarting Shairport Sync Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 22:43:30 volumio sudo[21084]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 25 22:43:30 volumio sudo[21084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:30 volumio sudo[21084]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:30 volumio sudo[21086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 25 22:43:30 volumio sudo[21086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:30 volumio volumio[1112]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 25 22:43:30 volumio volumio[1112]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:30 volumio volumio[1112]: info: QobuzConnect: setDeactiveState invoked Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jan 25 22:43:30 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 25 22:43:30 volumio upmpdcli[1397]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 25 22:43:30 volumio sudo[21096]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 25 22:43:30 volumio sudo[21096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:30 volumio sudo[21098]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 25 22:43:30 volumio sudo[21098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:30 volumio volumio[1112]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Jan 25 22:43:30 volumio volumio[1112]: info: MPD Permissions set Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:30 volumio sudo[21106]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 25 22:43:30 volumio sudo[21106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:31 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 22:43:31 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:31 volumio volumio[1112]: info: Starting Shairport Sync Jan 25 22:43:31 volumio sudo[21096]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:31 volumio sudo[21115]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 25 22:43:31 volumio sudo[21115]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:31 volumio sudo[21098]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:31 volumio sudo[21106]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:31 volumio qobuz-connect[2339]: 20260125 22:43:31.260 [2339.2339] INFO SampleApp: shat down connection on UNIX socket Jan 25 22:43:31 volumio volumio[1112]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 25 22:43:31 volumio volumio[1112]: info: QobuzConnect: setDeactiveState invoked Jan 25 22:43:31 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jan 25 22:43:31 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 25 22:43:31 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 25 22:43:31 volumio systemd[1]: mpd.service: Consumed 8.209s CPU time. Jan 25 22:43:31 volumio sudo[21119]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 25 22:43:31 volumio sudo[21119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:31 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 25 22:43:31 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 25 22:43:31 volumio systemd[1]: qobuz-connect.service: Consumed 2.500s CPU time. Jan 25 22:43:31 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 25 22:43:31 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 25 22:43:31 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 25 22:43:31 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 25 22:43:31 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 25 22:43:31 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 25 22:43:31 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 25 22:43:31 volumio sudo[21076]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:31 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 25 22:43:31 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 25 22:43:31 volumio systemd[1]: shairport-sync.service: Consumed 13min 51.923s CPU time. Jan 25 22:43:31 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 25 22:43:31 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 25 22:43:31 volumio sudo[21115]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:31 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 25 22:43:31 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 25 22:43:31 volumio volumio[1112]: info: Shairport-Sync Started Jan 25 22:43:31 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 25 22:43:31 volumio sudo[21119]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:31 volumio volumio[1112]: info: Executing endpoint qc_getconfig Jan 25 22:43:31 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 25 22:43:31 volumio qobuz-connect[21126]: 20260125 22:43:31.826 [21126.21126] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 25 22:43:31 volumio volumio[1112]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 25 22:43:31 volumio volumio[1112]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 25 22:43:31 volumio qobuz-connect[21126]: 20260125 22:43:31.846 [21126.21126] INFO VolumeManager: [0x157ccb8]: Setting new playback volume: 75 Jan 25 22:43:31 volumio qobuz-connect[21126]: 20260125 22:43:31.847 [21126.21126] INFO VolumeManager: [0x157ccb8]: Setting new mute state: 0 Jan 25 22:43:31 volumio qobuz-connect[21126]: 20260125 22:43:31.847 [21126.21126] INFO QobuzConnect: [0x157d688]: Client initialized! Jan 25 22:43:31 volumio qobuz-connect[21126]: 20260125 22:43:31.848 [21126.21126] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jan 25 22:43:31 volumio qobuz-connect[21126]: 20260125 22:43:31.898 [21126.21126] INFO LocalConfigManager: [0x157c598]: Starting Local Configuration server Jan 25 22:43:31 volumio qobuz-connect[21126]: 20260125 22:43:31.898 [21126.21126] INFO SampleApp: Starting Local configuration server Jan 25 22:43:31 volumio qobuz-connect[21126]: 20260125 22:43:31.899 [21126.21126] INFO SampleApp: Connected to UNIX socket client 0x1567818 Jan 25 22:43:31 volumio sudo[21123]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 25 22:43:31 volumio sudo[21123]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 25 22:43:31 volumio sudo[21123]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:31 volumio qobuz-connect[21126]: 20260125 22:43:31.977 [21126.21126] INFO SampleApp: Playback volume changed: 75 Jan 25 22:43:31 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jan 25 22:43:32 volumio kernel: usb 1-1.1.2: USB disconnect, device number 5 Jan 25 22:43:32 volumio volumio[1112]: info: Jan 25 22:43:32 volumio volumio[1112]: ---------------------------- USB Audio Device Detached Jan 25 22:43:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jan 25 22:43:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 22:43:33 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jan 25 22:43:33 volumio volumio[1112]: info: TidalConnect service stoped! Jan 25 22:43:33 volumio kernel: usb 1-1.1.2: new high-speed USB device number 6 using dwc_otg Jan 25 22:43:33 volumio go-librespot[1590]: time="2026-01-25T22:43:33+09:00" level=trace msg="sent dealer ping" Jan 25 22:43:33 volumio go-librespot[1590]: time="2026-01-25T22:43:33+09:00" level=trace msg="received dealer pong" Jan 25 22:43:33 volumio kernel: usb 1-1.1.2: New USB device found, idVendor=279d, idProduct=0002, bcdDevice= 1.06 Jan 25 22:43:33 volumio kernel: usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jan 25 22:43:33 volumio kernel: usb 1-1.1.2: Product: Olasonic USB DAC(HD) Jan 25 22:43:33 volumio kernel: usb 1-1.1.2: Manufacturer: TOWA Electronics Jan 25 22:43:33 volumio kernel: hid-generic 0003:279D:0002.0002: No inputs registered, leaving Jan 25 22:43:33 volumio kernel: hid-generic 0003:279D:0002.0002: hidraw0: USB HID v1.00 Device [TOWA Electronics Olasonic USB DAC(HD)] on usb-3f980000.usb-1.1.2/input2 Jan 25 22:43:33 volumio volumio[1112]: info: TidalConnect service stoped! Jan 25 22:43:33 volumio volumio[1112]: info: Jan 25 22:43:33 volumio volumio[1112]: ---------------------------- USB Audio Device Attached Jan 25 22:43:33 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jan 25 22:43:33 volumio volumio[1112]: info: CoreCommandRouter::Close All Modals sent Jan 25 22:43:33 volumio volumio[1112]: info: Preparing to save Alsa Options, stopping services first Jan 25 22:43:33 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jan 25 22:43:33 volumio volumio[1112]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Olasonic USB DAC(HD)","alsacard":"DACHD"},"i2s":false} Jan 25 22:43:33 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 22:43:33 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 22:43:33 volumio volumio[1112]: info: Setting mixer PCM for card Olasonic USB DAC(HD) Jan 25 22:43:33 volumio volumio[1112]: info: QobuzConnect: setDeactiveState invoked Jan 25 22:43:33 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jan 25 22:43:33 volumio sudo[21243]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 25 22:43:33 volumio volumio[1112]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 25 22:43:33 volumio volumio[1112]: info: Updating Volume Controller Parameters: Device: 5 Name: Olasonic USB DAC(HD) Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 25 22:43:33 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 25 22:43:33 volumio volumio[1112]: info: Disabling external Volume Control Jan 25 22:43:33 volumio sudo[21243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:33 volumio volumio[1112]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 25 22:43:33 volumio volumio[1112]: info: Preparing to generate the ALSA configuration file Jan 25 22:43:34 volumio sudo[21243]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:34 volumio sudo[21251]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 25 22:43:34 volumio sudo[21251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 25 22:43:34 volumio volumio[1112]: info: CoreStateMachine::getcurrentVolume Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::volumioRetrievevolume Jan 25 22:43:34 volumio volumio[1112]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 25 22:43:34 volumio volumio[1112]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 25 22:43:34 volumio volumio[1112]: info: Reading ALSA contributions from plugins. Jan 25 22:43:34 volumio sudo[21258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 25 22:43:34 volumio sudo[21258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:34 volumio volumio[1112]: Unable to parse: Jan 25 22:43:34 volumio volumio[1112]: Simple mixer control 'PCM',0 Jan 25 22:43:34 volumio volumio[1112]: Capabilities: pswitch pswitch-joined Jan 25 22:43:34 volumio volumio[1112]: Playback channels: Mono Jan 25 22:43:34 volumio volumio[1112]: Mono: Playback [on] Jan 25 22:43:34 volumio volumio[1112]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jan 25 22:43:34 volumio volumio[1112]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 25 22:43:34 volumio volumio[1112]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 25 22:43:34 volumio volumio[1112]: Unable to parse: Jan 25 22:43:34 volumio volumio[1112]: Simple mixer control 'PCM',0 Jan 25 22:43:34 volumio volumio[1112]: Capabilities: pswitch pswitch-joined Jan 25 22:43:34 volumio volumio[1112]: Playback channels: Mono Jan 25 22:43:34 volumio volumio[1112]: Mono: Playback [on] Jan 25 22:43:34 volumio volumio[1112]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jan 25 22:43:34 volumio volumio[1112]: info: TidalConnect service stoped! Jan 25 22:43:34 volumio volumio[1112]: info: TidalConnect service stoped! Jan 25 22:43:34 volumio sudo[21258]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:34 volumio sudo[21251]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:34 volumio sudo[21264]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 25 22:43:34 volumio sudo[21264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:34 volumio sudo[21272]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 25 22:43:34 volumio sudo[21272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:34 volumio qobuz-connect[21126]: 20260125 22:43:34.458 [21126.21126] INFO SampleApp: Stopping Local configuration server Jan 25 22:43:34 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 25 22:43:34 volumio volumio[1112]: Unable to parse: Jan 25 22:43:34 volumio volumio[1112]: Simple mixer control 'PCM',0 Jan 25 22:43:34 volumio volumio[1112]: Capabilities: pswitch pswitch-joined Jan 25 22:43:34 volumio volumio[1112]: Playback channels: Mono Jan 25 22:43:34 volumio volumio[1112]: Mono: Playback [on] Jan 25 22:43:34 volumio volumio[1112]: info: VolumeController:: Volume=undefined Mute =false Jan 25 22:43:34 volumio volumio[1112]: info: CoreStateMachine::pushState Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jan 25 22:43:34 volumio volumio[1112]: info: MRS: Pushing multiroomSync output update for this device Jan 25 22:43:34 volumio volumio[1112]: info: MRS: Pushing multiroomSync output Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jan 25 22:43:34 volumio volumio[1112]: info: CoreStateMachine::updateTrackBlock Jan 25 22:43:34 volumio volumio[1112]: info: CorePlayQueue::getTrackBlock Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::volumioRetrievevolume Jan 25 22:43:34 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jan 25 22:43:34 volumio volumio[1112]: Unable to parse: Jan 25 22:43:34 volumio volumio[1112]: Simple mixer control 'PCM',0 Jan 25 22:43:34 volumio volumio[1112]: Capabilities: pswitch pswitch-joined Jan 25 22:43:34 volumio volumio[1112]: Playback channels: Mono Jan 25 22:43:34 volumio volumio[1112]: Mono: Playback [on] Jan 25 22:43:34 volumio volumio[1112]: info: VolumeController:: Volume=undefined Mute =false Jan 25 22:43:34 volumio volumio[1112]: info: CoreStateMachine::pushState Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jan 25 22:43:34 volumio volumio[1112]: info: MRS: Pushing multiroomSync output update for this device Jan 25 22:43:34 volumio volumio[1112]: info: MRS: Pushing multiroomSync output Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jan 25 22:43:34 volumio sudo[21272]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jan 25 22:43:34 volumio volumio[1112]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.zcj6LGJjqmbLzc1eitSt2oJqs7D3.c827ea0af3730513cfc294d1116504df.state.status' Jan 25 22:43:34 volumio volumio[1112]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.zcj6LGJjqmbLzc1eitSt2oJqs7D3.c827ea0af3730513cfc294d1116504df.state.status' Jan 25 22:43:34 volumio volumio[1112]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 25 22:43:34 volumio volumio[1112]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 25 22:43:34 volumio volumio[1112]: Unable to parse: Jan 25 22:43:34 volumio volumio[1112]: Simple mixer control 'PCM',0 Jan 25 22:43:34 volumio volumio[1112]: Capabilities: pswitch pswitch-joined Jan 25 22:43:34 volumio volumio[1112]: Playback channels: Mono Jan 25 22:43:34 volumio volumio[1112]: Mono: Playback [on] Jan 25 22:43:34 volumio volumio[1112]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jan 25 22:43:34 volumio volumio[1112]: info: Asound.conf file unchanged, so no further update is needed Jan 25 22:43:34 volumio volumio[1112]: info: Output device has changed, restarting MPD Jan 25 22:43:34 volumio volumio[1112]: info: Output device has changed, restarting Shairport Sync Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 22:43:34 volumio volumio[1112]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 25 22:43:34 volumio volumio[1112]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:34 volumio sudo[21296]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 25 22:43:34 volumio sudo[21296]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:34 volumio volumio[1112]: info: QobuzConnect: setDeactiveState invoked Jan 25 22:43:34 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jan 25 22:43:34 volumio sudo[21299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 25 22:43:34 volumio sudo[21296]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:34 volumio sudo[21299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:35 volumio sudo[21308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 25 22:43:35 volumio sudo[21308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:35 volumio volumio[1112]: info: MPD Permissions set Jan 25 22:43:35 volumio volumio[1112]: Unable to parse: Jan 25 22:43:35 volumio volumio[1112]: Simple mixer control 'PCM',0 Jan 25 22:43:35 volumio volumio[1112]: Capabilities: pswitch pswitch-joined Jan 25 22:43:35 volumio volumio[1112]: Playback channels: Mono Jan 25 22:43:35 volumio volumio[1112]: Mono: Playback [on] Jan 25 22:43:35 volumio volumio[1112]: info: VolumeController:: Volume=undefined Mute =false Jan 25 22:43:35 volumio volumio[1112]: info: CoreStateMachine::pushState Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jan 25 22:43:35 volumio volumio[1112]: info: MRS: Pushing multiroomSync output update for this device Jan 25 22:43:35 volumio volumio[1112]: info: MRS: Pushing multiroomSync output Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jan 25 22:43:35 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 25 22:43:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 25 22:43:35 volumio systemd[1]: mpd.service: Consumed 3.207s CPU time. Jan 25 22:43:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 25 22:43:35 volumio sudo[21311]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 25 22:43:35 volumio sudo[21311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:35 volumio upmpdcli[1397]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection reset by peer Jan 25 22:43:35 volumio upmpdcli[1397]: :2:../src/mpdcli.cxx:396::MPDCli::updStatus: connection failed Jan 25 22:43:35 volumio upmpdcli[1397]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Jan 25 22:43:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 25 22:43:35 volumio sudo[21319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 25 22:43:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 25 22:43:35 volumio sudo[21319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:35 volumio volumio[1112]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.zcj6LGJjqmbLzc1eitSt2oJqs7D3.c827ea0af3730513cfc294d1116504df.state.status' Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:35 volumio sudo[21317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 25 22:43:35 volumio sudo[21317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 25 22:43:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 22:43:35 volumio volumio[1112]: info: Starting Shairport Sync Jan 25 22:43:35 volumio volumio[1112]: info: Executing endpoint tc_getconfig Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 25 22:43:35 volumio vtcs[21284]: STARTING TidalConnect services, version: 1.5.2.56 Jan 25 22:43:35 volumio sudo[21331]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 25 22:43:35 volumio volumio[1112]: info: Preparing to generate the ALSA configuration file Jan 25 22:43:35 volumio sudo[21331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:35 volumio vtcs[21284]: STARTED TidalConnect services. Jan 25 22:43:35 volumio volumio[1112]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 25 22:43:35 volumio volumio[1112]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 25 22:43:35 volumio volumio[1112]: info: Reading ALSA contributions from plugins. Jan 25 22:43:35 volumio volumio[1112]: info: Executing endpoint tc_connect Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 25 22:43:35 volumio volumio[1112]: info: Connecting to TidalConnect Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::servicePushState Jan 25 22:43:35 volumio volumio[1112]: info: CoreStateMachine::pushState Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jan 25 22:43:35 volumio volumio[1112]: info: MRS: Pushing multiroomSync output update for this device Jan 25 22:43:35 volumio volumio[1112]: info: MRS: Pushing multiroomSync output Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jan 25 22:43:35 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jan 25 22:43:35 volumio volumio[1112]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jan 25 22:43:35 volumio volumio[1112]: verbose: CURRENT POSITION 0 Jan 25 22:43:35 volumio volumio[1112]: info: CoreStateMachine::syncState stateService stop Jan 25 22:43:35 volumio volumio[1112]: info: CoreStateMachine::syncState currentStatus stop Jan 25 22:43:35 volumio volumio[1112]: info: CoreStateMachine::pushState Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jan 25 22:43:35 volumio volumio[1112]: info: MRS: Pushing multiroomSync output update for this device Jan 25 22:43:35 volumio volumio[1112]: info: MRS: Pushing multiroomSync output Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jan 25 22:43:35 volumio volumio[1112]: info: No code Jan 25 22:43:35 volumio volumio[1112]: info: CoreStateMachine::pushState Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jan 25 22:43:35 volumio sudo[21317]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:35 volumio sudo[21308]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:35 volumio volumio[1112]: info: MRS: Pushing multiroomSync output update for this device Jan 25 22:43:35 volumio volumio[1112]: info: MRS: Pushing multiroomSync output Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jan 25 22:43:35 volumio sudo[21319]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::servicePushState Jan 25 22:43:35 volumio volumio[1112]: info: CoreStateMachine::pushState Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jan 25 22:43:35 volumio volumio[1112]: info: MRS: Pushing multiroomSync output update for this device Jan 25 22:43:35 volumio volumio[1112]: info: MRS: Pushing multiroomSync output Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jan 25 22:43:35 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jan 25 22:43:35 volumio volumio[1112]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jan 25 22:43:35 volumio volumio[1112]: verbose: CURRENT POSITION 0 Jan 25 22:43:35 volumio volumio[1112]: info: CoreStateMachine::syncState stateService stop Jan 25 22:43:35 volumio volumio[1112]: info: CoreStateMachine::syncState currentStatus stop Jan 25 22:43:35 volumio volumio[1112]: info: CoreStateMachine::pushState Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jan 25 22:43:35 volumio volumio[1112]: info: MRS: Pushing multiroomSync output update for this device Jan 25 22:43:35 volumio volumio[1112]: info: MRS: Pushing multiroomSync output Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jan 25 22:43:35 volumio volumio[1112]: info: No code Jan 25 22:43:35 volumio volumio[1112]: info: CoreStateMachine::pushState Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jan 25 22:43:35 volumio volumio[1112]: info: MRS: Pushing multiroomSync output update for this device Jan 25 22:43:35 volumio volumio[1112]: info: MRS: Pushing multiroomSync output Jan 25 22:43:35 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jan 25 22:43:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 25 22:43:35 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jan 25 22:43:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 25 22:43:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 25 22:43:35 volumio systemd[1]: shairport-sync.service: Consumed 1.682s CPU time. Jan 25 22:43:35 volumio systemd[1]: vtcs.service: Deactivated successfully. Jan 25 22:43:35 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jan 25 22:43:35 volumio sudo[21343]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 25 22:43:35 volumio sudo[21343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 22:43:35 volumio sudo[21311]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:35 volumio volumio[1112]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.zcj6LGJjqmbLzc1eitSt2oJqs7D3.c827ea0af3730513cfc294d1116504df.state.status' Jan 25 22:43:35 volumio volumio[1112]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.zcj6LGJjqmbLzc1eitSt2oJqs7D3.c827ea0af3730513cfc294d1116504df.state.status' Jan 25 22:43:35 volumio volumio[1112]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.zcj6LGJjqmbLzc1eitSt2oJqs7D3.c827ea0af3730513cfc294d1116504df.state.status' Jan 25 22:43:35 volumio volumio[1112]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.zcj6LGJjqmbLzc1eitSt2oJqs7D3.c827ea0af3730513cfc294d1116504df.state.status' Jan 25 22:43:35 volumio volumio[1112]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.zcj6LGJjqmbLzc1eitSt2oJqs7D3.c827ea0af3730513cfc294d1116504df.state.status' Jan 25 22:43:35 volumio volumio[1112]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.zcj6LGJjqmbLzc1eitSt2oJqs7D3.c827ea0af3730513cfc294d1116504df.state.status' Jan 25 22:43:35 volumio volumio[1112]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3 Jan 25 22:43:35 volumio volumio[1112]: Job for vtcs.service canceled. Jan 25 22:43:35 volumio volumio[1112]: {"cmd":"/usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3\nJob for vtcs.service canceled.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Jan 25 22:43:35 volumio volumio[1112]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 22:43:35 volumio sudo[21329]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 25 22:43:35 volumio sudo[21329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 25 22:43:35 volumio sudo[21329]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:35 volumio volumio[1112]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Jan 25 22:43:35 volumio volumio[1112]: Job for vtcs.service canceled. Jan 25 22:43:35 volumio volumio[1112]: at ChildProcess.exithandler (node:child_process:421:12) Jan 25 22:43:35 volumio volumio[1112]: at ChildProcess.emit (node:events:514:28) Jan 25 22:43:35 volumio volumio[1112]: at maybeClose (node:internal/child_process:1105:16) Jan 25 22:43:35 volumio volumio[1112]: at Socket. (node:internal/child_process:457:11) Jan 25 22:43:35 volumio volumio[1112]: at Socket.emit (node:events:514:28) Jan 25 22:43:35 volumio volumio[1112]: at Pipe. (node:net:337:12) { Jan 25 22:43:35 volumio volumio[1112]: code: 1, Jan 25 22:43:35 volumio volumio[1112]: killed: false, Jan 25 22:43:35 volumio volumio[1112]: signal: null, Jan 25 22:43:35 volumio volumio[1112]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Jan 25 22:43:35 volumio volumio[1112]: stdout: '', Jan 25 22:43:35 volumio volumio[1112]: stderr: 'Job for vtcs.service canceled.\n' Jan 25 22:43:35 volumio volumio[1112]: } Jan 25 22:43:35 volumio volumio[1112]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 22:43:35 volumio qobuz-connect[21126]: 20260125 22:43:35.911 [21126.21126] INFO SampleApp: shat down connection on UNIX socket Jan 25 22:43:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 25 22:43:35 volumio sudo[21331]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:35 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 25 22:43:35 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 25 22:43:36 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 25 22:43:36 volumio sudo[21264]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:36 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 25 22:43:36 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 25 22:43:36 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 25 22:43:36 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 25 22:43:36 volumio sudo[21343]: pam_unix(sudo:session): session closed for user root Jan 25 22:43:37 volumio sudo[21379]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-25 22:42' Jan 25 22:43:37 volumio sudo[21379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"