-- Logs begin at Sat 2025-05-24 18:05:37 WEST, end at Sat 2025-05-24 18:24:24 WEST. -- May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.635 [6706.6716] INFO SampleApp: API endpoint invoked: connect-to-qconnect May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.636 [6706.6706] INFO EndpointManager: [0xac664030]: Updating API endpoint May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.636 [6706.6706] INFO EndpointManager: [0xac664030]: Updating QConnect endpoint May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.636 [6706.6706] INFO ActiveStateManager: [0xac663048]: Setting new active state: active May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.636 [6706.6706] INFO PlaybackSessionManager: [0xac664420]: Starting playback session maintenance May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.636 [6706.6706] INFO HttpDownloader: [0xac664610]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.637 [6706.6706] INFO CloudClient: [0xac664c90]: Connecting to the cloud May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.638 [6706.6706] INFO SampleApp: Renderer is now active May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.871 [6706.6706] INFO CloudClient: [0xac664c90]: Connection established May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.871 [6706.6706] INFO QwspMessageSender: [0xac76eca8]: Sending Authenticate message May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.871 [6706.6706] INFO QwspMessageSender: [0xac76eca8]: Sending Subscribe message May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.871 [6706.6706] INFO QConnectMessageSender: [0xac6a4740]: Sending JoinSession message May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.871 [6706.6706] INFO QConnectMessageSender: [0xac6a4740]: Sending VolumeChanged message May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.871 [6706.6706] INFO QConnectMessageSender: [0xac6a4740]: Sending VolumeMuted message May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.871 [6706.6706] INFO QConnectMessageSender: [0xac6a4740]: Sending MaxAudioQualityChanged message May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.872 [6706.6706] INFO QwspMessageSender: [0xac76eca8]: Sending Payload message May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.945 [6706.6706] INFO ProtocolHandler: [0xac664df8]: Received SetActive message: active May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.945 [6706.6706] INFO ProtocolHandler: [0xac664df8]: Received SetState message: May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.945 [6706.6706] INFO ProtocolHandler: [0xac664df8]: Playing state: Paused May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.945 [6706.6706] INFO ProtocolHandler: [0xac664df8]: Playback position: 61847 May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.945 [6706.6706] INFO ProtocolHandler: [0xac664df8]: Queue version: 5.6 May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.945 [6706.6706] INFO ProtocolHandler: [0xac664df8]: Current track: TID: 313558352, QID: 0, Context UUID: f2317849-1d4e-4a24-bf9d-216ebb2e915f May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.945 [6706.6706] INFO ProtocolHandler: [0xac664df8]: Next track: TID: 313558353, QID: 1, Context UUID: f2317849-1d4e-4a24-bf9d-216ebb2e915f May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.945 [6706.6706] INFO MediaEngine: [0xac6646b8]: Stopping playback, clearing tracks May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.945 [6706.6706] INFO MediaEngine: [0xac6646b8]: Initiating playback May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.945 [6706.6706] INFO RendererActionAvailabilityManager: [0xac664d48]: Renderer action 'Next' is available May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.945 [6706.6706] INFO ProtocolHandler: [0xac664df8]: Received SetLoopMode message: Off May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.945 [6706.6706] INFO PlaybackControlsManager: [0xac664a90]: Setting new loop mode: Off May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.945 [6706.6706] INFO MediaEngine: [0xac6646b8]: Setting current track: 313558352, initial offset: 61847ms May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.946 [6706.6706] INFO AudioStreamManager: [0xac6647a0]: Clearing all streams May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.946 [6706.6706] INFO AudioStreamManager: [0xac6647a0]: New stream: 1 May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.946 [6706.6706] INFO HttpDownloader: [0xac73fd38]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=6bdf8fbeb330c50f90e0c5bc6255086e&request_ts=1748107383&track_id=313558352 May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.946 [6706.6706] INFO HttpDownloader: [0xac740cc8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=313558352 May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.946 [6706.6706] INFO AudioStreamManager: [0xac6647a0]: [Stream 1]: Running audio stream May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.946 [6706.6706] INFO ProtocolHandler: [0xac664df8]: Received SetShuffleMode message: disabled May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.946 [6706.6706] INFO PlaybackControlsManager: [0xac664a90]: Setting new shuffle mode: disabled May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.947 [6706.6706] INFO MediaEngine: [0xac6646b8]: Setting next track: 313558353 May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.947 [6706.6706] INFO AudioStreamManager: [0xac6647a0]: New stream: 2 May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.947 [6706.6706] INFO HttpDownloader: [0xac804e28]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=461129728e02d148a18e69d8a129e14d&request_ts=1748107383&track_id=313558353 May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.947 [6706.6706] INFO HttpDownloader: [0xac805dc8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=313558353 May 24 18:23:03 moustachesrivo qobuz-connect[6706]: 20250524 18:23:03.948 [6706.6706] INFO MediaEngine: [0xac6646b8]: Waiting for current stream to start before starting audio renderer May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.006 [6706.6706] INFO PlaybackSessionManager: [0xac664420]: Playback session has been refreshed May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.006 [6706.6706] INFO HttpDownloader: [0xac6707c8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=937e3b5d6747f53639abf0412e46b2f8&request_ts=1748107384&track_id=313558352 May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.006 [6706.6706] INFO HttpDownloader: [0xac66a370]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=627332f44443222cbe29c43505ee7794&request_ts=1748107384&track_id=313558353 May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.163 [6706.6706] INFO AudioStreamManager: [0xac6647a0]: [Stream 1]: Metadata became available: May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.163 [6706.6706] INFO AudioStreamManager: [0xac6647a0]: Title: Call For Love May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.163 [6706.6706] INFO AudioStreamManager: [0xac6647a0]: Artist: Morcheeba May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.163 [6706.6706] INFO AudioStreamManager: [0xac6647a0]: Album: Escape The Chaos May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.163 [6706.6706] INFO AudioStreamManager: [0xac6647a0]: Album art URL: https://static.qobuz.com/images/covers/4a/gg/h91yp40krgg4a_600.jpg May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.163 [6706.6706] INFO AudioStreamManager: [0xac6647a0]: [Stream 2]: Metadata became available: May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.163 [6706.6706] INFO AudioStreamManager: [0xac6647a0]: Title: Elephant Clouds May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.163 [6706.6706] INFO AudioStreamManager: [0xac6647a0]: Artist: Morcheeba May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.163 [6706.6706] INFO AudioStreamManager: [0xac6647a0]: Album: Escape The Chaos May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.163 [6706.6706] INFO AudioStreamManager: [0xac6647a0]: Album art URL: https://static.qobuz.com/images/covers/4a/gg/h91yp40krgg4a_600.jpg May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.165 [6706.6706] INFO AudioStreamManager: [0xac6647a0]: [Stream 2]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1702366&eid=313558353&fmt=7&profile=raw&app_id=174516466&cid=1441537&etsp=1748110984&hmac=ZYuIO00jbi_v05pWoY-BZfDtdYk May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.173 [6706.6706] INFO AudioStreamManager: [0xac6647a0]: [Stream 1]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1702366&eid=313558352&fmt=7&profile=raw&app_id=174516466&cid=1441537&etsp=1748110984&hmac=bJRFs8nr124LhySXfUvWsplyfX8 May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.179 [6706.6706] INFO AudioStreamManager: [0xac6647a0]: [Stream 1]: stream information have been fetched May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.180 [6706.6706] INFO UrlAudioSource: [0xac73fe60]: Starting URL audio source, initial position: 61847ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1702366&eid=313558352&fmt=7&profile=raw&app_id=174516466&cid=1441537&etsp=1748110984&hmac=bJRFs8nr124LhySXfUvWsplyfX8 May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.180 [6706.6706] INFO ContentFetcher: [0xac66b030]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1702366&eid=313558352&fmt=7&profile=raw&app_id=174516466&cid=1441537&etsp=1748110984&hmac=bJRFs8nr124LhySXfUvWsplyfX8, offset: 0 May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.180 [6706.6706] INFO AudioRenderer: [0xac6648d8]: Starting audio renderer, initial playback state: Paused May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.180 [6706.6706] INFO SampleApp: [Stream 1]: New audio stream (starting from 61847ms) May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.181 [6706.6706] INFO SampleApp: [Stream 1]: Stream metadata became available: May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.181 [6706.6706] INFO SampleApp: Title: Call For Love May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.181 [6706.6706] INFO SampleApp: Artist: Morcheeba May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.181 [6706.6706] INFO SampleApp: Album: Escape The Chaos May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.181 [6706.6706] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/4a/gg/h91yp40krgg4a_600.jpg May 24 18:23:04 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:23:04 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:23:04 moustachesrivo volumio[3113]: info: QobuzConnect: setActiveState() May 24 18:23:04 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:23:04 moustachesrivo volumio[3113]: info: QobuzConnect: Set Qobuz connect default volume to 10 May 24 18:23:04 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.184 [6706.6706] INFO VolumeManager: [0xac664b20]: Setting new playback volume: 10 May 24 18:23:04 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:23:04 moustachesrivo volumio[3113]: info: QobuzConnect: Stopping currently active service May 24 18:23:04 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioStop May 24 18:23:04 moustachesrivo volumio[3113]: info: CoreStateMachine::stop May 24 18:23:04 moustachesrivo volumio[3113]: info: CoreStateMachine::serviceStop May 24 18:23:04 moustachesrivo volumio[3113]: info: Received STOP, but no service to execute it May 24 18:23:04 moustachesrivo volumio[3113]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 18:23:04 moustachesrivo volumio[3113]: info: CoreCommandRouter::servicePushState May 24 18:23:04 moustachesrivo volumio[3113]: info: CoreStateMachine::pushState May 24 18:23:04 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioPushState May 24 18:23:04 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:23:04 moustachesrivo volumio[3113]: info: MRS: Pushing multiroomSync output update for this device May 24 18:23:04 moustachesrivo volumio[3113]: info: MRS: Pushing multiroomSync output May 24 18:23:04 moustachesrivo volumio[3113]: info: CorePlayQueue::getTrack 13 May 24 18:23:04 moustachesrivo volumio[3113]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received qobuzconnect May 24 18:23:04 moustachesrivo volumio[3113]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect May 24 18:23:04 moustachesrivo volumio[3113]: info: QobuzConnect: setActiveState() completed, send playback confirmation May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.204 [6706.6706] INFO SampleApp: Initiating playback, initial state: Paused May 24 18:23:04 moustachesrivo volumio[3113]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 24 18:23:04 moustachesrivo qobuz-connect[6706]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card May 24 18:23:04 moustachesrivo qobuz-connect[6706]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback May 24 18:23:04 moustachesrivo qobuz-connect[6706]: 20250524 18:23:04.245 [6706.6706] ERROR SampleApp: Unable to open ALSA audio sink 'volumio': No such device (-19) May 24 18:23:04 moustachesrivo systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 24 18:23:04 moustachesrivo volumio[3113]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 24 18:23:04 moustachesrivo volumio[3113]: info: QobuzConnect: setDeactiveState invoked May 24 18:23:04 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:23:04 moustachesrivo systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 24 18:23:09 moustachesrivo systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 24 18:23:09 moustachesrivo systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 10. May 24 18:23:09 moustachesrivo systemd[1]: Stopped Volumio Qobuz Connect Service. May 24 18:23:09 moustachesrivo systemd[1]: Started Volumio Qobuz Connect Service. May 24 18:23:09 moustachesrivo volumio[3113]: info: Executing endpoint qc_getconfig May 24 18:23:09 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 24 18:23:09 moustachesrivo qobuz-connect[7078]: 20250524 18:23:09.535 [7078.7078] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 24 18:23:09 moustachesrivo volumio[3113]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 24 18:23:09 moustachesrivo volumio[3113]: info: QobuzConnect: QOBUZ Connect daemon connected May 24 18:23:09 moustachesrivo qobuz-connect[7078]: 20250524 18:23:09.541 [7078.7078] INFO VolumeManager: [0xac8b6b20]: Setting new playback volume: 75 May 24 18:23:09 moustachesrivo qobuz-connect[7078]: 20250524 18:23:09.541 [7078.7078] INFO VolumeManager: [0xac8b6b20]: Setting new mute state: 0 May 24 18:23:09 moustachesrivo qobuz-connect[7078]: 20250524 18:23:09.541 [7078.7078] INFO QobuzConnect: [0xac8b74f0]: Client initialized! May 24 18:23:09 moustachesrivo qobuz-connect[7078]: 20250524 18:23:09.541 [7078.7078] INFO SampleApp: Starting Avahi advertising, name: MoustachesRivo, service name: _qobuz-connect._tcp May 24 18:23:09 moustachesrivo qobuz-connect[7078]: 20250524 18:23:09.553 [7078.7078] INFO LocalConfigManager: [0xac8b6400]: Starting Local Configuration server May 24 18:23:09 moustachesrivo qobuz-connect[7078]: 20250524 18:23:09.553 [7078.7078] INFO SampleApp: Starting Local configuration server May 24 18:23:09 moustachesrivo qobuz-connect[7078]: 20250524 18:23:09.554 [7078.7078] INFO SampleApp: Connected to UNIX socket client 0xac8abed8 May 24 18:23:09 moustachesrivo qobuz-connect[7078]: 20250524 18:23:09.684 [7078.7078] INFO SampleApp: Playback volume changed: 75 May 24 18:23:09 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:23:10 moustachesrivo qobuz-connect[7078]: 20250524 18:23:10.569 [7078.7082] INFO SampleApp: API endpoint invoked: get-display-info May 24 18:23:10 moustachesrivo qobuz-connect[7078]: 20250524 18:23:10.580 [7078.7082] INFO SampleApp: API endpoint invoked: get-connect-info May 24 18:23:48 moustachesrivo kernel: input: 38:8F:30:4A:32:BB as /devices/virtual/input/input6 May 24 18:23:49 moustachesrivo volumio[3113]: info: VolumeController::SetAlsaVolume60 May 24 18:23:49 moustachesrivo volumio[3113]: BT PLUGIN MESSAGE: Volume set to 60 May 24 18:23:49 moustachesrivo volumio[3113]: info: CoreStateMachine::pushState May 24 18:23:49 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 18:23:49 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioPushState May 24 18:23:49 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:23:49 moustachesrivo volumio[3113]: info: MRS: Pushing multiroomSync output update for this device May 24 18:23:49 moustachesrivo volumio[3113]: info: MRS: Pushing multiroomSync output May 24 18:23:49 moustachesrivo volumio[3113]: ------------------------------------ BT MESSAGE: BT STATUS: paused May 24 18:23:49 moustachesrivo volumio[3113]: BT PLUGIN MESSAGE: Playing: false May 24 18:23:49 moustachesrivo volumio[3113]: BT PLUGIN MESSAGE: VOLATILE: undefined May 24 18:23:49 moustachesrivo volumio[3113]: BT PLUGIN MESSAGE: STATE:undefined May 24 18:23:49 moustachesrivo systemd-udevd[7185]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 24 18:23:49 moustachesrivo volumio[3113]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 24 18:23:49 moustachesrivo systemd-logind[2821]: Watching system buttons on /dev/input/event5 (38:8F:30:4A:32:BB) May 24 18:23:49 moustachesrivo volumio[3113]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 24 18:23:49 moustachesrivo volumio[3113]: BT PLUGIN MESSAGE: Duration is 262000 May 24 18:23:49 moustachesrivo pulseaudio[3946]: Support for resampler 'src-sinc-best-quality' not compiled in, reverting to 'auto'. May 24 18:23:49 moustachesrivo pulseaudio[3946]: Configured latency of 200.00 ms is smaller than minimum latency, using minimum instead May 24 18:23:49 moustachesrivo pulseaudio[3946]: Cannot set requested source latency of 66.67 ms, adjusting to 250.00 ms May 24 18:23:57 moustachesrivo qobuz-connect[7078]: 20250524 18:23:57.683 [7078.7082] INFO SampleApp: API endpoint invoked: connect-to-qconnect May 24 18:23:57 moustachesrivo qobuz-connect[7078]: 20250524 18:23:57.683 [7078.7078] INFO EndpointManager: [0xac8b6030]: Updating API endpoint May 24 18:23:57 moustachesrivo qobuz-connect[7078]: 20250524 18:23:57.683 [7078.7078] INFO EndpointManager: [0xac8b6030]: Updating QConnect endpoint May 24 18:23:57 moustachesrivo qobuz-connect[7078]: 20250524 18:23:57.683 [7078.7078] INFO ActiveStateManager: [0xac8b5048]: Setting new active state: active May 24 18:23:57 moustachesrivo qobuz-connect[7078]: 20250524 18:23:57.683 [7078.7078] INFO PlaybackSessionManager: [0xac8b6420]: Starting playback session maintenance May 24 18:23:57 moustachesrivo qobuz-connect[7078]: 20250524 18:23:57.684 [7078.7078] INFO HttpDownloader: [0xac8b6610]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start May 24 18:23:57 moustachesrivo qobuz-connect[7078]: 20250524 18:23:57.684 [7078.7078] INFO CloudClient: [0xac8b6c90]: Connecting to the cloud May 24 18:23:57 moustachesrivo qobuz-connect[7078]: 20250524 18:23:57.685 [7078.7078] INFO SampleApp: Renderer is now active May 24 18:23:57 moustachesrivo qobuz-connect[7078]: 20250524 18:23:57.928 [7078.7078] INFO CloudClient: [0xac8b6c90]: Connection established May 24 18:23:57 moustachesrivo qobuz-connect[7078]: 20250524 18:23:57.928 [7078.7078] INFO QwspMessageSender: [0xac9c0ca8]: Sending Authenticate message May 24 18:23:57 moustachesrivo qobuz-connect[7078]: 20250524 18:23:57.928 [7078.7078] INFO QwspMessageSender: [0xac9c0ca8]: Sending Subscribe message May 24 18:23:57 moustachesrivo qobuz-connect[7078]: 20250524 18:23:57.928 [7078.7078] INFO QConnectMessageSender: [0xac8f6740]: Sending JoinSession message May 24 18:23:57 moustachesrivo qobuz-connect[7078]: 20250524 18:23:57.928 [7078.7078] INFO QConnectMessageSender: [0xac8f6740]: Sending VolumeChanged message May 24 18:23:57 moustachesrivo qobuz-connect[7078]: 20250524 18:23:57.928 [7078.7078] INFO QConnectMessageSender: [0xac8f6740]: Sending VolumeMuted message May 24 18:23:57 moustachesrivo qobuz-connect[7078]: 20250524 18:23:57.928 [7078.7078] INFO QConnectMessageSender: [0xac8f6740]: Sending MaxAudioQualityChanged message May 24 18:23:57 moustachesrivo qobuz-connect[7078]: 20250524 18:23:57.929 [7078.7078] INFO QwspMessageSender: [0xac9c0ca8]: Sending Payload message May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.016 [7078.7078] INFO PlaybackSessionManager: [0xac8b6420]: Playback session has been refreshed May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.035 [7078.7078] INFO ProtocolHandler: [0xac8b6df8]: Received SetActive message: active May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.035 [7078.7078] INFO ProtocolHandler: [0xac8b6df8]: Received SetState message: May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.035 [7078.7078] INFO ProtocolHandler: [0xac8b6df8]: Playing state: Paused May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.035 [7078.7078] INFO ProtocolHandler: [0xac8b6df8]: Playback position: 61847 May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.035 [7078.7078] INFO ProtocolHandler: [0xac8b6df8]: Queue version: 5.6 May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.035 [7078.7078] INFO ProtocolHandler: [0xac8b6df8]: Current track: TID: 313558352, QID: 0, Context UUID: f2317849-1d4e-4a24-bf9d-216ebb2e915f May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.035 [7078.7078] INFO ProtocolHandler: [0xac8b6df8]: Next track: TID: 313558353, QID: 1, Context UUID: f2317849-1d4e-4a24-bf9d-216ebb2e915f May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.035 [7078.7078] INFO MediaEngine: [0xac8b66b8]: Stopping playback, clearing tracks May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.035 [7078.7078] INFO MediaEngine: [0xac8b66b8]: Initiating playback May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.035 [7078.7078] INFO RendererActionAvailabilityManager: [0xac8b6d48]: Renderer action 'Next' is available May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.035 [7078.7078] INFO ProtocolHandler: [0xac8b6df8]: Received SetLoopMode message: Off May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.036 [7078.7078] INFO PlaybackControlsManager: [0xac8b6a90]: Setting new loop mode: Off May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.036 [7078.7078] INFO MediaEngine: [0xac8b66b8]: Setting current track: 313558352, initial offset: 61847ms May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.036 [7078.7078] INFO AudioStreamManager: [0xac8b67a0]: Clearing all streams May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.036 [7078.7078] INFO AudioStreamManager: [0xac8b67a0]: New stream: 1 May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.036 [7078.7078] INFO HttpDownloader: [0xac8b9ff0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=ed2efaae5a64fa5e0f50570067989e88&request_ts=1748107438&track_id=313558352 May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.036 [7078.7078] INFO HttpDownloader: [0xac8c28b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=313558352 May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.036 [7078.7078] INFO HttpDownloader: [0xac991a70]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=5ab30e932e6ecfb5cd79e716aa0c1839&request_ts=1748107438&track_id=313558352 May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.036 [7078.7078] INFO AudioStreamManager: [0xac8b67a0]: [Stream 1]: Running audio stream May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.036 [7078.7078] INFO ProtocolHandler: [0xac8b6df8]: Received SetShuffleMode message: disabled May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.036 [7078.7078] INFO PlaybackControlsManager: [0xac8b6a90]: Setting new shuffle mode: disabled May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.038 [7078.7078] INFO MediaEngine: [0xac8b66b8]: Setting next track: 313558353 May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.038 [7078.7078] INFO AudioStreamManager: [0xac8b67a0]: New stream: 2 May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.038 [7078.7078] INFO HttpDownloader: [0xac996998]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=b40ecfd73c55bcb7a3daa02032881fb0&request_ts=1748107438&track_id=313558353 May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.038 [7078.7078] INFO HttpDownloader: [0xac996688]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=313558353 May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.038 [7078.7078] INFO HttpDownloader: [0xac998830]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=0322a59263dbd6f1eb0d6c26a452fa5b&request_ts=1748107438&track_id=313558353 May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.039 [7078.7078] INFO MediaEngine: [0xac8b66b8]: Waiting for current stream to start before starting audio renderer May 24 18:23:58 moustachesrivo volumio[3113]: BT PLUGIN MESSAGE: Duration is 0 May 24 18:23:58 moustachesrivo volumio[3113]: BT PLUGIN MESSAGE: Duration is 262000 May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.256 [7078.7078] INFO AudioStreamManager: [0xac8b67a0]: [Stream 1]: stream information have been fetched May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.256 [7078.7078] INFO AudioStreamManager: [0xac8b67a0]: [Stream 2]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1702366&eid=313558353&fmt=7&profile=raw&app_id=174516466&cid=1441537&etsp=1748111038&hmac=tx9ocauD1HTjy85kbtrWrX1hhwg May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.284 [7078.7078] INFO AudioStreamManager: [0xac8b67a0]: [Stream 2]: Metadata became available: May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.284 [7078.7078] INFO AudioStreamManager: [0xac8b67a0]: Title: Elephant Clouds May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.284 [7078.7078] INFO AudioStreamManager: [0xac8b67a0]: Artist: Morcheeba May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.284 [7078.7078] INFO AudioStreamManager: [0xac8b67a0]: Album: Escape The Chaos May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.284 [7078.7078] INFO AudioStreamManager: [0xac8b67a0]: Album art URL: https://static.qobuz.com/images/covers/4a/gg/h91yp40krgg4a_600.jpg May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.328 [7078.7078] INFO AudioStreamManager: [0xac8b67a0]: [Stream 1]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=1702366&eid=313558352&fmt=7&profile=raw&app_id=174516466&cid=1441537&etsp=1748111038&hmac=y7AezORcybFVQ9qYkTKcMT5N98Y May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.328 [7078.7078] INFO UrlAudioSource: [0xac9964d0]: Starting URL audio source, initial position: 61847ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=1702366&eid=313558352&fmt=7&profile=raw&app_id=174516466&cid=1441537&etsp=1748111038&hmac=y7AezORcybFVQ9qYkTKcMT5N98Y May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.329 [7078.7078] INFO ContentFetcher: [0xaca69d90]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=1702366&eid=313558352&fmt=7&profile=raw&app_id=174516466&cid=1441537&etsp=1748111038&hmac=y7AezORcybFVQ9qYkTKcMT5N98Y, offset: 0 May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.329 [7078.7078] INFO AudioRenderer: [0xac8b68d8]: Starting audio renderer, initial playback state: Paused May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.329 [7078.7078] INFO SampleApp: [Stream 1]: New audio stream (starting from 61847ms) May 24 18:23:58 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:23:58 moustachesrivo volumio[3113]: info: QobuzConnect: setActiveState() May 24 18:23:58 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:23:58 moustachesrivo volumio[3113]: info: QobuzConnect: Set Qobuz connect default volume to 60 May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.334 [7078.7078] INFO VolumeManager: [0xac8b6b20]: Setting new playback volume: 60 May 24 18:23:58 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:23:58 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:23:58 moustachesrivo volumio[3113]: info: QobuzConnect: Stopping currently active service May 24 18:23:58 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioStop May 24 18:23:58 moustachesrivo volumio[3113]: info: CoreStateMachine::stop May 24 18:23:58 moustachesrivo volumio[3113]: info: CoreStateMachine::serviceStop May 24 18:23:58 moustachesrivo volumio[3113]: info: Received STOP, but no service to execute it May 24 18:23:58 moustachesrivo volumio[3113]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 18:23:58 moustachesrivo volumio[3113]: info: CoreCommandRouter::servicePushState May 24 18:23:58 moustachesrivo volumio[3113]: info: CoreStateMachine::pushState May 24 18:23:58 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioPushState May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.346 [7078.7078] INFO AudioStreamManager: [0xac8b67a0]: [Stream 1]: Metadata became available: May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.346 [7078.7078] INFO AudioStreamManager: [0xac8b67a0]: Title: Call For Love May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.346 [7078.7078] INFO AudioStreamManager: [0xac8b67a0]: Artist: Morcheeba May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.346 [7078.7078] INFO AudioStreamManager: [0xac8b67a0]: Album: Escape The Chaos May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.346 [7078.7078] INFO AudioStreamManager: [0xac8b67a0]: Album art URL: https://static.qobuz.com/images/covers/4a/gg/h91yp40krgg4a_600.jpg May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.346 [7078.7078] INFO SampleApp: [Stream 1]: Stream metadata became available: May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.346 [7078.7078] INFO SampleApp: Title: Call For Love May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.347 [7078.7078] INFO SampleApp: Artist: Morcheeba May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.347 [7078.7078] INFO SampleApp: Album: Escape The Chaos May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.347 [7078.7078] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/4a/gg/h91yp40krgg4a_600.jpg May 24 18:23:58 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:23:58 moustachesrivo volumio[3113]: info: MRS: Pushing multiroomSync output update for this device May 24 18:23:58 moustachesrivo volumio[3113]: info: MRS: Pushing multiroomSync output May 24 18:23:58 moustachesrivo volumio[3113]: info: CorePlayQueue::getTrack 13 May 24 18:23:58 moustachesrivo volumio[3113]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received qobuzconnect May 24 18:23:58 moustachesrivo volumio[3113]: info: QobuzConnect: setActiveState(): setting Volatile state to qobuzconnect May 24 18:23:58 moustachesrivo volumio[3113]: info: QobuzConnect: setActiveState() completed, send playback confirmation May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.362 [7078.7078] INFO SampleApp: Initiating playback, initial state: Paused May 24 18:23:58 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:23:58 moustachesrivo volumio[3113]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 24 18:23:58 moustachesrivo qobuz-connect[7078]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card May 24 18:23:58 moustachesrivo qobuz-connect[7078]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback May 24 18:23:58 moustachesrivo qobuz-connect[7078]: 20250524 18:23:58.404 [7078.7078] ERROR SampleApp: Unable to open ALSA audio sink 'volumio': No such device (-19) May 24 18:23:58 moustachesrivo volumio[3113]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 24 18:23:58 moustachesrivo volumio[3113]: info: QobuzConnect: setDeactiveState invoked May 24 18:23:58 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:23:58 moustachesrivo systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV May 24 18:23:58 moustachesrivo systemd[1]: qobuz-connect.service: Failed with result 'signal'. May 24 18:24:03 moustachesrivo systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. May 24 18:24:03 moustachesrivo systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 11. May 24 18:24:03 moustachesrivo systemd[1]: Stopped Volumio Qobuz Connect Service. May 24 18:24:03 moustachesrivo systemd[1]: Started Volumio Qobuz Connect Service. May 24 18:24:03 moustachesrivo volumio[3113]: info: Executing endpoint qc_getconfig May 24 18:24:03 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 24 18:24:03 moustachesrivo qobuz-connect[7234]: 20250524 18:24:03.545 [7234.7234] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 24 18:24:03 moustachesrivo volumio[3113]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 24 18:24:03 moustachesrivo volumio[3113]: info: QobuzConnect: QOBUZ Connect daemon connected May 24 18:24:03 moustachesrivo qobuz-connect[7234]: 20250524 18:24:03.551 [7234.7234] INFO VolumeManager: [0xac0ccb20]: Setting new playback volume: 75 May 24 18:24:03 moustachesrivo qobuz-connect[7234]: 20250524 18:24:03.551 [7234.7234] INFO VolumeManager: [0xac0ccb20]: Setting new mute state: 0 May 24 18:24:03 moustachesrivo qobuz-connect[7234]: 20250524 18:24:03.551 [7234.7234] INFO QobuzConnect: [0xac0cd4f0]: Client initialized! May 24 18:24:03 moustachesrivo qobuz-connect[7234]: 20250524 18:24:03.551 [7234.7234] INFO SampleApp: Starting Avahi advertising, name: MoustachesRivo, service name: _qobuz-connect._tcp May 24 18:24:03 moustachesrivo qobuz-connect[7234]: 20250524 18:24:03.563 [7234.7234] INFO LocalConfigManager: [0xac0cc400]: Starting Local Configuration server May 24 18:24:03 moustachesrivo qobuz-connect[7234]: 20250524 18:24:03.563 [7234.7234] INFO SampleApp: Starting Local configuration server May 24 18:24:03 moustachesrivo qobuz-connect[7234]: 20250524 18:24:03.564 [7234.7234] INFO SampleApp: Connected to UNIX socket client 0xac0c1ed8 May 24 18:24:03 moustachesrivo qobuz-connect[7234]: 20250524 18:24:03.695 [7234.7234] INFO SampleApp: Playback volume changed: 75 May 24 18:24:03 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:24:06 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 18:24:06 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 18:24:06 moustachesrivo volumio[3113]: info: Discovery: Getting this device information May 24 18:24:06 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:24:06 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 18:24:06 moustachesrivo volumio[3113]: verbose: New Socket.io Connection to 192.168.0.109:3000 from 192.168.0.101 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 May 24 18:24:06 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:24:06 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 24 18:24:06 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 24 18:24:08 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:24:09 moustachesrivo volumio[3113]: verbose: New Socket.io Connection to 192.168.0.109:3000 from 192.168.0.101 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 24 18:24:09 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:24:09 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 24 18:24:09 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 24 18:24:09 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:24:10 moustachesrivo sudo[7265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 18:24:10 moustachesrivo sudo[7265]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 18:24:10 moustachesrivo sudo[7265]: pam_unix(sudo:session): session closed for user root May 24 18:24:10 moustachesrivo sudo[7267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 18:24:10 moustachesrivo sudo[7267]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 18:24:10 moustachesrivo sudo[7267]: pam_unix(sudo:session): session closed for user root May 24 18:24:10 moustachesrivo volumio[3113]: verbose: New Socket.io Connection to 192.168.0.109 from 192.168.0.101 UA: Mozilla/5.0 (Linux; Android 14; SM-A336B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 24 18:24:10 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetVisibleSources May 24 18:24:10 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 18:24:10 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 24 18:24:10 moustachesrivo volumio[3113]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 24 18:24:10 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:24:10 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 24 18:24:10 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 24 18:24:10 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 24 18:24:10 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 24 18:24:10 moustachesrivo sudo[7273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 18:24:10 moustachesrivo sudo[7273]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 18:24:10 moustachesrivo sudo[7273]: pam_unix(sudo:session): session closed for user root May 24 18:24:10 moustachesrivo sudo[7277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 18:24:10 moustachesrivo sudo[7277]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 18:24:10 moustachesrivo sudo[7277]: pam_unix(sudo:session): session closed for user root May 24 18:24:10 moustachesrivo volumio[3113]: verbose: New Socket.io Connection to 192.168.0.109 from 192.168.0.101 UA: Mozilla/5.0 (Linux; Android 14; SM-A336B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.60 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 24 18:24:11 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioGetState May 24 18:24:11 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 24 18:24:11 moustachesrivo volumio[3113]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 24 18:24:11 moustachesrivo volumio[3113]: info: Listing playlists May 24 18:24:11 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 24 18:24:11 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 24 18:24:11 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 24 18:24:11 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 24 18:24:15 moustachesrivo volumio[3113]: info: CoreCommandRouter::volumioVolatilePlay May 24 18:24:15 moustachesrivo volumio[3113]: info: CoreStateMachine::volatilePlay May 24 18:24:15 moustachesrivo volumio[3113]: info: WARNING: No play method for volatile plugin undefined May 24 18:24:23 moustachesrivo volumio[3113]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay May 24 18:24:23 moustachesrivo volumio[3113]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom May 24 18:24:23 moustachesrivo volumio[3113]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 18:24:23 moustachesrivo volumio[3113]: TypeError: Cannot read property 'then' of undefined May 24 18:24:23 moustachesrivo volumio[3113]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) May 24 18:24:23 moustachesrivo volumio[3113]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) May 24 18:24:23 moustachesrivo volumio[3113]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1456:26) May 24 18:24:23 moustachesrivo volumio[3113]: at Socket.emit (events.js:400:28) May 24 18:24:23 moustachesrivo volumio[3113]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 24 18:24:23 moustachesrivo volumio[3113]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 24 18:24:23 moustachesrivo volumio[3113]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 18:24:24 moustachesrivo sudo[7306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 18:23 May 24 18:24:24 moustachesrivo sudo[7306]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 06:24:39 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="c8a59422b1bfedffc72f72cef58ad20b"