-- Logs begin at Wed 2026-10-14 20:10:34 JST, end at Wed 2026-10-14 20:30:04 JST. -- Oct 14 20:29:03 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 20:29:07 volumio qobuz-connect[2116]: 20261014 20:29:07.024 [2116.2116] INFO ProtocolHandler: [0x7f7a61f8]: Need to send state update Oct 14 20:29:07 volumio qobuz-connect[2116]: 20261014 20:29:07.024 [2116.2116] INFO QConnectMessageSender: [0x7f882a90]: Sending StateUpdated message Oct 14 20:29:07 volumio qobuz-connect[2116]: 20261014 20:29:07.024 [2116.2116] INFO QwspMessageSender: [0x7f882a80]: Sending Payload message Oct 14 20:29:07 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:07 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Oct 14 20:29:07 volumio volumio[1090]: info: CoreStateMachine::pushState Oct 14 20:29:07 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 20:29:07 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Oct 14 20:29:07 volumio volumio[1090]: info: MRS: Pushing multiroomSync output update for this device Oct 14 20:29:07 volumio volumio[1090]: info: MRS: Pushing multiroomSync output Oct 14 20:29:07 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:07 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 20:29:07 volumio volumio[1090]: info: Disabling MyMusic plugin tidalconnect Oct 14 20:29:07 volumio volumio[1090]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service tidalconnect Oct 14 20:29:07 volumio vtcs[2190]: [2026-10-14 20:29:07.805] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 14 20:29:07 volumio vtcs[2190]: [2026-10-14 20:29:07.805] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer Oct 14 20:29:07 volumio vtcs[2190]: [2026-10-14 20:29:07.805] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 14 20:29:07 volumio volumio[1090]: info: Disabling plugin tidalconnect Oct 14 20:29:07 volumio sudo[4998]: volumio : unable to resolve host volumio Oct 14 20:29:07 volumio sudo[4998]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 14 20:29:07 volumio sudo[4998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 20:29:07 volumio sudo[4998]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 20:29:07 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Oct 14 20:29:07 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 14 20:29:07 volumio systemd[1]: vtcs.service: Succeeded. Oct 14 20:29:07 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Oct 14 20:29:07 volumio volumio[1090]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 20:29:07 volumio sudo[4998]: pam_unix(sudo:session): session closed for user root Oct 14 20:29:10 volumio volumio[1090]: error: Unable to stop TidalConnect service: Oct 14 20:29:10 volumio volumio[1090]: info: TidalConnect service stoped! Oct 14 20:29:11 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 20:29:15 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 20:29:16 volumio volumio[1090]: info: Disabling MyMusic plugin cd_controller Oct 14 20:29:16 volumio volumio[1090]: info: [MyVolumio PluginManager] Disabling and stopping plugin music_service cd_controller Oct 14 20:29:16 volumio volumio[1090]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD Oct 14 20:29:16 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 20:29:16 volumio volumio[1090]: Cannot find translation for source DigiHug USB Audio Oct 14 20:29:16 volumio volumio[1090]: Cannot find translation for source QOBUZ Oct 14 20:29:16 volumio volumio[1090]: Cannot find translation for source Spotify Oct 14 20:29:16 volumio volumio[1090]: info: Stopping UDEV Watcher for CD Oct 14 20:29:16 volumio volumio[1090]: info: Disabling plugin cd_controller Oct 14 20:29:17 volumio qobuz-connect[2116]: 20261014 20:29:17.026 [2116.2116] INFO ProtocolHandler: [0x7f7a61f8]: Need to send state update Oct 14 20:29:17 volumio qobuz-connect[2116]: 20261014 20:29:17.026 [2116.2116] INFO QConnectMessageSender: [0x7f882a90]: Sending StateUpdated message Oct 14 20:29:17 volumio qobuz-connect[2116]: 20261014 20:29:17.026 [2116.2116] INFO QwspMessageSender: [0x7f882a80]: Sending Payload message Oct 14 20:29:17 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:17 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Oct 14 20:29:17 volumio volumio[1090]: info: CoreStateMachine::pushState Oct 14 20:29:17 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 20:29:17 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Oct 14 20:29:17 volumio volumio[1090]: info: MRS: Pushing multiroomSync output update for this device Oct 14 20:29:17 volumio volumio[1090]: info: MRS: Pushing multiroomSync output Oct 14 20:29:17 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:19 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 20:29:21 volumio volumio[1090]: info: Disabling MyMusic plugin multiroom Oct 14 20:29:21 volumio volumio[1090]: info: [MyVolumio PluginManager] Disabling and stopping plugin audio_interface multiroom Oct 14 20:29:21 volumio volumio[1090]: info: Disabling plugin multiroom Oct 14 20:29:21 volumio volumio[1090]: info: Preparing to generate the ALSA configuration file Oct 14 20:29:21 volumio volumio[1090]: info: Asound.conf file unchanged, so no further update is needed Oct 14 20:29:21 volumio volumio[1090]: info: Output device has changed, restarting MPD Oct 14 20:29:21 volumio sudo[5025]: volumio : unable to resolve host volumio Oct 14 20:29:21 volumio sudo[5025]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 14 20:29:21 volumio volumio[1090]: info: Output device has changed, restarting Shairport Sync Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 20:29:21 volumio sudo[5025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 20:29:21 volumio sudo[5028]: volumio : unable to resolve host volumio Oct 14 20:29:21 volumio sudo[5028]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 14 20:29:21 volumio sudo[5025]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 20:29:21 volumio sudo[5028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 20:29:21 volumio sudo[5025]: pam_unix(sudo:session): session closed for user root Oct 14 20:29:21 volumio sudo[5028]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 20:29:21 volumio volumio[1090]: No protocol specified Oct 14 20:29:21 volumio volumio[1090]: xcb_connection_has_error() returned true Oct 14 20:29:21 volumio volumio[1090]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 20:29:21 volumio volumio[1090]: No protocol specified Oct 14 20:29:21 volumio volumio[1090]: xcb_connection_has_error() returned true Oct 14 20:29:21 volumio volumio[1090]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 20:29:21 volumio systemd[1]: Stopping Music Player Daemon... Oct 14 20:29:21 volumio volumio[1090]: info: QobuzConnect: setDeactiveState invoked Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:21 volumio volumio[1090]: info: QobuzConnect: setDeactiveState(): relinquishing Volumio State Oct 14 20:29:21 volumio volumio[1090]: verbose: UNSET VOLATILE: Service: qobuzconnect Oct 14 20:29:21 volumio volumio[1090]: info: QobuzConnect: onUnsetVolatile() called, relinquishing Volumio State to another service Oct 14 20:29:21 volumio volumio[1090]: info: QobuzConnect: Received stop Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.445 [2116.2116] INFO MediaEngine: [0x7f7a5c20]: Stopping playback, keeping tracks Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.445 [2116.2116] INFO AudioRenderer: [0x7f7a5d88]: Stopping audio renderer Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.445 [2116.2116] INFO SampleApp: Stopping playback Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.448 [2116.2116] INFO AudioStreamManager: [0x7f7a5cd0]: [Stream 5]: Disposing of audio stream Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.448 [2116.2116] INFO ContentFetcher: [0x7f9ac380]: Canceling download Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.452 [2116.2116] INFO AudioStreamManager: [0x7f7a5cd0]: New stream: 7 Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.453 [2116.2116] INFO HttpDownloader: [0x7fb416b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=7&request_sig=83445d89828b24b3a9527536971be7f0&request_ts=1791977361&track_id=34218876 Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.453 [2116.2116] INFO HttpDownloader: [0x7fa782d8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=34218876 Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.454 [2116.2116] INFO HttpDownloader: [0x7f93c970]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=7&intent=stream&request_sig=1871d2e739b15e4274cc6a0bfb166edb&request_ts=1791977361&track_id=34218876 Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.454 [2116.2116] INFO AudioStreamManager: [0x7f7a5cd0]: [Stream 7]: Running audio stream Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.454 [2116.2116] INFO AudioStreamManager: [0x7f7a5cd0]: New stream: 8 Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.454 [2116.2116] INFO HttpDownloader: [0x7fa2d788]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=7&request_sig=1592018a688bf73e485556abba28ad62&request_ts=1791977361&track_id=449190540 Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.454 [2116.2116] INFO HttpDownloader: [0x7f881de0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=449190540 Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.454 [2116.2116] INFO HttpDownloader: [0x7f7dfda8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=7&intent=stream&request_sig=c11cfe90bd735156cf9c9e5324a29b27&request_ts=1791977361&track_id=449190540 Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.455 [2116.2116] INFO SampleApp: Playback state changed: Stopped Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.455 [2116.2116] INFO StreamingEventReporter: [0x7f7a65a8]: Reporting streaming ended event Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.455 [2116.2116] INFO HttpDownloader: [0x7f7a6600]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.455 [2116.2116] INFO ProtocolHandler: [0x7f7a61f8]: Need to send state update Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.455 [2116.2116] INFO QConnectMessageSender: [0x7f882a90]: Sending StateUpdated message Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.455 [2116.2116] INFO QwspMessageSender: [0x7f882a80]: Sending Payload message Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.456 [2116.2116] INFO SampleApp: [Stream 5]: Audio stream has been disposed of Oct 14 20:29:21 volumio systemd[1]: mpd.service: Succeeded. Oct 14 20:29:21 volumio systemd[1]: Stopped Music Player Daemon. Oct 14 20:29:21 volumio systemd[1]: Starting Music Player Daemon... Oct 14 20:29:21 volumio sudo[5049]: volumio : unable to resolve host volumio Oct 14 20:29:21 volumio sudo[5049]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 14 20:29:21 volumio sudo[5049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 20:29:21 volumio sudo[5053]: volumio : unable to resolve host volumio Oct 14 20:29:21 volumio sudo[5053]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 14 20:29:21 volumio sudo[5049]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 20:29:21 volumio sudo[5053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 20:29:21 volumio sudo[5047]: root : unable to resolve host volumio Oct 14 20:29:21 volumio sudo[5047]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 14 20:29:21 volumio sudo[5047]: sudo: unable to resolve host volumio: System error Oct 14 20:29:21 volumio sudo[5053]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 20:29:21 volumio sudo[5047]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 20:29:21 volumio volumio[1090]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 20:29:21 volumio volumio[1090]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 14 20:29:21 volumio sudo[5047]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 20:29:21 volumio sudo[5065]: volumio : unable to resolve host volumio Oct 14 20:29:21 volumio sudo[5065]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 14 20:29:21 volumio volumio[1090]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 20:29:21 volumio volumio[1090]: info: QobuzConnect: playback_stop received, deferred solved Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:21 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:21 volumio sudo[5049]: pam_unix(sudo:session): session closed for user root Oct 14 20:29:21 volumio sudo[5047]: pam_unix(sudo:session): session closed for user root Oct 14 20:29:21 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:21 volumio volumio[1090]: info: MPD Permissions set Oct 14 20:29:21 volumio sudo[5065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 20:29:21 volumio sudo[5053]: pam_unix(sudo:session): session closed for user root Oct 14 20:29:21 volumio sudo[5065]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 14 20:29:21 volumio sudo[5065]: pam_unix(sudo:session): session closed for user root Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 20:29:21 volumio sudo[5097]: volumio : unable to resolve host volumio Oct 14 20:29:21 volumio sudo[5097]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 14 20:29:21 volumio volumio[1090]: info: Starting Shairport Sync Oct 14 20:29:21 volumio sudo[5097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 14 20:29:21 volumio sudo[5097]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 20:29:21 volumio sudo[5103]: volumio : unable to resolve host volumio Oct 14 20:29:21 volumio sudo[5103]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 14 20:29:21 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Oct 14 20:29:21 volumio sudo[5103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.700 [2116.2116] INFO HttpDownloader: [0x7f93c970]: Canceling download Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.701 [2116.2116] INFO HttpDownloader: [0x7fa782d8]: Canceling download Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.701 [2116.2116] INFO HttpDownloader: [0x7fb416b8]: Canceling download Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.701 [2116.2116] INFO HttpDownloader: [0x7f7dfda8]: Canceling download Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.701 [2116.2116] INFO HttpDownloader: [0x7f881de0]: Canceling download Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.701 [2116.2116] INFO HttpDownloader: [0x7fa2d788]: Canceling download Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.701 [2116.2116] INFO CloudClient: [0x7f7a60e8]: Disconnecting from the cloud Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.701 [2116.2116] INFO CloudClient: [0x7f7a60e8]: Connection closed Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.704 [2116.2116] INFO HttpDownloader: [0x7f7a6600]: Canceling download Oct 14 20:29:21 volumio volumio[1090]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 20:29:21 volumio sudo[5103]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.722 [2116.2116] INFO SampleApp: Stopping Local configuration server Oct 14 20:29:21 volumio qobuz-connect[2116]: 20261014 20:29:21.732 [2116.2116] INFO SampleApp: shat down connection on UNIX socket Oct 14 20:29:21 volumio volumio[1090]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 14 20:29:21 volumio volumio[1090]: info: QobuzConnect: setDeactiveState invoked Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:21 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 14 20:29:21 volumio systemd[1]: qobuz-connect.service: Succeeded. Oct 14 20:29:21 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Oct 14 20:29:21 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 14 20:29:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 14 20:29:21 volumio volumio[1090]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 20:29:21 volumio volumio[1090]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 14 20:29:21 volumio volumio[1090]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 14 20:29:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 14 20:29:21 volumio systemd[1]: Started Volumio Qobuz Connect Service. Oct 14 20:29:21 volumio sudo[5097]: pam_unix(sudo:session): session closed for user root Oct 14 20:29:21 volumio sudo[5103]: pam_unix(sudo:session): session closed for user root Oct 14 20:29:21 volumio volumio[1090]: info: Executing endpoint qc_getconfig Oct 14 20:29:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 14 20:29:21 volumio qobuz-connect[5112]: 20261014 20:29:21.858 [5112.5112] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 14 20:29:21 volumio volumio[1090]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 14 20:29:21 volumio volumio[1090]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 14 20:29:21 volumio qobuz-connect[5112]: 20261014 20:29:21.862 [5112.5112] INFO VolumeManager: [0x80df5f78]: Setting new playback volume: 75 Oct 14 20:29:21 volumio qobuz-connect[5112]: 20261014 20:29:21.862 [5112.5112] INFO VolumeManager: [0x80df5f78]: Setting new mute state: 0 Oct 14 20:29:21 volumio qobuz-connect[5112]: 20261014 20:29:21.862 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: Setting new audio download buffer size: 1048576 Oct 14 20:29:21 volumio qobuz-connect[5112]: 20261014 20:29:21.862 [5112.5112] INFO QobuzConnect: [0x80df6840]: Client initialized! Oct 14 20:29:21 volumio qobuz-connect[5112]: 20261014 20:29:21.862 [5112.5112] INFO SampleApp: Starting Avahi advertising, name: 木むさんVolumio, service name: _qobuz-connect._tcp Oct 14 20:29:21 volumio volumio[1090]: info: Shairport-Sync Started Oct 14 20:29:21 volumio qobuz-connect[5112]: 20261014 20:29:21.869 [5112.5112] INFO LocalConfigManager: [0x80df59f8]: Starting Local Configuration server Oct 14 20:29:21 volumio qobuz-connect[5112]: 20261014 20:29:21.869 [5112.5112] INFO SampleApp: Starting Local configuration server Oct 14 20:29:21 volumio qobuz-connect[5112]: 20261014 20:29:21.870 [5112.5112] INFO SampleApp: Connected to UNIX socket client 0x80debed8 Oct 14 20:29:22 volumio qobuz-connect[5112]: 20261014 20:29:22.008 [5112.5112] INFO SampleApp: Playback volume changed: 75 Oct 14 20:29:22 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:22 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:22 volumio mpd[5098]: Oct 14 20:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 14 20:29:22 volumio systemd[1]: Started Music Player Daemon. Oct 14 20:29:22 volumio sudo[5028]: pam_unix(sudo:session): session closed for user root Oct 14 20:29:22 volumio volumio[1090]: error: updateQueue error: null Oct 14 20:29:23 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 20:29:24 volumio volumio[1090]: error: Unable to stop TidalConnect service: Oct 14 20:29:24 volumio volumio[1090]: info: TidalConnect service stoped! Oct 14 20:29:24 volumio volumio[1090]: error: Unable to stop TidalConnect service: Oct 14 20:29:24 volumio volumio[1090]: info: TidalConnect service stoped! Oct 14 20:29:24 volumio volumio[1090]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 14 20:29:24 volumio volumio[1090]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 14 20:29:24 volumio sudo[5154]: volumio : unable to resolve host volumio Oct 14 20:29:24 volumio sudo[5154]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 14 20:29:24 volumio sudo[5154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 14 20:29:24 volumio sudo[5154]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 20:29:24 volumio systemd[1]: Started Volumio Tidal Connect Service. Oct 14 20:29:24 volumio sudo[5154]: pam_unix(sudo:session): session closed for user root Oct 14 20:29:24 volumio volumio[1090]: info: Executing endpoint tc_getconfig Oct 14 20:29:24 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 14 20:29:24 volumio vtcs[5160]: STARTING TidalConnect services, version: 1.6.1 Oct 14 20:29:24 volumio vtcs[5160]: STARTED TidalConnect services. Oct 14 20:29:24 volumio volumio[1090]: info: Executing endpoint tc_connect Oct 14 20:29:24 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 14 20:29:24 volumio volumio[1090]: info: Connecting to TidalConnect Oct 14 20:29:24 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Oct 14 20:29:24 volumio volumio[1090]: info: CoreStateMachine::pushState Oct 14 20:29:24 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:24 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 20:29:24 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Oct 14 20:29:24 volumio volumio[1090]: info: MRS: Pushing multiroomSync output update for this device Oct 14 20:29:24 volumio volumio[1090]: info: MRS: Pushing multiroomSync output Oct 14 20:29:24 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:24 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:24 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:24 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 14 20:29:24 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Oct 14 20:29:24 volumio volumio[1090]: info: CoreStateMachine::pushState Oct 14 20:29:24 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:24 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Oct 14 20:29:24 volumio volumio[1090]: info: MRS: Pushing multiroomSync output update for this device Oct 14 20:29:24 volumio volumio[1090]: info: MRS: Pushing multiroomSync output Oct 14 20:29:24 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:24 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:24 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:24 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 14 20:29:25 volumio qobuz-connect[5112]: 20261014 20:29:25.936 [5112.5182] INFO SampleApp: API endpoint invoked: get-display-info Oct 14 20:29:26 volumio volumio[1090]: info: Executing endpoint metavolumio Oct 14 20:29:26 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 20:29:26 volumio volumio[1090]: info: Executing endpoint metavolumio Oct 14 20:29:26 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 20:29:26 volumio volumio[1090]: info: Executing endpoint metavolumio Oct 14 20:29:26 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 20:29:27 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 20:29:27 volumio volumio[1090]: error: Unable to start TidalConnect service: Oct 14 20:29:27 volumio volumio[1090]: info: TidalConnect service started! Oct 14 20:29:31 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 20:29:35 volumio qobuz-connect[5112]: 20261014 20:29:35.400 [5112.5182] INFO SampleApp: API endpoint invoked: get-connect-info Oct 14 20:29:35 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 20:29:36 volumio qobuz-connect[5112]: 20261014 20:29:36.701 [5112.5182] INFO SampleApp: API endpoint invoked: connect-to-qconnect Oct 14 20:29:36 volumio qobuz-connect[5112]: 20261014 20:29:36.701 [5112.5112] INFO EndpointManager: [0x80df5700]: Updating API endpoint Oct 14 20:29:36 volumio qobuz-connect[5112]: 20261014 20:29:36.701 [5112.5112] INFO EndpointManager: [0x80df5700]: Updating QConnect endpoint Oct 14 20:29:36 volumio qobuz-connect[5112]: 20261014 20:29:36.701 [5112.5112] INFO ActiveStateManager: [0x80df4798]: Setting new active state: active Oct 14 20:29:36 volumio qobuz-connect[5112]: 20261014 20:29:36.701 [5112.5112] INFO PlaybackSessionManager: [0x80df5a18]: Starting playback session maintenance Oct 14 20:29:36 volumio qobuz-connect[5112]: 20261014 20:29:36.701 [5112.5112] INFO HttpDownloader: [0x80df5bc0]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start Oct 14 20:29:36 volumio qobuz-connect[5112]: 20261014 20:29:36.701 [5112.5112] INFO CloudClient: [0x80df60e8]: Connecting to the cloud Oct 14 20:29:36 volumio qobuz-connect[5112]: 20261014 20:29:36.701 [5112.5112] INFO SampleApp: Renderer is now active Oct 14 20:29:36 volumio qobuz-connect[5112]: 20261014 20:29:36.949 [5112.5112] INFO CloudClient: [0x80df60e8]: Connection established Oct 14 20:29:36 volumio qobuz-connect[5112]: 20261014 20:29:36.949 [5112.5112] INFO QwspMessageSender: [0x80ee0a18]: Sending Authenticate message Oct 14 20:29:36 volumio qobuz-connect[5112]: 20261014 20:29:36.949 [5112.5112] INFO QwspMessageSender: [0x80ee0a18]: Sending Subscribe message Oct 14 20:29:36 volumio qobuz-connect[5112]: 20261014 20:29:36.949 [5112.5112] INFO QConnectMessageSender: [0x80ee0a28]: Sending JoinSession message Oct 14 20:29:36 volumio qobuz-connect[5112]: 20261014 20:29:36.949 [5112.5112] INFO QConnectMessageSender: [0x80ee0a28]: Sending VolumeChanged message Oct 14 20:29:36 volumio qobuz-connect[5112]: 20261014 20:29:36.949 [5112.5112] INFO QConnectMessageSender: [0x80ee0a28]: Sending VolumeMuted message Oct 14 20:29:36 volumio qobuz-connect[5112]: 20261014 20:29:36.949 [5112.5112] INFO QConnectMessageSender: [0x80ee0a28]: Sending MaxAudioQualityChanged message Oct 14 20:29:36 volumio qobuz-connect[5112]: 20261014 20:29:36.950 [5112.5112] INFO QwspMessageSender: [0x80ee0a18]: Sending Payload message Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Received SetActive message: active Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Received SetState message: Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Playing state: Paused Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Playback position: 0 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Queue version: 3101.1 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Current track: TID: 34218876, QID: 467, Context UUID: cbf1760e-6bc6-40a6-8ae3-2debb29dad38 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Next track: TID: 449190540, QID: 468, Context UUID: cbf1760e-6bc6-40a6-8ae3-2debb29dad38 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO MediaEngine: [0x80df5c20]: Stopping playback, clearing tracks Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO MediaEngine: [0x80df5c20]: Initiating playback Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO RendererActionAvailabilityManager: [0x80df6158]: Renderer action 'Next' is available Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Received SetLoopMode message: All Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO PlaybackModeManager: [0x80df5ee8]: Setting new loop mode: All Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO MediaEngine: [0x80df5c20]: Setting current track: 34218876, initial offset: 0ms Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: Clearing all streams Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: New stream: 1 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO HttpDownloader: [0x80ee1be0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=a7a9d88c72fa971268445148ef6da2b2&request_ts=1791977377&track_id=34218876 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO HttpDownloader: [0x80f371a0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=34218876 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: [Stream 1]: Running audio stream Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Received SetShuffleMode message: disabled Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO PlaybackModeManager: [0x80df5ee8]: Setting new shuffle mode: disabled Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.003 [5112.5112] INFO SampleApp: Playback mode changed, loop mode: All, shuffle mode: disabled Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:37 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:37 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.005 [5112.5112] INFO MediaEngine: [0x80df5c20]: Setting next track: 449190540 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.005 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: New stream: 2 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.005 [5112.5112] INFO HttpDownloader: [0x80f20628]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=f4fd601ba04092d0f6b72fee98148d89&request_ts=1791977377&track_id=449190540 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.005 [5112.5112] INFO HttpDownloader: [0x80f1d890]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=449190540 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.005 [5112.5112] INFO MediaEngine: [0x80df5c20]: Waiting for current stream to start before starting audio renderer Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.171 [5112.5112] INFO PlaybackSessionManager: [0x80df5a18]: Playback session has been refreshed Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.171 [5112.5112] INFO HttpDownloader: [0x80f07fa8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=5317bfbe744a705da487f013ad20cae6&request_ts=1791977377&track_id=34218876 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.172 [5112.5112] INFO HttpDownloader: [0x80fa1a60]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=a9860abb4bdf6baa0a0e9f03de43e87f&request_ts=1791977377&track_id=449190540 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.294 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: [Stream 2]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3355377&eid=449190540&fmt=6&profile=raw&app_id=174516466&cid=4513155&etsp=1791980977&hmac=4BCjokfqb8BPIJtLWj9qfsOpp3M Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.349 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: [Stream 2]: Metadata became available: Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.349 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: Title: Blowfly Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.349 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: Artist: Truth Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.349 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: Album: Blowfly Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.349 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: Album art URL: https://static.qobuz.com/images/covers/hg/q6/es8ozqvxyq6hg_600.jpg Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.428 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: [Stream 1]: Metadata became available: Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.429 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: Title: ラヴ・ロックダウン (Album Version) Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.429 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: Artist: K.West Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.429 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: Album: 808s & Heartbreak (Album Version) Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.429 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: Album art URL: https://static.qobuz.com/images/covers/23/34/0060255713423_600.jpg Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.434 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: [Stream 1]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3355377&eid=34218876&fmt=6&profile=raw&app_id=174516466&cid=4513155&etsp=1791980977&hmac=3mdR92ynjR03azHT9GeYy_85iD0 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.536 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: [Stream 1]: stream information have been fetched Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.536 [5112.5112] INFO UrlAudioSource: [0x80ee1ca8]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3355377&eid=34218876&fmt=6&profile=raw&app_id=174516466&cid=4513155&etsp=1791980977&hmac=3mdR92ynjR03azHT9GeYy_85iD0 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.536 [5112.5112] INFO ContentFetcher: [0x80f9d2b8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3355377&eid=34218876&fmt=6&profile=raw&app_id=174516466&cid=4513155&etsp=1791980977&hmac=3mdR92ynjR03azHT9GeYy_85iD0, offset: 0 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.536 [5112.5112] INFO AudioRenderer: [0x80df5d88]: Starting audio renderer, initial playback state: Paused Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.536 [5112.5112] INFO SampleApp: [Stream 1]: New audio stream (starting from 0ms) Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.537 [5112.5112] INFO SampleApp: [Stream 1]: Stream metadata became available: Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.537 [5112.5112] INFO SampleApp: Title: ラヴ・ロックダウン (Album Version) Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.537 [5112.5112] INFO SampleApp: Artist: K.West Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.537 [5112.5112] INFO SampleApp: Album: 808s & Heartbreak (Album Version) Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.537 [5112.5112] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/23/34/0060255713423_600.jpg Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:37 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:37 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:37 volumio volumio[1090]: info: QobuzConnect: setActiveState() Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:37 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:37 volumio volumio[1090]: info: QobuzConnect: Set Qobuz connect default volume to 100 Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:37 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:37 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:37 volumio volumio[1090]: info: QobuzConnect: Stopping currently active service Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioStop Oct 14 20:29:37 volumio volumio[1090]: info: CoreStateMachine::stop Oct 14 20:29:37 volumio volumio[1090]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 20:29:37 volumio volumio[1090]: info: CoreStateMachine::stPlaybackTimer Oct 14 20:29:37 volumio volumio[1090]: info: CoreStateMachine::updateTrackBlock Oct 14 20:29:37 volumio volumio[1090]: info: CorePlayQueue::getTrackBlock Oct 14 20:29:37 volumio volumio[1090]: info: CoreStateMachine::pushState Oct 14 20:29:37 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Oct 14 20:29:37 volumio volumio[1090]: info: MRS: Pushing multiroomSync output update for this device Oct 14 20:29:37 volumio volumio[1090]: info: MRS: Pushing multiroomSync output Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.548 [5112.5112] INFO VolumeManager: [0x80df5f78]: Setting new playback volume: 100 Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:37 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:37 volumio volumio[1090]: info: CoreStateMachine::serviceStop Oct 14 20:29:37 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::serviceStop Oct 14 20:29:37 volumio volumio[1090]: info: ControllerMpd::stop Oct 14 20:29:37 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 20:29:37 volumio volumio[1090]: info: sendMpdCommand stop took 15 milliseconds Oct 14 20:29:37 volumio volumio[1090]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Oct 14 20:29:37 volumio volumio[1090]: info: CoreStateMachine::pushState Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.567 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: [Stream 2]: stream information have been fetched Oct 14 20:29:37 volumio volumio[1090]: info: MRS: Pushing multiroomSync output update for this device Oct 14 20:29:37 volumio volumio[1090]: info: MRS: Pushing multiroomSync output Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:37 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Oct 14 20:29:37 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received qobuzconnect Oct 14 20:29:37 volumio volumio[1090]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect Oct 14 20:29:37 volumio volumio[1090]: info: QobuzConnect: setActiveState() completed, send playback confirmation Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.581 [5112.5112] INFO SampleApp: Initiating playback, initial state: Paused Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.666 [5112.5112] INFO SampleApp: Playback state changed: Paused Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.667 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Need to send state update Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.667 [5112.5112] INFO QConnectMessageSender: [0x80ee0a28]: Sending StateUpdated message Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.667 [5112.5112] INFO QwspMessageSender: [0x80ee0a18]: Sending Payload message Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Oct 14 20:29:37 volumio volumio[1090]: info: CoreStateMachine::pushState Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.668 [5112.5112] INFO QConnectMessageSender: [0x80ee0a28]: Sending FileAudioQualityChanged message Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.669 [5112.5112] INFO QwspMessageSender: [0x80ee0a18]: Sending Payload message Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.669 [5112.5112] INFO QConnectMessageSender: [0x80ee0a28]: Sending DeviceAudioQualityChanged message Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.670 [5112.5112] INFO QwspMessageSender: [0x80ee0a18]: Sending Payload message Oct 14 20:29:37 volumio volumio[1090]: info: MRS: Pushing multiroomSync output update for this device Oct 14 20:29:37 volumio volumio[1090]: info: MRS: Pushing multiroomSync output Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Oct 14 20:29:37 volumio volumio[1090]: info: CoreStateMachine::pushState Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Oct 14 20:29:37 volumio volumio[1090]: info: MRS: Pushing multiroomSync output update for this device Oct 14 20:29:37 volumio volumio[1090]: info: MRS: Pushing multiroomSync output Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.695 [5112.5112] INFO UrlAudioSource: [0x80ee1ca8]: Content info extracted Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.695 [5112.5112] INFO UrlAudioSource: [0x80ee1ca8]: Size: 25819863 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.698 [5112.5241] INFO AudioDecoder: [0x80fd3830]: Stream info: Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.698 [5112.5112] INFO SampleApp: Playback volume changed: 100 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.700 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Volume changed: 100 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.700 [5112.5112] INFO QConnectMessageSender: [0x80ee0a28]: Sending VolumeChanged message Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.700 [5112.5112] INFO QwspMessageSender: [0x80ee0a18]: Sending Payload message Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.700 [5112.5241] INFO AudioDecoder: [0x80fd3830]: Total samples: 11920524 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.701 [5112.5241] INFO AudioDecoder: [0x80fd3830]: Sample rate: 44100 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.701 [5112.5241] INFO AudioDecoder: [0x80fd3830]: Channel count: 2 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.703 [5112.5241] INFO AudioDecoder: [0x80fd3830]: Bits per sample: 16 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.703 [5112.5241] INFO AudioDecoder: [0x80fd3830]: Duration: 270306 Oct 14 20:29:37 volumio qobuz-connect[5112]: 20261014 20:29:37.704 [5112.5112] INFO UrlAudioSource: [0x80ee1ca8]: Audio properties have changed Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:37 volumio volumio[1090]: info: QobuzConnect: Volume received from daemon: 100 Oct 14 20:29:37 volumio volumio[1090]: info: VolumeController::SetAlsaVolume100 Oct 14 20:29:37 volumio volumio[1090]: info: CoreStateMachine::pushState Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Oct 14 20:29:37 volumio volumio[1090]: info: MRS: Pushing multiroomSync output update for this device Oct 14 20:29:37 volumio volumio[1090]: info: MRS: Pushing multiroomSync output Oct 14 20:29:37 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:39 volumio volumio[1090]: info: Executing endpoint metavolumio Oct 14 20:29:39 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 20:29:39 volumio volumio[1090]: info: Executing endpoint metavolumio Oct 14 20:29:39 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 20:29:39 volumio volumio[1090]: info: Executing endpoint metavolumio Oct 14 20:29:39 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 20:29:39 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 20:29:43 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 20:29:47 volumio qobuz-connect[5112]: 20261014 20:29:47.429 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Received SetMaxAudioQuality message: HIRES_L1 Oct 14 20:29:47 volumio qobuz-connect[5112]: 20261014 20:29:47.429 [5112.5112] INFO MediaEngine: [0x80df5c20]: Setting maximum audio quality: HIRES_L1 Oct 14 20:29:47 volumio qobuz-connect[5112]: 20261014 20:29:47.429 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: New stream: 3 Oct 14 20:29:47 volumio qobuz-connect[5112]: 20261014 20:29:47.429 [5112.5112] INFO HttpDownloader: [0x80fa1a60]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=7&request_sig=251288b16450bbe246d09dbaeb75ef82&request_ts=1791977387&track_id=449190540 Oct 14 20:29:47 volumio qobuz-connect[5112]: 20261014 20:29:47.429 [5112.5112] INFO HttpDownloader: [0x80efb5e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=449190540 Oct 14 20:29:47 volumio qobuz-connect[5112]: 20261014 20:29:47.429 [5112.5112] INFO HttpDownloader: [0x80fd1a90]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=7&intent=stream&request_sig=199396ca9567714b390d0fbb6bda1932&request_ts=1791977387&track_id=449190540 Oct 14 20:29:47 volumio qobuz-connect[5112]: 20261014 20:29:47.429 [5112.5112] INFO SampleApp: Maximum audio quality has changed: HIRES_L1 Oct 14 20:29:47 volumio qobuz-connect[5112]: 20261014 20:29:47.430 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Max audio quality changed: HIRES_L1 Oct 14 20:29:47 volumio qobuz-connect[5112]: 20261014 20:29:47.430 [5112.5112] INFO QConnectMessageSender: [0x80ee0a28]: Sending MaxAudioQualityChanged message Oct 14 20:29:47 volumio qobuz-connect[5112]: 20261014 20:29:47.430 [5112.5112] INFO QwspMessageSender: [0x80ee0a18]: Sending Payload message Oct 14 20:29:47 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 20:29:47 volumio qobuz-connect[5112]: 20261014 20:29:47.767 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: [Stream 3]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3355377&eid=449190540&fmt=6&profile=raw&app_id=174516466&cid=4513155&etsp=1791980987&hmac=NF_jMILe2omwnggP2WHARGSKmyg Oct 14 20:29:47 volumio qobuz-connect[5112]: 20261014 20:29:47.770 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: [Stream 3]: stream information have been fetched Oct 14 20:29:47 volumio qobuz-connect[5112]: 20261014 20:29:47.927 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: [Stream 3]: Metadata became available: Oct 14 20:29:47 volumio qobuz-connect[5112]: 20261014 20:29:47.927 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: Title: Blowfly Oct 14 20:29:47 volumio qobuz-connect[5112]: 20261014 20:29:47.927 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: Artist: Truth Oct 14 20:29:47 volumio qobuz-connect[5112]: 20261014 20:29:47.927 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: Album: Blowfly Oct 14 20:29:47 volumio qobuz-connect[5112]: 20261014 20:29:47.927 [5112.5112] INFO AudioStreamManager: [0x80df5cd0]: Album art URL: https://static.qobuz.com/images/covers/hg/q6/es8ozqvxyq6hg_600.jpg Oct 14 20:29:50 volumio qobuz-connect[5112]: 20261014 20:29:50.595 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Received SetState message: Oct 14 20:29:50 volumio qobuz-connect[5112]: 20261014 20:29:50.595 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Playing state: Playing Oct 14 20:29:50 volumio qobuz-connect[5112]: 20261014 20:29:50.595 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Playback position: Oct 14 20:29:50 volumio qobuz-connect[5112]: 20261014 20:29:50.595 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Queue version: Oct 14 20:29:50 volumio qobuz-connect[5112]: 20261014 20:29:50.595 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Current track: Oct 14 20:29:50 volumio qobuz-connect[5112]: 20261014 20:29:50.595 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Next track: Oct 14 20:29:50 volumio qobuz-connect[5112]: 20261014 20:29:50.595 [5112.5112] INFO MediaEngine: [0x80df5c20]: Resuming playback Oct 14 20:29:50 volumio qobuz-connect[5112]: 20261014 20:29:50.595 [5112.5112] INFO AudioRenderer: [0x80df5d88]: Resuming audio renderer Oct 14 20:29:50 volumio qobuz-connect[5112]: 20261014 20:29:50.595 [5112.5112] INFO SampleApp: Resuming playback Oct 14 20:29:50 volumio qobuz-connect[5112]: 20261014 20:29:50.598 [5112.5112] INFO SampleApp: Playback state changed: Playing Oct 14 20:29:50 volumio qobuz-connect[5112]: 20261014 20:29:50.598 [5112.5112] INFO StreamingEventReporter: [0x80df65a8]: Reporting streaming started event Oct 14 20:29:50 volumio qobuz-connect[5112]: 20261014 20:29:50.599 [5112.5112] INFO HttpDownloader: [0x80df6600]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Oct 14 20:29:50 volumio qobuz-connect[5112]: 20261014 20:29:50.599 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Need to send state update Oct 14 20:29:50 volumio qobuz-connect[5112]: 20261014 20:29:50.599 [5112.5112] INFO QConnectMessageSender: [0x80ee0a28]: Sending StateUpdated message Oct 14 20:29:50 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:50 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Oct 14 20:29:50 volumio volumio[1090]: info: CoreStateMachine::pushState Oct 14 20:29:50 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Oct 14 20:29:50 volumio qobuz-connect[5112]: 20261014 20:29:50.601 [5112.5112] INFO QwspMessageSender: [0x80ee0a18]: Sending Payload message Oct 14 20:29:50 volumio volumio[1090]: info: MRS: Pushing multiroomSync output update for this device Oct 14 20:29:50 volumio volumio[1090]: info: MRS: Pushing multiroomSync output Oct 14 20:29:50 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:50 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Oct 14 20:29:50 volumio volumio[1090]: info: CoreStateMachine::pushState Oct 14 20:29:50 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 20:29:50 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Oct 14 20:29:50 volumio volumio[1090]: info: MRS: Pushing multiroomSync output update for this device Oct 14 20:29:50 volumio volumio[1090]: info: MRS: Pushing multiroomSync output Oct 14 20:29:50 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:50 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:50 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Oct 14 20:29:50 volumio volumio[1090]: info: CoreStateMachine::pushState Oct 14 20:29:50 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Oct 14 20:29:50 volumio volumio[1090]: info: MRS: Pushing multiroomSync output update for this device Oct 14 20:29:50 volumio volumio[1090]: info: MRS: Pushing multiroomSync output Oct 14 20:29:50 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:50 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:50 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Oct 14 20:29:50 volumio volumio[1090]: info: CoreStateMachine::pushState Oct 14 20:29:50 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Oct 14 20:29:50 volumio volumio[1090]: info: MRS: Pushing multiroomSync output update for this device Oct 14 20:29:50 volumio volumio[1090]: info: MRS: Pushing multiroomSync output Oct 14 20:29:50 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:29:51 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 20:29:55 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 20:29:59 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 20:30:00 volumio qobuz-connect[5112]: 20261014 20:30:00.599 [5112.5112] INFO ProtocolHandler: [0x80df61f8]: Need to send state update Oct 14 20:30:00 volumio qobuz-connect[5112]: 20261014 20:30:00.599 [5112.5112] INFO QConnectMessageSender: [0x80ee0a28]: Sending StateUpdated message Oct 14 20:30:00 volumio qobuz-connect[5112]: 20261014 20:30:00.599 [5112.5112] INFO QwspMessageSender: [0x80ee0a18]: Sending Payload message Oct 14 20:30:00 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:30:00 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Oct 14 20:30:00 volumio volumio[1090]: info: CoreStateMachine::pushState Oct 14 20:30:00 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 20:30:00 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Oct 14 20:30:00 volumio volumio[1090]: info: MRS: Pushing multiroomSync output update for this device Oct 14 20:30:00 volumio volumio[1090]: info: MRS: Pushing multiroomSync output Oct 14 20:30:00 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Oct 14 20:30:00 volumio volumio[1090]: info: Disabling MyMusic plugin upnp Oct 14 20:30:00 volumio sudo[5272]: volumio : unable to resolve host volumio Oct 14 20:30:00 volumio sudo[5272]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 14 20:30:00 volumio sudo[5272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 14 20:30:00 volumio sudo[5272]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 20:30:00 volumio volumio[1090]: error: Upnp client error: Error: This socket has been ended by the other party Oct 14 20:30:00 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 14 20:30:00 volumio volumio[1090]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 14 20:30:03 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 20:30:04 volumio volumio[1090]: info: Enabling MyMusic plugin upnp Oct 14 20:30:04 volumio volumio[1090]: info: Enabling plugin upnp Oct 14 20:30:04 volumio volumio[1090]: info: Loading plugin "upnp"... Oct 14 20:30:04 volumio volumio[1090]: info: [1791977404471] Starting Upmpd Daemon Oct 14 20:30:04 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 14 20:30:04 volumio volumio[1090]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 20:30:04 volumio volumio[1090]: Error: listen EADDRINUSE: address already in use :::6599 Oct 14 20:30:04 volumio volumio[1090]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Oct 14 20:30:04 volumio volumio[1090]: at listenInCluster (net.js:1379:12) Oct 14 20:30:04 volumio volumio[1090]: at Server.listen (net.js:1465:7) Oct 14 20:30:04 volumio volumio[1090]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 14 20:30:04 volumio volumio[1090]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 14 20:30:04 volumio volumio[1090]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 14 20:30:04 volumio volumio[1090]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 14 20:30:04 volumio volumio[1090]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Oct 14 20:30:04 volumio volumio[1090]: code: 'EADDRINUSE', Oct 14 20:30:04 volumio volumio[1090]: errno: -98, Oct 14 20:30:04 volumio volumio[1090]: syscall: 'listen', Oct 14 20:30:04 volumio volumio[1090]: address: '::', Oct 14 20:30:04 volumio volumio[1090]: port: 6599 Oct 14 20:30:04 volumio volumio[1090]: } Oct 14 20:30:04 volumio volumio[1090]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 20:30:04 volumio sudo[5301]: volumio : unable to resolve host volumio Oct 14 20:30:04 volumio sudo[5301]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Oct 14 20:30:04 volumio sudo[5301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-14 20:29 Oct 14 20:30:04 volumio sudo[5301]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="3dada8b1e619a5feb94867e0865ace17474d7bce" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="30045b259d1a704a832ca7c1460c1fdfa4b723f9" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Mar 2026 12:25:22 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="cf5455596c5328b0cbc691c04ff0184a"