-- Logs begin at Tue 2025-06-17 08:35:56 UTC, end at Wed 2025-06-18 04:29:49 UTC. -- Jun 18 04:28:02 zen-stream play[24912]: 20250618 04:28:02.590 [24929.24929] TRACE AudioRenderer: [0xab2757c8]: Playback position: 44465 Jun 18 04:28:02 zen-stream play[24912]: 20250618 04:28:02.590 [24929.24929] INFO ProtocolHandler: [0xab275ce8]: Need to send state update Jun 18 04:28:02 zen-stream play[24912]: 20250618 04:28:02.590 [24929.24929] INFO QConnectMessageSender: [0xab3952a8]: Sending StateUpdated message Jun 18 04:28:02 zen-stream play[24912]: 20250618 04:28:02.590 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Playback state: Playing Jun 18 04:28:02 zen-stream play[24912]: 20250618 04:28:02.590 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Current position: 44465 Jun 18 04:28:02 zen-stream play[24912]: 20250618 04:28:02.590 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Duration: 201253 Jun 18 04:28:02 zen-stream play[24912]: 20250618 04:28:02.590 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Queue version: 1.1 Jun 18 04:28:02 zen-stream play[24912]: 20250618 04:28:02.590 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Current queue item ID: 11 Jun 18 04:28:02 zen-stream play[24912]: 20250618 04:28:02.590 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Next queue item ID: 0 Jun 18 04:28:02 zen-stream play[24912]: 20250618 04:28:02.590 [24929.24929] INFO QwspMessageSender: [0xab395298]: Sending Payload message Jun 18 04:28:02 zen-stream play[24912]: 20250618 04:28:02.590 [24929.24929] TRACE QwspMessageSender: [0xab395298]: Protocol: QConnect Jun 18 04:28:02 zen-stream play[24912]: 20250618 04:28:02.590 [24929.24929] TRACE QwspMessageSender: [0xab395298]: Channel: Backend Jun 18 04:28:02 zen-stream play[24912]: 20250618 04:28:02.591 [24929.24929] TRACE PlaybackPositionEstimator: [0xab276278]: Synced playback position: 44465ms Jun 18 04:28:03 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:28:04 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:28:04 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:28:08 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:28:09 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:28:09 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:28:12 zen-stream play[24912]: 20250618 04:28:12.589 [24929.24929] TRACE AudioRenderer: [0xab2757c8]: Playback position: 54465 Jun 18 04:28:12 zen-stream play[24912]: 20250618 04:28:12.590 [24929.24929] INFO ProtocolHandler: [0xab275ce8]: Need to send state update Jun 18 04:28:12 zen-stream play[24912]: 20250618 04:28:12.590 [24929.24929] INFO QConnectMessageSender: [0xab3952a8]: Sending StateUpdated message Jun 18 04:28:12 zen-stream play[24912]: 20250618 04:28:12.590 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Playback state: Playing Jun 18 04:28:12 zen-stream play[24912]: 20250618 04:28:12.590 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Current position: 54465 Jun 18 04:28:12 zen-stream play[24912]: 20250618 04:28:12.590 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Duration: 201253 Jun 18 04:28:12 zen-stream play[24912]: 20250618 04:28:12.590 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Queue version: 1.1 Jun 18 04:28:12 zen-stream play[24912]: 20250618 04:28:12.590 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Current queue item ID: 11 Jun 18 04:28:12 zen-stream play[24912]: 20250618 04:28:12.590 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Next queue item ID: 0 Jun 18 04:28:12 zen-stream play[24912]: 20250618 04:28:12.590 [24929.24929] INFO QwspMessageSender: [0xab395298]: Sending Payload message Jun 18 04:28:12 zen-stream play[24912]: 20250618 04:28:12.590 [24929.24929] TRACE QwspMessageSender: [0xab395298]: Protocol: QConnect Jun 18 04:28:12 zen-stream play[24912]: 20250618 04:28:12.590 [24929.24929] TRACE QwspMessageSender: [0xab395298]: Channel: Backend Jun 18 04:28:12 zen-stream play[24912]: 20250618 04:28:12.590 [24929.24929] TRACE PlaybackPositionEstimator: [0xab276278]: Synced playback position: 54465ms Jun 18 04:28:14 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:28:14 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:28:14 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:28:19 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:28:19 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:28:19 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:28:22 zen-stream play[24912]: 20250618 04:28:22.590 [24929.24929] TRACE AudioRenderer: [0xab2757c8]: Playback position: 64466 Jun 18 04:28:22 zen-stream play[24912]: 20250618 04:28:22.591 [24929.24929] INFO ProtocolHandler: [0xab275ce8]: Need to send state update Jun 18 04:28:22 zen-stream play[24912]: 20250618 04:28:22.591 [24929.24929] INFO QConnectMessageSender: [0xab3952a8]: Sending StateUpdated message Jun 18 04:28:22 zen-stream play[24912]: 20250618 04:28:22.591 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Playback state: Playing Jun 18 04:28:22 zen-stream play[24912]: 20250618 04:28:22.591 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Current position: 64466 Jun 18 04:28:22 zen-stream play[24912]: 20250618 04:28:22.591 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Duration: 201253 Jun 18 04:28:22 zen-stream play[24912]: 20250618 04:28:22.591 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Queue version: 1.1 Jun 18 04:28:22 zen-stream play[24912]: 20250618 04:28:22.591 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Current queue item ID: 11 Jun 18 04:28:22 zen-stream play[24912]: 20250618 04:28:22.591 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Next queue item ID: 0 Jun 18 04:28:22 zen-stream play[24912]: 20250618 04:28:22.591 [24929.24929] INFO QwspMessageSender: [0xab395298]: Sending Payload message Jun 18 04:28:22 zen-stream play[24912]: 20250618 04:28:22.591 [24929.24929] TRACE QwspMessageSender: [0xab395298]: Protocol: QConnect Jun 18 04:28:22 zen-stream play[24912]: 20250618 04:28:22.591 [24929.24929] TRACE QwspMessageSender: [0xab395298]: Channel: Backend Jun 18 04:28:22 zen-stream play[24912]: 20250618 04:28:22.592 [24929.24929] TRACE PlaybackPositionEstimator: [0xab276278]: Synced playback position: 64466ms Jun 18 04:28:24 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:28:24 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:28:24 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:28:29 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:28:29 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:28:29 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:28:32 zen-stream play[24912]: 20250618 04:28:32.591 [24929.24929] TRACE AudioRenderer: [0xab2757c8]: Playback position: 74467 Jun 18 04:28:32 zen-stream play[24912]: 20250618 04:28:32.591 [24929.24929] INFO ProtocolHandler: [0xab275ce8]: Need to send state update Jun 18 04:28:32 zen-stream play[24912]: 20250618 04:28:32.591 [24929.24929] INFO QConnectMessageSender: [0xab3952a8]: Sending StateUpdated message Jun 18 04:28:32 zen-stream play[24912]: 20250618 04:28:32.591 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Playback state: Playing Jun 18 04:28:32 zen-stream play[24912]: 20250618 04:28:32.591 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Current position: 74467 Jun 18 04:28:32 zen-stream play[24912]: 20250618 04:28:32.591 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Duration: 201253 Jun 18 04:28:32 zen-stream play[24912]: 20250618 04:28:32.591 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Queue version: 1.1 Jun 18 04:28:32 zen-stream play[24912]: 20250618 04:28:32.591 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Current queue item ID: 11 Jun 18 04:28:32 zen-stream play[24912]: 20250618 04:28:32.591 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Next queue item ID: 0 Jun 18 04:28:32 zen-stream play[24912]: 20250618 04:28:32.591 [24929.24929] INFO QwspMessageSender: [0xab395298]: Sending Payload message Jun 18 04:28:32 zen-stream play[24912]: 20250618 04:28:32.591 [24929.24929] TRACE QwspMessageSender: [0xab395298]: Protocol: QConnect Jun 18 04:28:32 zen-stream play[24912]: 20250618 04:28:32.591 [24929.24929] TRACE QwspMessageSender: [0xab395298]: Channel: Backend Jun 18 04:28:32 zen-stream play[24912]: 20250618 04:28:32.592 [24929.24929] TRACE PlaybackPositionEstimator: [0xab276278]: Synced playback position: 74467ms Jun 18 04:28:34 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:28:34 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:28:34 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:28:39 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:28:39 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:28:39 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:28:41 zen-stream play[24912]: 20250618 04:28:41.797 [24929.24929] TRACE WebsocketClient: [0xab27c178]: Binary data received, size: 40 Jun 18 04:28:41 zen-stream play[24912]: 20250618 04:28:41.798 [24929.24929] INFO ProtocolHandler: [0xab275ce8]: Received SetState message: Jun 18 04:28:41 zen-stream play[24912]: 20250618 04:28:41.798 [24929.24929] INFO ProtocolHandler: [0xab275ce8]: Playing state: Paused Jun 18 04:28:41 zen-stream play[24912]: 20250618 04:28:41.798 [24929.24929] INFO ProtocolHandler: [0xab275ce8]: Playback position: Jun 18 04:28:41 zen-stream play[24912]: 20250618 04:28:41.798 [24929.24929] INFO ProtocolHandler: [0xab275ce8]: Queue version: Jun 18 04:28:41 zen-stream play[24912]: 20250618 04:28:41.798 [24929.24929] INFO ProtocolHandler: [0xab275ce8]: Current track: Jun 18 04:28:41 zen-stream play[24912]: 20250618 04:28:41.798 [24929.24929] INFO ProtocolHandler: [0xab275ce8]: Next track: Jun 18 04:28:41 zen-stream play[24912]: 20250618 04:28:41.798 [24929.24929] INFO MediaEngine: [0xab2755a8]: Pausing playback Jun 18 04:28:41 zen-stream play[24912]: 20250618 04:28:41.798 [24929.24929] DEBUG MediaEngineCommandSequencer: [0xab275920]: Enqueuing command: Pause Jun 18 04:28:41 zen-stream play[24912]: 20250618 04:28:41.798 [24929.24929] DEBUG MediaEngine: [0xab2755a8]: Executing command: Pause Jun 18 04:28:41 zen-stream play[24912]: 20250618 04:28:41.798 [24929.24929] INFO AudioRenderer: [0xab2757c8]: Pausing audio renderer Jun 18 04:28:41 zen-stream play[24912]: 20250618 04:28:41.798 [24929.24929] DEBUG PlaybackProgressManager: [0xab275820]: Audio playback is paused Jun 18 04:28:41 zen-stream play[24912]: 20250618 04:28:41.798 [24929.24929] TRACE AudioRenderer: [0xab2757c8]: State changed: Playing -> Pausing Jun 18 04:28:41 zen-stream play[24912]: 20250618 04:28:41.798 [24929.24929] DEBUG MediaEngine: [0xab2755a8]: Audio renderer state changed: Playing -> Pausing Jun 18 04:28:41 zen-stream play[24912]: 20250618 04:28:41.798 [24929.24929] INFO SampleApp: Pausing playback Jun 18 04:28:41 zen-stream volumio[859]: info: Jun 18 04:28:41 zen-stream volumio[859]: ---------------------------- Client requests Stop Qobuz Playback Jun 18 04:28:41 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: qobuz_connect , stopQobuzPlayback Jun 18 04:28:41 zen-stream volumio[859]: info: Qobuz Connect playback stop Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.807 [24929.24929] DEBUG AudioRenderer: [0xab2757c8]: Transition to paused state has been acknowledged Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.807 [24929.24929] TRACE AudioRenderer: [0xab2757c8]: State changed: Pausing -> Paused Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.807 [24929.24929] ERROR SampleApp: Unable to get buffered frame count from ALSA audio sink: device is not open Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.807 [24929.24929] TRACE AudioRenderer: [0xab2757c8]: Playback position: 84346 Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.807 [24929.24929] INFO ProtocolHandler: [0xab275ce8]: Need to send state update Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.807 [24929.24929] INFO QConnectMessageSender: [0xab3952a8]: Sending StateUpdated message Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.807 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Playback state: Playing Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.807 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Current position: 84346 Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.807 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Duration: 201253 Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.807 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Queue version: 1.1 Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.807 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Current queue item ID: 11 Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.807 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Next queue item ID: 0 Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.808 [24929.24929] INFO QwspMessageSender: [0xab395298]: Sending Payload message Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.808 [24929.24929] TRACE QwspMessageSender: [0xab395298]: Protocol: QConnect Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.808 [24929.24929] TRACE QwspMessageSender: [0xab395298]: Channel: Backend Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.808 [24929.24929] DEBUG MediaEngine: [0xab2755a8]: Audio renderer state changed: Pausing -> Paused Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.808 [24929.24929] TRACE MediaEngine: [0xab2755a8]: State changed: Playing -> Paused Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.808 [24929.24929] DEBUG MediaEngineCommandSequencer: [0xab275920]: Confirming command: Pause Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.809 [24929.24929] INFO SampleApp: Playback state changed: Paused Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.809 [24929.24929] ERROR SampleApp: Unable to get buffered frame count from ALSA audio sink: device is not open Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.809 [24929.24929] TRACE AudioRenderer: [0xab2757c8]: Playback position: 84346 Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.809 [24929.24929] INFO StreamingEventReporter: [0xab276100]: Reporting streaming ended event Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.809 [24929.24929] TRACE StreamingEventReporter: [0xab276100]: End position: 84346ms Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.809 [24929.24929] TRACE StreamingEventReporter: [0xab276100]: Duration: 69881ms Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.810 [24929.24929] DEBUG StreamingEventReporter: [0xab276100]: Sending streaming reports Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.810 [24929.24929] INFO HttpDownloader: [0xab276158]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.810 [24929.24929] ERROR SampleApp: Unable to get buffered frame count from ALSA audio sink: device is not open Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.810 [24929.24929] TRACE AudioRenderer: [0xab2757c8]: Playback position: 84346 Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.810 [24929.24929] INFO ProtocolHandler: [0xab275ce8]: Need to send state update Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.810 [24929.24929] INFO QConnectMessageSender: [0xab3952a8]: Sending StateUpdated message Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.810 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Playback state: Paused Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.810 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Current position: 84346 Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.811 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Duration: 201253 Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.811 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Queue version: 1.1 Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.811 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Current queue item ID: 11 Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.811 [24929.24929] TRACE QConnectMessageSender: [0xab3952a8]: Next queue item ID: 0 Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.811 [24929.24929] TRACE ProtocolHandler: [0xab275ce8]: Stopping periodic state updates Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.811 [24929.24929] DEBUG UserInactivityManager: [0xab276340]: Renderer is active but not playing, will deactivate in 3600s Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.812 [24929.24929] INFO QwspMessageSender: [0xab395298]: Sending Payload message Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.813 [24929.24929] TRACE QwspMessageSender: [0xab395298]: Protocol: QConnect Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.813 [24929.24929] TRACE QwspMessageSender: [0xab395298]: Channel: Backend Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.813 [24929.24929] TRACE PlaybackPositionEstimator: [0xab276278]: Synced playback position: 84346ms Jun 18 04:28:42 zen-stream play[24912]: 20250618 04:28:42.814 [24929.24929] TRACE PlaybackPositionEstimator: [0xab276278]: Synced playback position: 84346ms Jun 18 04:28:43 zen-stream play[24912]: 20250618 04:28:43.266 [24929.24929] DEBUG StreamingEventReporter: [0xab276100]: Streaming report submitted successfully Jun 18 04:28:44 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:28:44 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:28:44 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:28:46 zen-stream kernel: usb 4-1.3: new high-speed USB device number 4 using xhci-hcd Jun 18 04:28:46 zen-stream kernel: usb 4-1.3: New USB device found, idVendor=20b1, idProduct=3008 Jun 18 04:28:46 zen-stream kernel: usb 4-1.3: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Jun 18 04:28:46 zen-stream kernel: usb 4-1.3: Product: GO bar KENSEI Jun 18 04:28:46 zen-stream kernel: usb 4-1.3: Manufacturer: iFi (by AMR) Jun 18 04:28:46 zen-stream kernel: usb 4-1.3: SerialNumber: 0015 Jun 18 04:28:46 zen-stream kernel: hid-generic 0003:20B1:3008.0001: hiddev0,hidraw0: USB HID v1.10 Device [iFi (by AMR) GO bar KENSEI] on usb-xhci-hcd.0.auto-1.3/input3 Jun 18 04:28:46 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:28:46 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:28:46 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:46 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:28:46 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:28:46 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:46 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:28:46 zen-stream kernel: input: iFi (by AMR) GO bar KENSEI as /devices/platform/usb@ff600000/ff600000.dwc3/xhci-hcd.0.auto/usb4/4-1/4-1.3/4-1.3:1.4/0003:20B1:3008.0002/input/input1 Jun 18 04:28:46 zen-stream kernel: hid-generic 0003:20B1:3008.0002: input,hidraw1: USB HID v1.11 Device [iFi (by AMR) GO bar KENSEI] on usb-xhci-hcd.0.auto-1.3/input4 Jun 18 04:28:46 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:28:46 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:28:46 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:46 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:28:46 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:28:46 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:46 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:28:46 zen-stream systemd-udevd[26949]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 18 04:28:47 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:28:47 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:28:47 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:47 zen-stream kernel: usb 4-1.3: Warning! Unlikely big volume range (=32512), cval->res is probably wrong. Jun 18 04:28:47 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:28:47 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:28:47 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:47 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:28:47 zen-stream kernel: usb 4-1.3: [10] FU [GO bar KENSEI Playback Volume] ch = 2, val = -32512/0/1 Jun 18 04:28:47 zen-stream kernel: usb 4-1.3: Warning! Unlikely big volume range (=32512), cval->res is probably wrong. Jun 18 04:28:47 zen-stream kernel: usb 4-1.3: [10] FU [GO bar KENSEI Playback Volume] ch = 1, val = -32512/0/1 Jun 18 04:28:47 zen-stream kernel: usbcore: registered new interface driver snd-usb-audio Jun 18 04:28:47 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:28:47 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:28:47 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:47 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:28:47 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:28:47 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:47 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:28:47 zen-stream systemd-udevd[26949]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Jun 18 04:28:47 zen-stream volumio[859]: info: Jun 18 04:28:47 zen-stream volumio[859]: ---------------------------- USB Audio Device Attached Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::Close All Modals sent Jun 18 04:28:47 zen-stream volumio[859]: info: Preparing to save Alsa Options, stopping services first Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::volumioGetState Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::volumioPause Jun 18 04:28:47 zen-stream volumio[859]: info: CoreStateMachine::pause Jun 18 04:28:47 zen-stream volumio[859]: info: Saving Audio Output to: {"disallowPush":false,"output_device":{"value":"5","label":"GO bar KENSEI"}} Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , forceDoP Jun 18 04:28:47 zen-stream volumio[859]: info: GO bar KENSEI: Supports DSD Native. Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 18 04:28:47 zen-stream volumio[859]: info: Ignoring Mixers Options Jun 18 04:28:47 zen-stream volumio[859]: info: Device 5 does not have any Mixer Control Available Jun 18 04:28:47 zen-stream volumio[859]: info: Restoring Previous Volume level: 100 false false Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 18 04:28:47 zen-stream volumio[859]: info: Updating Volume Controller Parameters: Device: 5 Name: GO bar KENSEI Mixer: None Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Jun 18 04:28:47 zen-stream volumio[859]: info: CoreStateMachine::pushState Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::volumioPushState Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 04:28:47 zen-stream volumio[859]: info: Preparing to generate the ALSA configuration file Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: wizard , refreshWizarOutputDevices Jun 18 04:28:47 zen-stream volumio[859]: info: Asound.conf file written Jun 18 04:28:47 zen-stream sudo[26987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 18 04:28:47 zen-stream sudo[26987]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:28:47 zen-stream sudo[26987]: pam_unix(sudo:session): session closed for user root Jun 18 04:28:47 zen-stream volumio[859]: No state is present for card rkifispdif Jun 18 04:28:47 zen-stream volumio[859]: Found hardware: "rk-ifi-spdif" "" "" "" "" Jun 18 04:28:47 zen-stream volumio[859]: Hardware is initialized using a generic method Jun 18 04:28:47 zen-stream volumio[859]: No state is present for card rkifispdif Jun 18 04:28:47 zen-stream volumio[859]: No state is present for card KENSEI Jun 18 04:28:47 zen-stream volumio[859]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:3008" "" "" Jun 18 04:28:47 zen-stream volumio[859]: Hardware is initialized using a generic method Jun 18 04:28:47 zen-stream volumio[859]: No state is present for card KENSEI Jun 18 04:28:47 zen-stream volumio[859]: info: Tidal connect: Output device has changed Jun 18 04:28:47 zen-stream volumio[859]: info: Output device has changed, restarting MPD Jun 18 04:28:47 zen-stream volumio[859]: info: Qobuz connect: Output device has changed Jun 18 04:28:47 zen-stream volumio[859]: info: Roon Ready: Output device has changed Jun 18 04:28:47 zen-stream volumio[859]: info: Spotify: Output device has changed Jun 18 04:28:47 zen-stream volumio[859]: info: Tidal connect: Output device has changed Jun 18 04:28:47 zen-stream volumio[859]: info: Output device has changed, restarting MPD Jun 18 04:28:47 zen-stream sudo[26993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 04:28:47 zen-stream sudo[26993]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:28:47 zen-stream sudo[26993]: pam_unix(sudo:session): session closed for user root Jun 18 04:28:47 zen-stream volumio[859]: info: Qobuz connect: Output device has changed Jun 18 04:28:47 zen-stream volumio[859]: info: Roon Ready: Output device has changed Jun 18 04:28:47 zen-stream volumio[859]: info: Spotify: Output device has changed Jun 18 04:28:47 zen-stream volumio[859]: info: MPD Permissions set Jun 18 04:28:47 zen-stream sudo[26996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:28:47 zen-stream sudo[26996]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:28:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:28:47 zen-stream sudo[26996]: pam_unix(sudo:session): session closed for user root Jun 18 04:28:48 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 04:28:48 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 04:28:48 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:28:48 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:28:48 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:28:48 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:28:48 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:28:48 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:28:48 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:28:48 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:28:48 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:28:48 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 04:28:48 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 04:28:48 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:28:48 zen-stream volumio[859]: info: MPD Permissions set Jun 18 04:28:48 zen-stream volumio[859]: info: Starting RoonReady Sync Jun 18 04:28:48 zen-stream sudo[27019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Jun 18 04:28:48 zen-stream sudo[27019]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:28:48 zen-stream sudo[27020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Jun 18 04:28:48 zen-stream sudo[27020]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:28:48 zen-stream systemd[1]: Stopping iFi Streamer Qobuz Connect Service... Jun 18 04:28:48 zen-stream systemd[1]: ifi-streamer-qobuz-connect.service: Main process exited, code=killed, status=15/TERM Jun 18 04:28:48 zen-stream node[489]: ConvSwitch: Drop client qobuz-connect Jun 18 04:28:48 zen-stream volumio[859]: info: Starting RoonReady Sync Jun 18 04:28:48 zen-stream systemd[1]: ifi-streamer-qobuz-connect.service: Succeeded. Jun 18 04:28:48 zen-stream systemd[1]: Stopped iFi Streamer Qobuz Connect Service. Jun 18 04:28:48 zen-stream sudo[27023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Jun 18 04:28:48 zen-stream sudo[27023]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:28:48 zen-stream sudo[27019]: pam_unix(sudo:session): session closed for user root Jun 18 04:28:48 zen-stream sudo[27027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Jun 18 04:28:48 zen-stream sudo[27027]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:28:48 zen-stream systemd[1]: Stopping iFi Streamer Roon Ready Service... Jun 18 04:28:48 zen-stream node[489]: ConvSwitch: Drop client roon-ready Jun 18 04:28:48 zen-stream systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=killed, status=15/TERM Jun 18 04:28:48 zen-stream systemd[1]: ifi-streamer-roon-ready.service: Succeeded. Jun 18 04:28:48 zen-stream systemd[1]: Stopped iFi Streamer Roon Ready Service. Jun 18 04:28:48 zen-stream sudo[27023]: pam_unix(sudo:session): session closed for user root Jun 18 04:28:48 zen-stream systemd[1]: Started iFi Streamer Roon Ready Service. Jun 18 04:28:48 zen-stream sudo[27020]: pam_unix(sudo:session): session closed for user root Jun 18 04:28:48 zen-stream sudo[27027]: pam_unix(sudo:session): session closed for user root Jun 18 04:28:48 zen-stream volumio[859]: info: RoonReady Started Jun 18 04:28:48 zen-stream volumio[859]: info: RoonReady Started Jun 18 04:28:49 zen-stream play[27029]: /usr/ifi/ifi-roonready-release/play: line 18: warning: command substitution: ignored null byte in input Jun 18 04:28:49 zen-stream node[489]: ConvSwitch: Add client roon-ready Jun 18 04:28:49 zen-stream volumio[859]: info: ConvSwitch: Get message: request:pause Jun 18 04:28:49 zen-stream volumio[859]: info: CoreCommandRouter::volumioStop Jun 18 04:28:49 zen-stream volumio[859]: info: CoreStateMachine::stop Jun 18 04:28:49 zen-stream volumio[859]: info: CoreStateMachine::serviceStop Jun 18 04:28:49 zen-stream volumio[859]: info: CoreCommandRouter::serviceStop Jun 18 04:28:49 zen-stream volumio[859]: error: Could not retrieve plugin music_service qobuz_connect Jun 18 04:28:49 zen-stream volumio[859]: error: WARNING: No stop method for service qobuz_connect Jun 18 04:28:49 zen-stream volumio[859]: info: Jun 18 04:28:49 zen-stream volumio[859]: ---------------------------- Client requests Start RoonReady PlaybackRoutine Jun 18 04:28:49 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: roon_ready , startRoonReadyPlayback Jun 18 04:28:49 zen-stream volumio[859]: info: Roon Ready playback start Jun 18 04:28:49 zen-stream volumio[859]: info: CoreCommandRouter::volumioStop Jun 18 04:28:49 zen-stream volumio[859]: info: CoreStateMachine::stop Jun 18 04:28:49 zen-stream volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 04:28:49 zen-stream volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 04:28:49 zen-stream volumio[859]: info: Roon Ready metadata receiver is on Jun 18 04:28:49 zen-stream sudo[27080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Jun 18 04:28:49 zen-stream sudo[27080]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:28:49 zen-stream systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Jun 18 04:28:49 zen-stream systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Jun 18 04:28:49 zen-stream systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Jun 18 04:28:49 zen-stream systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Jun 18 04:28:49 zen-stream systemd[1]: Stopping iFi Streamer Tidal Connect Service... Jun 18 04:28:49 zen-stream systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Jun 18 04:28:49 zen-stream node[489]: ConvSwitch: Drop client tidal Jun 18 04:28:49 zen-stream systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Jun 18 04:28:49 zen-stream systemd[1]: Stopped iFi Streamer Tidal Connect Service. Jun 18 04:28:49 zen-stream systemd[1]: Started iFi Streamer Tidal Connect Service. Jun 18 04:28:49 zen-stream sudo[27080]: pam_unix(sudo:session): session closed for user root Jun 18 04:28:49 zen-stream systemd[1]: Started iFi Streamer Tidal Metadata Service. Jun 18 04:28:49 zen-stream volumio[859]: info: Tidal Connect Service Started Jun 18 04:28:49 zen-stream ifi_metadata[27085]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:28:49 zen-stream sudo[27084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Jun 18 04:28:49 zen-stream sudo[27084]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:28:49 zen-stream systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Jun 18 04:28:49 zen-stream systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Jun 18 04:28:49 zen-stream systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Jun 18 04:28:49 zen-stream systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Jun 18 04:28:49 zen-stream systemd[1]: Stopping iFi Streamer Tidal Connect Service... Jun 18 04:28:49 zen-stream systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Jun 18 04:28:49 zen-stream systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Jun 18 04:28:49 zen-stream systemd[1]: Stopped iFi Streamer Tidal Connect Service. Jun 18 04:28:49 zen-stream systemd[1]: Started iFi Streamer Tidal Connect Service. Jun 18 04:28:49 zen-stream sudo[27084]: pam_unix(sudo:session): session closed for user root Jun 18 04:28:49 zen-stream systemd[1]: Started iFi Streamer Tidal Metadata Service. Jun 18 04:28:49 zen-stream volumio[859]: info: Tidal Connect Service Started Jun 18 04:28:49 zen-stream ifi_metadata[27095]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:28:49 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:28:49 zen-stream sudo[27096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Jun 18 04:28:49 zen-stream sudo[27096]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:28:49 zen-stream systemd[1]: Started iFi Streamer Qobuz Connect Service. Jun 18 04:28:49 zen-stream sudo[27096]: pam_unix(sudo:session): session closed for user root Jun 18 04:28:49 zen-stream sudo[27112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Jun 18 04:28:49 zen-stream volumio[859]: info: Qobuz Connect Service Started Jun 18 04:28:49 zen-stream sudo[27112]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:28:49 zen-stream sudo[27112]: pam_unix(sudo:session): session closed for user root Jun 18 04:28:49 zen-stream volumio[859]: info: Qobuz Connect Service Started Jun 18 04:28:49 zen-stream play[27118]: 20250618 04:28:49.914 [27149.27149] DEBUG SampleApp: Starting sample application Jun 18 04:28:49 zen-stream play[27118]: 20250618 04:28:49.914 [27149.27149] DEBUG SampleApp: Device Name: ZEN Stream Jun 18 04:28:49 zen-stream play[27118]: 20250618 04:28:49.914 [27149.27149] DEBUG SampleApp: Manufacturer: Sample manufacturer Jun 18 04:28:49 zen-stream play[27118]: 20250618 04:28:49.914 [27149.27149] DEBUG SampleApp: Model: iFi Streamer Jun 18 04:28:49 zen-stream play[27118]: 20250618 04:28:49.914 [27149.27149] DEBUG SampleApp: Serial Number: ifi:e6:5a:25:9a:a2:ae Jun 18 04:28:49 zen-stream play[27118]: 20250618 04:28:49.914 [27149.27149] DEBUG SampleApp: Application ID: 679318972 Jun 18 04:28:49 zen-stream play[27118]: 20250618 04:28:49.915 [27149.27149] DEBUG SampleApp: Application Secret: a32fe515ff9d11ffafcb2b2a1426c244 Jun 18 04:28:49 zen-stream play[27118]: 20250618 04:28:49.915 [27149.27149] DEBUG SampleApp: Audio output device: volumio Jun 18 04:28:49 zen-stream play[27118]: 20250618 04:28:49.915 [27149.27149] DEBUG SampleApp: Volume control device: default Jun 18 04:28:49 zen-stream play[27118]: 20250618 04:28:49.915 [27149.27149] DEBUG SampleApp: Volume control element: Master Jun 18 04:28:49 zen-stream play[27118]: 20250618 04:28:49.915 [27149.27149] DEBUG SampleApp: Local config server port: 8000 Jun 18 04:28:49 zen-stream play[27118]: 20250618 04:28:49.920 [27149.27149] ERROR SampleApp: Unable to find ALSA mixer element 'Master' on index 0 Jun 18 04:28:49 zen-stream play[27118]: 20250618 04:28:49.920 [27149.27149] DEBUG SampleApp: Maximum supported audio quality: HIRES_L3 Jun 18 04:28:49 zen-stream play[27118]: [Info] MetaClient: MetaClient SDK -- v1.02 Jun 18 04:28:49 zen-stream play[27118]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:28:49 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:28:49 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:28:49 zen-stream play[27118]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Jun 18 04:28:49 zen-stream node[489]: ConvSwitch: Add client qobuz-connect Jun 18 04:28:49 zen-stream sudo[27160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Jun 18 04:28:49 zen-stream sudo[27160]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:28:50 zen-stream systemd[1]: mpd.socket: Succeeded. Jun 18 04:28:50 zen-stream systemd[1]: Closed mpd.socket. Jun 18 04:28:50 zen-stream systemd[1]: Stopping Music Player Daemon... Jun 18 04:28:50 zen-stream sudo[27165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Jun 18 04:28:50 zen-stream sudo[27165]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:28:50 zen-stream play[27118]: [Info] ConvSwitch: ConvSwitchInit Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.034 [27149.27149] DEBUG ActiveStateManager: [0xab919ee0]: Creating new Active State Manager Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.034 [27149.27149] DEBUG NetworkActivityTracker: [0xab919f50]: Creating new Network Activity Tracker Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.034 [27149.27149] DEBUG EndpointManager: [0xab91aec8]: Creating Endpoint Manager Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.034 [27149.27149] DEBUG PlaybackSessionManager: [0xab91b2b8]: Creating Playback Session Manager Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] DEBUG BackoffTimer: [0xab91b430]: Creating Backoff Timer Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] DEBUG MediaEngine: [0xab91b550]: Creating new Media Engine Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] DEBUG AudioRenderer: [0xab91b770]: Creating Audio Renderer Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] TRACE MediaEngineCommandSequencer: [0xab91b8c8]: Creating Media Engine Command Sequencer Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] DEBUG PlaybackControlsManager: [0xab91b928]: Creating new Playback Controls Manager Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] DEBUG VolumeManager: [0xab91b9b8]: Creating new Volume Manager Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] DEBUG ProtocolHandler: [0xab91bc90]: Creating Protocol Handler Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] DEBUG MessageReceiver: [0xab91bd30]: Creating Message Receiver Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] DEBUG RendererActivationController: [0xab91bea8]: Creating new Renderer Activation Controller Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] TRACE RendererActivationCommandSequencer: [0xab91bf50]: Creating Renderer Activation Command Sequencer Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] DEBUG ReportingManager: [0xab91c060]: Creating new Reporting Manager Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] DEBUG StreamingEventReporter: [0xab91c0a8]: Creating Streaming Event Reporter Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] DEBUG BackoffTimer: [0xab91c188]: Creating Backoff Timer Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] DEBUG TokenExpiryController: [0xab91c298]: Creating Token Expiry Controller Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] DEBUG UserInactivityManager: [0xab91c2e8]: Creating new User Inactivity Manager Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] INFO VolumeManager: [0xab91b9b8]: Setting new playback volume: 100 Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] INFO VolumeManager: [0xab91b9b8]: Setting new mute state: 1 Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] DEBUG SampleApp: Qobuz Connect has been initialized Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] TRACE SampleApp: Running event loop Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] INFO QobuzConnect: [0xab91c388]: Client initialized! Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.035 [27149.27149] INFO SampleApp: Starting Avahi advertising, name: ZEN Stream, service name: _qobuz-connect._tcp Jun 18 04:28:50 zen-stream volumio[859]: MPD server connection closed Jun 18 04:28:50 zen-stream volumio[859]: Connection closed by client Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.059 [27149.27149] DEBUG SampleApp: Avahi client state changed: RUNNING Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.063 [27149.27149] DEBUG SampleApp: Avahi entry group state changed: UNCOMMITED Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.070 [27149.27149] INFO LocalConfigManager: [0xab91b298]: Starting Local Configuration server Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.070 [27149.27149] INFO SampleApp: Starting Local configuration server Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.071 [27149.27149] INFO SampleApp: Playback mute state changed: 1 Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.071 [27149.27149] ERROR SampleApp: Unable to set mute state, no ALSA volume element Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.072 [27149.27149] DEBUG SampleApp: Avahi entry group state changed: REGISTERING Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.072 [27149.27149] INFO SampleApp: Playback volume changed: 100 Jun 18 04:28:50 zen-stream play[27118]: 20250618 04:28:50.072 [27149.27149] ERROR SampleApp: Unable to set volume, no ALSA volume element Jun 18 04:28:50 zen-stream systemd[1]: mpd.service: Succeeded. Jun 18 04:28:50 zen-stream systemd[1]: Stopped Music Player Daemon. Jun 18 04:28:50 zen-stream sudo[27160]: pam_unix(sudo:session): session closed for user root Jun 18 04:28:50 zen-stream sudo[27165]: pam_unix(sudo:session): session closed for user root Jun 18 04:28:50 zen-stream sudo[27177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Jun 18 04:28:50 zen-stream sudo[27177]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:28:50 zen-stream sudo[27179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Jun 18 04:28:50 zen-stream sudo[27179]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:28:50 zen-stream systemd[1]: Listening on mpd.socket. Jun 18 04:28:50 zen-stream systemd[1]: mpd.socket: Succeeded. Jun 18 04:28:50 zen-stream systemd[1]: Closed mpd.socket. Jun 18 04:28:50 zen-stream systemd[1]: Stopping mpd.socket. Jun 18 04:28:50 zen-stream systemd[1]: Listening on mpd.socket. Jun 18 04:28:50 zen-stream systemd[1]: Starting Music Player Daemon... Jun 18 04:28:50 zen-stream systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 18 04:28:50 zen-stream systemd[1]: mpd.service: Succeeded. Jun 18 04:28:50 zen-stream systemd[1]: Stopped Music Player Daemon. Jun 18 04:28:50 zen-stream volumio[859]: info: CoreCommandRouter::servicePushState Jun 18 04:28:50 zen-stream volumio[859]: info: CoreStateMachine::pushState Jun 18 04:28:50 zen-stream volumio[859]: info: CoreCommandRouter::volumioPushState Jun 18 04:28:50 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 04:28:50 zen-stream systemd[1]: Starting Music Player Daemon... Jun 18 04:28:50 zen-stream sudo[27206]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 04:28:50 zen-stream sudo[27206]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:28:50 zen-stream sudo[27206]: pam_unix(sudo:session): session closed for user root Jun 18 04:28:50 zen-stream ifi_metadata[27095]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:28:50 zen-stream play[27118]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:28:51 zen-stream play[27118]: 20250618 04:28:51.062 [27149.27149] DEBUG SampleApp: Avahi entry group state changed: ESTABLISHED Jun 18 04:28:51 zen-stream sudo[27227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Jun 18 04:28:51 zen-stream sudo[27227]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:28:51 zen-stream volumio[859]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 18 04:28:51 zen-stream volumio[859]: info: CoreStateMachine::getcurrentVolume Jun 18 04:28:51 zen-stream volumio[859]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 04:28:51 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Jun 18 04:28:51 zen-stream volumio[859]: info: CoreStateMachine::pushState Jun 18 04:28:51 zen-stream volumio[859]: info: CoreCommandRouter::volumioPushState Jun 18 04:28:51 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 04:28:51 zen-stream play[2580]: 2025-06-18 04:28:51.554 E esdk select error: 4 -> Interrupted system call Jun 18 04:28:51 zen-stream play[2580]: 2025-06-18 04:28:51.555 I app Exiting with error = 0 Jun 18 04:28:51 zen-stream play[2580]: 2025-06-18 04:28:51.555 I api SpFree() Jun 18 04:28:51 zen-stream play[2580]: 2025-06-18 04:28:51.555 E esdk Unable to set option:6 error:Bad file descriptor(9) Jun 18 04:28:51 zen-stream play[2580]: 2025-06-18 04:28:51.555 E esdk Socket close error: 9 Jun 18 04:28:51 zen-stream play[2580]: 2025-06-18 04:28:51.555 E esdk socket_close ret: -10002 Jun 18 04:28:51 zen-stream play[2580]: 2025-06-18 04:28:51.555 I api SpFree [returned value: 0] Jun 18 04:28:51 zen-stream play[2580]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Jun 18 04:28:51 zen-stream play[2580]: [Info] ConvSwitch: ConvSwitchInit Jun 18 04:28:51 zen-stream play[2580]: [Info] MetaClient: MetaClient SDK -- v1.02 Jun 18 04:28:51 zen-stream play[2580]: [Info] ConvSwitch: ConvSwitchExit Jun 18 04:28:51 zen-stream play[2580]: [Info] MetaClient: MetaClientExit Jun 18 04:28:51 zen-stream systemd[1]: Stopping iFi Streamer Spotify Connect Service... Jun 18 04:28:51 zen-stream node[489]: ConvSwitch: Drop client spotify Jun 18 04:28:51 zen-stream systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Jun 18 04:28:51 zen-stream systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Jun 18 04:28:51 zen-stream systemd[1]: Stopped iFi Streamer Spotify Connect Service. Jun 18 04:28:51 zen-stream systemd[1]: Started iFi Streamer Spotify Connect Service. Jun 18 04:28:51 zen-stream sudo[27227]: pam_unix(sudo:session): session closed for user root Jun 18 04:28:51 zen-stream volumio[859]: info: Spotify Connect Service Started Jun 18 04:28:51 zen-stream sudo[27231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Jun 18 04:28:51 zen-stream sudo[27231]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:28:51 zen-stream ifi_metadata[27095]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:28:51 zen-stream systemd[1]: Stopping iFi Streamer Spotify Connect Service... Jun 18 04:28:51 zen-stream systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Jun 18 04:28:51 zen-stream systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Jun 18 04:28:51 zen-stream systemd[1]: Stopped iFi Streamer Spotify Connect Service. Jun 18 04:28:51 zen-stream systemd[1]: Started iFi Streamer Spotify Connect Service. Jun 18 04:28:51 zen-stream volumio[859]: info: CoreCommandRouter::servicePushState Jun 18 04:28:51 zen-stream volumio[859]: info: CoreStateMachine::pushState Jun 18 04:28:51 zen-stream volumio[859]: info: CoreCommandRouter::volumioPushState Jun 18 04:28:51 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 04:28:51 zen-stream sudo[27231]: pam_unix(sudo:session): session closed for user root Jun 18 04:28:51 zen-stream volumio[859]: info: Spotify Connect Service Started Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:28:51 zen-stream kernel: hid-generic 0003:20B1:3008.0003: hiddev0,hidraw0: USB HID v1.10 Device [iFi (by AMR) GO bar KENSEI] on usb-xhci-hcd.0.auto-1.3/input3 Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:28:51 zen-stream play[27237]: output: volumio mixer: hw:5 Jun 18 04:28:51 zen-stream node[489]: ConvSwitch: Add client spotify Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:28:51 zen-stream kernel: input: iFi (by AMR) GO bar KENSEI as /devices/platform/usb@ff600000/ff600000.dwc3/xhci-hcd.0.auto/usb4/4-1/4-1.3/4-1.3:1.4/0003:20B1:3008.0004/input/input2 Jun 18 04:28:51 zen-stream play[27237]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:28:51 zen-stream kernel: hid-generic 0003:20B1:3008.0004: input,hidraw1: USB HID v1.11 Device [iFi (by AMR) GO bar KENSEI] on usb-xhci-hcd.0.auto-1.3/input4 Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:28:51 zen-stream play[27118]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.943 I api SpRegisterDebugCallbacks [returned value: 0] Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.943 I api SpInit(0xffeb85c0, API v66) Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.943 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.944 I esdk Successfully registered socket callbacks: cre, set_opt, cl, bind, list, con, acc, rd, wr, rd_from, wr_to, err, readable, writable, local_addresses, address, pump Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.944 I esdk Successfully registered dns callback: dns_lookup_callback Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.944 I download Using 128 kB for cache buffer Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.944 I download Using 621 kB for compressed audio buffer Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.944 I esdk Successfully registered error callback Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.958 I esdk Creating IPv4 socket (domain 2) Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.959 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.959 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.959 I esdk Creating IPv4 socket (domain 2) Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.959 I api SpInit [returned value: 0] Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.959 I api SpRegisterConnectionCallbacks(0xffeb85a8, (nil)) Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.960 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.960 I api SpRegisterConnectionCallbacks [returned value: 0] Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.960 I api SpRegisterPlaybackCallbacks(0xffeb8590, (nil)) Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.960 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.960 I api SpRegisterPlaybackCallbacks [returned value: 0] Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.967 W app Couldn't find mixer for volume controls. (tried , SoftMaster) Jun 18 04:28:51 zen-stream play[27237]: 2025-06-18 04:28:51.967 I app Initialized! Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:28:51 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:51 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:28:52 zen-stream systemd-udevd[27256]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 18 04:28:52 zen-stream volumio[859]: info: VolumeController::SetAlsaVolume100 Jun 18 04:28:52 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Jun 18 04:28:52 zen-stream volumio[859]: info: CoreStateMachine::pushState Jun 18 04:28:52 zen-stream volumio[859]: info: CoreCommandRouter::volumioPushState Jun 18 04:28:52 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 04:28:52 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:52 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:52 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:28:52 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:52 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:52 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:28:52 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:52 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:52 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:28:52 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:52 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:52 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:28:52 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:52 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:52 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:28:52 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:52 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:28:52 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:28:52 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:28:52 zen-stream play[27118]: 20250618 04:28:52.360 [27149.27167] INFO SampleApp: API endpoint invoked: get-display-info Jun 18 04:28:52 zen-stream node[489]: ConvSwitch: Add client tidal Jun 18 04:28:52 zen-stream tidal_connect_application[27287]: *** WARNING *** The program 'tidal_connect_application' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 04:28:52 zen-stream tidal_connect_application[27287]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 04:28:52 zen-stream tidal_connect_application[27287]: *** WARNING *** For more information see Jun 18 04:28:52 zen-stream ifi_metadata[27095]: [Info] MetaClient: MetaClient SDK -- v1.02 Jun 18 04:28:52 zen-stream ifi_metadata[27095]: Connected Jun 18 04:28:52 zen-stream play[27237]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:28:53 zen-stream mpd[27210]: Jun 18 04:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 18 04:28:53 zen-stream systemd[1]: Started Music Player Daemon. Jun 18 04:28:53 zen-stream sudo[27177]: pam_unix(sudo:session): session closed for user root Jun 18 04:28:53 zen-stream sudo[27179]: pam_unix(sudo:session): session closed for user root Jun 18 04:28:53 zen-stream volumio[859]: info: Restarted MPD. Jun 18 04:28:53 zen-stream volumio[859]: info: Restarted MPD. Jun 18 04:28:53 zen-stream volumio[859]: error: MPD error: The expression evaluated to a falsy value: Jun 18 04:28:53 zen-stream volumio[859]: assert.ok(self.idling) Jun 18 04:28:53 zen-stream volumio[859]: error: The expression evaluated to a falsy value: Jun 18 04:28:53 zen-stream volumio[859]: assert.ok(self.idling) Jun 18 04:28:53 zen-stream volumio[859]: error: updateQueue error: null Jun 18 04:28:53 zen-stream play[27237]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:28:54 zen-stream volumio[859]: info: CoreCommandRouter::volumioStop Jun 18 04:28:54 zen-stream volumio[859]: info: CoreStateMachine::stop Jun 18 04:28:54 zen-stream volumio[859]: info: CoreStateMachine::serviceStop Jun 18 04:28:54 zen-stream volumio[859]: info: CoreCommandRouter::serviceStop Jun 18 04:28:54 zen-stream volumio[859]: info: CoreCommandRouter::volumioStop Jun 18 04:28:54 zen-stream volumio[859]: info: CoreStateMachine::stop Jun 18 04:28:54 zen-stream volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 04:28:54 zen-stream volumio[859]: info: CoreStateMachine::updateTrackBlock Jun 18 04:28:54 zen-stream volumio[859]: info: CorePlayQueue::getTrackBlock Jun 18 04:28:54 zen-stream volumio[859]: info: CoreStateMachine::stPlaybackTimer Jun 18 04:28:54 zen-stream volumio[859]: info: CoreStateMachine::pushState Jun 18 04:28:54 zen-stream volumio[859]: info: CorePlayQueue::getTrack 0 Jun 18 04:28:54 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 04:28:54 zen-stream volumio[859]: info: CoreCommandRouter::volumioPushState Jun 18 04:28:54 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 04:28:54 zen-stream volumio[859]: info: CoreStateMachine::serviceStop Jun 18 04:28:54 zen-stream volumio[859]: info: CorePlayQueue::getTrack 0 Jun 18 04:28:54 zen-stream volumio[859]: info: CoreCommandRouter::serviceStop Jun 18 04:28:54 zen-stream volumio[859]: info: [1750220934040] ControllerWebradio::stop Jun 18 04:28:54 zen-stream volumio[859]: verbose: ControllerMpd::sendMpdCommand stop Jun 18 04:28:54 zen-stream volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 04:28:54 zen-stream volumio[859]: info: Roon Ready Stop Jun 18 04:28:54 zen-stream volumio[859]: info: sendMpdCommand stop took 32 milliseconds Jun 18 04:28:54 zen-stream volumio[859]: info: CoreCommandRouter::volumioStop Jun 18 04:28:54 zen-stream volumio[859]: info: CoreStateMachine::stop Jun 18 04:28:54 zen-stream volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 04:28:54 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:28:55 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:28:55 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:28:59 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:29:00 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:29:00 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:29:01 zen-stream volumio[859]: info: ++++--++++ getMenuItems Jun 18 04:29:02 zen-stream volumio[859]: info: ++++--++++ getUiConfig: {"page":"audio_interface/alsa_controller"} Jun 18 04:29:02 zen-stream volumio[859]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 18 04:29:02 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 18 04:29:02 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 04:29:02 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 18 04:29:03 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:29:03 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:29:03 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:29:03 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:29:03 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:29:03 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:29:03 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:29:03 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 18 04:29:03 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 18 04:29:05 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:29:05 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:29:05 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.336 [27149.27167] INFO SampleApp: API endpoint invoked: get-connect-info Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.692 [27149.27167] INFO SampleApp: API endpoint invoked: connect-to-qconnect Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27167] DEBUG SampleApp: Session ID: 8dae6a3b-d300-4493-aeb0-18c7242bdee2 Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27167] DEBUG SampleApp: JWT API: Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27167] DEBUG SampleApp: Endpoint: https://www.qobuz.com/api.json/0.2 Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27167] DEBUG SampleApp: JWT: eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImRWN3hqanE2TFljdXpGYWdyT1pkMWxuSEoyMVhRMk5UIn0.eyJpYXQiOjE3NTAyMjA5NDksImV4cCI6MTc1MDIyNDU0OSwiaXNzIjoiUW9idXogQVBJIiwicXVpZCI6MjY3OTE1NywicWFpZCI6IjY3OTMxODk3MiJ9.DELNYWEsAY98CvHQDmZ3GC03iUs75YyMAhDOQ4HPVNY Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27167] DEBUG SampleApp: Exp: 1750224549 Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27167] DEBUG SampleApp: JWT QConnect: Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27167] DEBUG SampleApp: Endpoint: wss://qws-us-prod.qobuz.com/ws Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27167] DEBUG SampleApp: JWT: eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJpYXQiOjE3NTAyMjA5NDksImV4cCI6MTc1MDIyNDU0OSwiaXNzIjoiUW9idXogQVBJIiwicXVpZCI6MjY3OTE1NywicWFpZCI6IjY3OTMxODk3MiJ9.Hq98ja59N_CnwApzaNrWbuvvXL-f6tb38QxttJI-gY0 Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27167] DEBUG SampleApp: Exp: 1750224549 Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27167] DEBUG SampleApp: Become Active: true Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27149] DEBUG RendererActivationCommandSequencer: [0xab91bf50]: Enqueuing command: Deactivate + Disconnect Renderer Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27149] DEBUG RendererActivationCommandSequencer: [0xab91bf50]: Enqueuing command: Connect Renderer Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27149] DEBUG RendererActivationController: [0xab91bea8]: Executing command: Deactivate + Disconnect Renderer Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27149] DEBUG RendererActivationCommandSequencer: [0xab91bf50]: Confirming command: Deactivate + Disconnect Renderer Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27149] DEBUG RendererActivationController: [0xab91bea8]: Executing command: Connect Renderer Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27149] DEBUG RendererActivationController: [0xab91bea8]: Updating endpoints Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27149] INFO EndpointManager: [0xab91aec8]: Updating API endpoint Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27149] TRACE EndpointManager: [0xab91aed8]: API endpoint's token state changed: Invalid -> Valid Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27149] DEBUG EndpointManager: [0xab91aed8]: API endpoint's token will be refreshed in 3540 seconds Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27149] DEBUG EndpointManager: [0xab91aed8]: API endpoint's token will expire in 3600 seconds Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27149] INFO EndpointManager: [0xab91aec8]: Updating QConnect endpoint Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.693 [27149.27149] TRACE EndpointManager: [0xab91afc0]: QConnect endpoint's token state changed: Invalid -> Valid Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.694 [27149.27149] DEBUG EndpointManager: [0xab91afc0]: QConnect endpoint's token will be refreshed in 3540 seconds Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.694 [27149.27149] DEBUG EndpointManager: [0xab91afc0]: QConnect endpoint's token will expire in 3600 seconds Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.694 [27149.27149] DEBUG RendererActivationController: [0xab91bea8]: Making the renderer active Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.694 [27149.27149] INFO ActiveStateManager: [0xab919ee0]: Setting new active state: active Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.694 [27149.27149] DEBUG RendererActivationController: [0xab91bea8]: Starting playback session maintenance Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.694 [27149.27149] INFO PlaybackSessionManager: [0xab91b2b8]: Starting playback session maintenance Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.694 [27149.27149] DEBUG PlaybackSessionManager: [0xab91b2b8]: Refreshing playback session Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.694 [27149.27149] DEBUG NetworkActivityTracker: [0xab919f50]: Network activity started Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.694 [27149.27149] INFO HttpDownloader: [0xab91b4a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/session/start Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.694 [27149.27149] DEBUG RendererActivationController: [0xab91bea8]: Connecting to the server Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.694 [27149.27149] INFO CloudClient: [0xab91bb28]: Connecting to the cloud Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.694 [27149.27149] DEBUG WebsocketClient: [0xab922120]: Creating Websocket client Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.694 [27149.27149] TRACE WebsocketClient: [0xab922120]: Configuring connection Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.695 [27149.27149] TRACE PlaybackSessionManager: [0xab91b2b8]: Not refreshing playback session because we're already doing so Jun 18 04:29:09 zen-stream play[27118]: 20250618 04:29:09.696 [27149.27149] INFO SampleApp: Renderer is now active Jun 18 04:29:10 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:29:10 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:29:10 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.510 [27149.27149] INFO PlaybackSessionManager: [0xab91b2b8]: Playback session has been refreshed Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.510 [27149.27149] TRACE PlaybackSessionManager: [0xab91b2b8]: Session state changed: Inactive -> Active Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.510 [27149.27149] DEBUG PlaybackSessionManager: [0xab91b2b8]: Playback session will be refreshed in 3000 seconds Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.510 [27149.27149] DEBUG PlaybackSessionManager: [0xab91b2b8]: Playback session will expire in 3600 seconds Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.796 [27149.27149] TRACE WebsocketClient: [0xab922120]: Connected, server protocols: default Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.796 [27149.27149] INFO CloudClient: [0xab91bb28]: Connection established Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.796 [27149.27149] DEBUG QwspMessageSender: [0xab954210]: Creating QWSP Message Sender Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.796 [27149.27149] DEBUG QConnectMessageSender: [0xab927670]: Creating QConnect Message Sender Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.796 [27149.27149] INFO QwspMessageSender: [0xab954210]: Sending Authenticate message Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.796 [27149.27149] TRACE QwspMessageSender: [0xab954210]: Token: eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJpYXQiOjE3NTAyMjA5NDksImV4cCI6MTc1MDIyNDU0OSwiaXNzIjoiUW9idXogQVBJIiwicXVpZCI6MjY3OTE1NywicWFpZCI6IjY3OTMxODk3MiJ9.Hq98ja59N_CnwApzaNrWbuvvXL-f6tb38QxttJI-gY0 Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.796 [27149.27149] INFO QwspMessageSender: [0xab954210]: Sending Subscribe message Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.796 [27149.27149] TRACE QwspMessageSender: [0xab954210]: Protocol: QConnect Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.796 [27149.27149] TRACE QwspMessageSender: [0xab954210]: Channel: Backend Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.796 [27149.27149] INFO QConnectMessageSender: [0xab927670]: Sending JoinSession message Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.796 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Reason: Controller Request Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.796 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Active: true Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.796 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Session UUID: 8dae6a3b-d300-4493-aeb0-18c7242bdee2 Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.797 [27149.27149] INFO QConnectMessageSender: [0xab927670]: Sending VolumeChanged message Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.797 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Volume: 100 Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.797 [27149.27149] INFO QConnectMessageSender: [0xab927670]: Sending VolumeMuted message Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.797 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Muted: true Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.797 [27149.27149] INFO QConnectMessageSender: [0xab927670]: Sending MaxAudioQualityChanged message Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.797 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Max quality: HIRES_L3 Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.797 [27149.27149] INFO QwspMessageSender: [0xab954210]: Sending Payload message Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.797 [27149.27149] TRACE QwspMessageSender: [0xab954210]: Protocol: QConnect Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.797 [27149.27149] TRACE QwspMessageSender: [0xab954210]: Channel: Backend Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.797 [27149.27149] DEBUG RendererActivationController: [0xab91bea8]: Connection to QConnect server established Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.797 [27149.27149] TRACE RendererActivationController: [0xab91bea8]: State changed: Inactive (Disconnected) -> Active Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.798 [27149.27149] DEBUG RendererActivationCommandSequencer: [0xab91bf50]: Confirming command: Connect Renderer Jun 18 04:29:10 zen-stream play[27118]: 20250618 04:29:10.798 [27149.27149] DEBUG UserInactivityManager: [0xab91c2e8]: Renderer is active but not playing, will deactivate in 3600s Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.068 [27149.27149] TRACE WebsocketClient: [0xab922120]: Binary data received, size: 127 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Received SetActive message: active Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Received SetState message: Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Playing state: Paused Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Playback position: 84000 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Queue version: 1.1 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Current track: TID: 312623304, QID: 11, Context UUID: 040f5acb-b489-4265-9c1f-bfbf615d2bfa Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Next track: TID: 312623293, QID: 0, Context UUID: 040f5acb-b489-4265-9c1f-bfbf615d2bfa Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] INFO MediaEngine: [0xab91b550]: Stopping playback, clearing tracks Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] DEBUG MediaEngineCommandSequencer: [0xab91b8c8]: Enqueuing command: Stop Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] DEBUG MediaEngineCommandSequencer: [0xab91b8c8]: Enqueuing command: SetCurrentTrack Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] DEBUG MediaEngineCommandSequencer: [0xab91b8c8]: Enqueuing command: SetNextTrack Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] INFO MediaEngine: [0xab91b550]: Initiating playback Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] DEBUG MediaEngineCommandSequencer: [0xab91b8c8]: Enqueuing command: InitiatePlayback Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] DEBUG MediaEngine: [0xab91b550]: Executing command: Stop Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] DEBUG MediaEngineCommandSequencer: [0xab91b8c8]: Confirming command: Stop Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] INFO RendererActionAvailabilityManager: [0xab91bbe0]: Renderer action 'Next' is available Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Received SetLoopMode message: All Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] INFO PlaybackControlsManager: [0xab91b928]: Setting new loop mode: All Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] DEBUG MediaEngine: [0xab91b550]: Executing command: SetCurrentTrack Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] INFO MediaEngine: [0xab91b550]: Setting current track: 312623304, initial offset: 84000ms Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.069 [27149.27149] INFO AudioStreamManager: [0xab91b638]: Clearing all streams Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.070 [27149.27149] INFO AudioStreamManager: [0xab91b638]: New stream: 1 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.070 [27149.27149] DEBUG PlaybackUrlFetcher: [0xab921da0]: Creating Playback URL Fetcher for track: 312623304 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.070 [27149.27149] INFO HttpDownloader: [0xab9276d8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=76d00168126859d686c19a159613f4e9&request_ts=1750220951&track_id=312623304 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.070 [27149.27149] DEBUG MetadataFetcher: [0xaba97fb0]: Creating Metadata Fetcher for track: 312623304 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.070 [27149.27149] INFO HttpDownloader: [0xab9240e8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=312623304 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.070 [27149.27149] DEBUG AudioStreamInfoFetcher: [0xab924ef8]: Creating Audio Stream Info Fetcher for track: 312623304 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.070 [27149.27149] INFO HttpDownloader: [0xab924fb8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=038a929656cd854e4f14fe61fd6ad5a3&request_ts=1750220951&track_id=312623304 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.070 [27149.27149] INFO AudioStreamManager: [0xab91b638]: [Stream 1]: Running audio stream Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.070 [27149.27149] DEBUG MediaEngineCommandSequencer: [0xab91b8c8]: Confirming command: SetCurrentTrack Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.070 [27149.27149] INFO SampleApp: Playback actions availability changed, next: available Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.070 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Received SetShuffleMode message: disabled Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.070 [27149.27149] INFO PlaybackControlsManager: [0xab91b928]: Setting new shuffle mode: disabled Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.070 [27149.27149] INFO SampleApp: Playback controls changed, loop mode: All, shuffle mode: disabled Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.073 [27149.27149] DEBUG MediaEngine: [0xab91b550]: Current stream changed state: Nonexistent -> Created Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.073 [27149.27149] DEBUG MediaEngine: [0xab91b550]: Executing command: SetNextTrack Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.073 [27149.27149] INFO MediaEngine: [0xab91b550]: Setting next track: 312623293 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.073 [27149.27149] INFO AudioStreamManager: [0xab91b638]: New stream: 2 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.073 [27149.27149] DEBUG PlaybackUrlFetcher: [0xab9fb240]: Creating Playback URL Fetcher for track: 312623293 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.073 [27149.27149] INFO HttpDownloader: [0xab9fb0f8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=57cfdb0e44462cad5be1569588c865ea&request_ts=1750220951&track_id=312623293 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.073 [27149.27149] DEBUG MetadataFetcher: [0xab9fac30]: Creating Metadata Fetcher for track: 312623293 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.073 [27149.27149] INFO HttpDownloader: [0xab9facb8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=312623293 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.073 [27149.27149] DEBUG AudioStreamInfoFetcher: [0xab9fcea0]: Creating Audio Stream Info Fetcher for track: 312623293 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.073 [27149.27149] INFO HttpDownloader: [0xab9fcf78]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=2c7a73de0a99185f53bb3afade0d2413&request_ts=1750220951&track_id=312623293 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.073 [27149.27149] DEBUG MediaEngineCommandSequencer: [0xab91b8c8]: Confirming command: SetNextTrack Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.075 [27149.27149] DEBUG MediaEngine: [0xab91b550]: Next stream changed state: Nonexistent -> Created Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.076 [27149.27149] DEBUG MediaEngine: [0xab91b550]: Executing command: InitiatePlayback Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.076 [27149.27149] INFO MediaEngine: [0xab91b550]: Waiting for current stream to start before starting audio renderer Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.439 [27149.27149] INFO AudioStreamManager: [0xab91b638]: [Stream 1]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2679157&eid=312623304&fmt=7&profile=raw&app_id=679318972&cid=2334679&etsp=1750224551&hmac=qa-VbkIeZdHwfp7pWSRIA9SyIyk Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.448 [27149.27149] INFO AudioStreamManager: [0xab91b638]: [Stream 2]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2679157&eid=312623293&fmt=7&profile=raw&app_id=679318972&cid=2334679&etsp=1750224551&hmac=s7kFNjb1JkrR_lFIUAgeYqR-KVo Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.500 [27149.27149] INFO AudioStreamManager: [0xab91b638]: [Stream 2]: stream information have been fetched Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.500 [27149.27149] DEBUG MediaEngine: [0xab91b550]: Next stream changed state: Created -> Info Retrieved Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.513 [27149.27149] TRACE MetadataFetcher: [0xab9fac30]: Track 312623293 doesn't have a valid version field Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.513 [27149.27149] TRACE MetadataFetcher: [0xab9fac30]: Track 312623293 doesn't have a valid work field Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.513 [27149.27149] TRACE MetadataFetcher: [0xab9fac30]: Track 312623293 doesn't have a valid album version field Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.513 [27149.27149] INFO AudioStreamManager: [0xab91b638]: [Stream 2]: Metadata became available: Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.513 [27149.27149] INFO AudioStreamManager: [0xab91b638]: Title: Thought For You Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.513 [27149.27149] INFO AudioStreamManager: [0xab91b638]: Artist: Sea Lemon Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.513 [27149.27149] INFO AudioStreamManager: [0xab91b638]: Album: Diving For A Prize Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.513 [27149.27149] INFO AudioStreamManager: [0xab91b638]: Album art URL: https://static.qobuz.com/images/covers/gb/5b/q1mca41oo5bgb_600.jpg Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.518 [27149.27149] INFO AudioStreamManager: [0xab91b638]: [Stream 1]: stream information have been fetched Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.518 [27149.27149] INFO UrlAudioSource: [0xab952ae0]: Starting URL audio source, initial position: 84000ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2679157&eid=312623304&fmt=7&profile=raw&app_id=679318972&cid=2334679&etsp=1750224551&hmac=qa-VbkIeZdHwfp7pWSRIA9SyIyk Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.518 [27149.27149] TRACE AudioSource: [0xab952ae0]: State changed: Idle -> Running Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.518 [27149.27149] DEBUG Ringbuffer: [0xab959738]: Creating Audio Ringbuffer Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.518 [27149.27149] INFO ContentFetcher: [0xabace178]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2679157&eid=312623304&fmt=7&profile=raw&app_id=679318972&cid=2334679&etsp=1750224551&hmac=qa-VbkIeZdHwfp7pWSRIA9SyIyk, offset: 0 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.518 [27149.27149] DEBUG MediaEngine: [0xab91b550]: Current stream changed state: Created -> Info Retrieved Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.518 [27149.27149] DEBUG MediaEngine: [0xab91b550]: Current stream changed state: Info Retrieved -> Running Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.518 [27149.27149] INFO AudioRenderer: [0xab91b770]: Starting audio renderer, initial playback state: Paused Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.518 [27149.27149] TRACE AudioRenderer: [0xab91b770]: State changed: Stopped -> Initiating (Paused) Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.518 [27149.27149] INFO SampleApp: [Stream 1]: New audio stream (starting from 84000ms) Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] DEBUG MediaEngine: [0xab91b550]: Audio renderer state changed: Stopped -> Initiating (Paused) Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] INFO SampleApp: Initiating playback, initial state: Paused Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] DEBUG AudioRenderer: [0xab91b770]: Playback initiated (paused state) Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] TRACE AudioRenderer: [0xab91b770]: State changed: Initiating (Paused) -> Paused Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] DEBUG MediaEngine: [0xab91b550]: Audio renderer state changed: Initiating (Paused) -> Paused Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] TRACE MediaEngine: [0xab91b550]: State changed: Stopped -> Paused Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] DEBUG MediaEngineCommandSequencer: [0xab91b8c8]: Confirming command: InitiatePlayback Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] INFO SampleApp: Playback state changed: Paused Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] ERROR SampleApp: Unable to get buffered frame count from ALSA audio sink: device is not open Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] TRACE AudioRenderer: [0xab91b770]: Playback position: 84000 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Need to send state update Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] INFO QConnectMessageSender: [0xab927670]: Sending StateUpdated message Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Playback state: Paused Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Current position: 84000 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Duration: 201253 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Queue version: 1.1 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Current queue item ID: 11 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Next queue item ID: 0 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.519 [27149.27149] INFO QwspMessageSender: [0xab954210]: Sending Payload message Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.520 [27149.27149] TRACE QwspMessageSender: [0xab954210]: Protocol: QConnect Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.520 [27149.27149] TRACE QwspMessageSender: [0xab954210]: Channel: Backend Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.520 [27149.27149] INFO QConnectMessageSender: [0xab927670]: Sending FileAudioQualityChanged message Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.520 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Sample rate: 96000 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.520 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Bit depth: 24 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.520 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Channel count: 2 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.520 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Audio quality: HIRES_L1 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.520 [27149.27149] INFO QwspMessageSender: [0xab954210]: Sending Payload message Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.520 [27149.27149] TRACE QwspMessageSender: [0xab954210]: Protocol: QConnect Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.520 [27149.27149] TRACE QwspMessageSender: [0xab954210]: Channel: Backend Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.521 [27149.27149] INFO QConnectMessageSender: [0xab927670]: Sending DeviceAudioQualityChanged message Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.521 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Sample rate: 96000 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.521 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Bit depth: 24 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.521 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Channel count: 2 Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.521 [27149.27149] INFO QwspMessageSender: [0xab954210]: Sending Payload message Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.521 [27149.27149] TRACE QwspMessageSender: [0xab954210]: Protocol: QConnect Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.521 [27149.27149] TRACE QwspMessageSender: [0xab954210]: Channel: Backend Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.521 [27149.27149] TRACE PlaybackPositionEstimator: [0xab91c220]: Synced playback position: 84000ms Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.588 [27149.27149] TRACE MetadataFetcher: [0xaba97fb0]: Track 312623304 doesn't have a valid version field Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.589 [27149.27149] TRACE MetadataFetcher: [0xaba97fb0]: Track 312623304 doesn't have a valid work field Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.589 [27149.27149] TRACE MetadataFetcher: [0xaba97fb0]: Track 312623304 doesn't have a valid album version field Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.589 [27149.27149] INFO AudioStreamManager: [0xab91b638]: [Stream 1]: Metadata became available: Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.589 [27149.27149] INFO AudioStreamManager: [0xab91b638]: Title: In The Flowers Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.589 [27149.27149] INFO AudioStreamManager: [0xab91b638]: Artist: Sea Lemon Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.589 [27149.27149] INFO AudioStreamManager: [0xab91b638]: Album: Diving For A Prize Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.589 [27149.27149] INFO AudioStreamManager: [0xab91b638]: Album art URL: https://static.qobuz.com/images/covers/gb/5b/q1mca41oo5bgb_600.jpg Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.589 [27149.27149] INFO SampleApp: [Stream 1]: Stream metadata became available: Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.589 [27149.27149] INFO SampleApp: Title: In The Flowers Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.589 [27149.27149] INFO SampleApp: Artist: Sea Lemon Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.589 [27149.27149] INFO SampleApp: Album: Diving For A Prize Jun 18 04:29:11 zen-stream play[27118]: 20250618 04:29:11.589 [27149.27149] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/gb/5b/q1mca41oo5bgb_600.jpg Jun 18 04:29:12 zen-stream play[27118]: 20250618 04:29:12.187 [27149.27149] INFO UrlAudioSource: [0xab952ae0]: Content info extracted Jun 18 04:29:12 zen-stream play[27118]: 20250618 04:29:12.187 [27149.27149] INFO UrlAudioSource: [0xab952ae0]: Size: 72439485 Jun 18 04:29:12 zen-stream play[27118]: 20250618 04:29:12.194 [27149.27697] INFO AudioDecoder: [0xabac0780]: Stream info: Jun 18 04:29:12 zen-stream play[27118]: 20250618 04:29:12.194 [27149.27697] INFO AudioDecoder: [0xabac0780]: Total samples: 19320320 Jun 18 04:29:12 zen-stream play[27118]: 20250618 04:29:12.194 [27149.27697] INFO AudioDecoder: [0xabac0780]: Sample rate: 96000 Jun 18 04:29:12 zen-stream play[27118]: 20250618 04:29:12.194 [27149.27697] INFO AudioDecoder: [0xabac0780]: Channel count: 2 Jun 18 04:29:12 zen-stream play[27118]: 20250618 04:29:12.194 [27149.27697] INFO AudioDecoder: [0xabac0780]: Bits per sample: 24 Jun 18 04:29:12 zen-stream play[27118]: 20250618 04:29:12.194 [27149.27697] INFO AudioDecoder: [0xabac0780]: Duration: 201253 Jun 18 04:29:12 zen-stream play[27118]: 20250618 04:29:12.195 [27149.27149] INFO UrlAudioSource: [0xab952ae0]: Audio properties have changed Jun 18 04:29:12 zen-stream play[27118]: 20250618 04:29:12.195 [27149.27149] INFO UrlAudioSource: [0xab952ae0]: Seeking input stream to byte offset: 30470909 Jun 18 04:29:12 zen-stream play[27118]: 20250618 04:29:12.195 [27149.27149] INFO ContentFetcher: [0xabace178]: Adjusting download offset to: 30470909 Jun 18 04:29:12 zen-stream play[27118]: 20250618 04:29:12.715 [27149.27149] INFO UrlAudioSource: [0xab952ae0]: Seeking input stream to byte offset: 30495389 Jun 18 04:29:12 zen-stream play[27118]: 20250618 04:29:12.716 [27149.27149] INFO ContentFetcher: [0xabace178]: Adjusting download offset to: 30495389 Jun 18 04:29:13 zen-stream play[27118]: 20250618 04:29:13.924 [27149.27149] TRACE WebsocketClient: [0xab922120]: Binary data received, size: 40 Jun 18 04:29:13 zen-stream play[27118]: 20250618 04:29:13.924 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Received SetState message: Jun 18 04:29:13 zen-stream play[27118]: 20250618 04:29:13.924 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Playing state: Playing Jun 18 04:29:13 zen-stream play[27118]: 20250618 04:29:13.924 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Playback position: Jun 18 04:29:13 zen-stream play[27118]: 20250618 04:29:13.924 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Queue version: Jun 18 04:29:13 zen-stream play[27118]: 20250618 04:29:13.924 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Current track: Jun 18 04:29:13 zen-stream play[27118]: 20250618 04:29:13.924 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Next track: Jun 18 04:29:13 zen-stream play[27118]: 20250618 04:29:13.925 [27149.27149] INFO MediaEngine: [0xab91b550]: Resuming playback Jun 18 04:29:13 zen-stream play[27118]: 20250618 04:29:13.925 [27149.27149] DEBUG MediaEngineCommandSequencer: [0xab91b8c8]: Enqueuing command: Resume Jun 18 04:29:13 zen-stream play[27118]: 20250618 04:29:13.925 [27149.27149] DEBUG MediaEngine: [0xab91b550]: Executing command: Resume Jun 18 04:29:13 zen-stream play[27118]: 20250618 04:29:13.925 [27149.27149] INFO AudioRenderer: [0xab91b770]: Resuming audio renderer Jun 18 04:29:13 zen-stream play[27118]: 20250618 04:29:13.925 [27149.27149] TRACE AudioRenderer: [0xab91b770]: State changed: Paused -> Resuming Jun 18 04:29:13 zen-stream play[27118]: 20250618 04:29:13.925 [27149.27149] DEBUG MediaEngine: [0xab91b550]: Audio renderer state changed: Paused -> Resuming Jun 18 04:29:13 zen-stream volumio[859]: info: ConvSwitch: Get message: request:pause Jun 18 04:29:13 zen-stream volumio[859]: info: CoreCommandRouter::volumioStop Jun 18 04:29:13 zen-stream volumio[859]: info: CoreStateMachine::stop Jun 18 04:29:13 zen-stream volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 04:29:13 zen-stream volumio[859]: info: CoreCommandRouter::volumioStop Jun 18 04:29:13 zen-stream volumio[859]: info: CoreStateMachine::stop Jun 18 04:29:13 zen-stream volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 04:29:13 zen-stream volumio[859]: info: Jun 18 04:29:13 zen-stream volumio[859]: ---------------------------- Client requests Start Qobuz PlaybackRoutine Jun 18 04:29:13 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: qobuz_connect , startQobuzPlayback Jun 18 04:29:13 zen-stream volumio[859]: info: Qobuz Connect playback start Jun 18 04:29:13 zen-stream volumio[859]: info: CoreCommandRouter::volumioStop Jun 18 04:29:13 zen-stream volumio[859]: info: CoreStateMachine::stop Jun 18 04:29:13 zen-stream volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 04:29:13 zen-stream volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.992 [27149.27149] TRACE SampleApp: ALSA PCM's descriptor count: 1 Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.992 [27149.27149] INFO SampleApp: Resuming playback Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.993 [27149.27149] DEBUG AudioRenderer: [0xab91b770]: Transition to playing state (resume) has been acknowledged Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.993 [27149.27149] TRACE AudioRenderer: [0xab91b770]: State changed: Resuming -> Playing Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.993 [27149.27149] DEBUG MediaEngine: [0xab91b550]: Audio renderer state changed: Resuming -> Playing Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.993 [27149.27149] TRACE MediaEngine: [0xab91b550]: State changed: Paused -> Playing Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.993 [27149.27149] DEBUG MediaEngineCommandSequencer: [0xab91b8c8]: Confirming command: Resume Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.995 [27149.27149] DEBUG PlaybackProgressManager: [0xab91b7c8]: Audio playback started, time until playback gets near the end: 107249ms Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.995 [27149.27149] INFO SampleApp: Playback state changed: Playing Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.996 [27149.27149] TRACE AudioRenderer: [0xab91b770]: Playback position: 84006 Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.996 [27149.27149] INFO StreamingEventReporter: [0xab91c0a8]: Reporting streaming started event Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.996 [27149.27149] TRACE StreamingEventReporter: [0xab91c0a8]: Track ID: 312623304 Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.996 [27149.27149] TRACE StreamingEventReporter: [0xab91c0a8]: Start position: 84006ms Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.996 [27149.27149] TRACE StreamingEventReporter: [0xab91c0a8]: Context UUID: 040f5acb-b489-4265-9c1f-bfbf615d2bfa Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.996 [27149.27149] TRACE StreamingEventReporter: [0xab91c0a8]: Blob ID: 100000.p3uOPJXHIKA2_HnBohiWBp5GOnhvYjQQLDWJU2jyEXSzQ5jSLFOHBYMSiF7FBgJG.gt3a2WQ8fX28c70nD-L0eNb-kHQ Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.996 [27149.27149] DEBUG StreamingEventReporter: [0xab91c0a8]: Sending streaming reports Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.996 [27149.27149] INFO HttpDownloader: [0xab91c100]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.996 [27149.27149] TRACE AudioRenderer: [0xab91b770]: Playback position: 84007 Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.996 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Need to send state update Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.996 [27149.27149] INFO QConnectMessageSender: [0xab927670]: Sending StateUpdated message Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.996 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Playback state: Playing Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.996 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Current position: 84007 Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.996 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Duration: 201253 Jun 18 04:29:14 zen-stream play[27118]: 20250618 04:29:14.996 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Queue version: 1.1 Jun 18 04:29:15 zen-stream play[27118]: 20250618 04:29:14.996 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Current queue item ID: 11 Jun 18 04:29:15 zen-stream play[27118]: 20250618 04:29:14.996 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Next queue item ID: 0 Jun 18 04:29:15 zen-stream play[27118]: 20250618 04:29:14.996 [27149.27149] DEBUG UserInactivityManager: [0xab91c2e8]: Renderer is actively playing Jun 18 04:29:15 zen-stream play[27118]: 20250618 04:29:15.000 [27149.27149] INFO QwspMessageSender: [0xab954210]: Sending Payload message Jun 18 04:29:15 zen-stream play[27118]: 20250618 04:29:15.000 [27149.27149] TRACE QwspMessageSender: [0xab954210]: Protocol: QConnect Jun 18 04:29:15 zen-stream play[27118]: 20250618 04:29:15.000 [27149.27149] TRACE QwspMessageSender: [0xab954210]: Channel: Backend Jun 18 04:29:15 zen-stream play[27118]: 20250618 04:29:15.003 [27149.27149] TRACE PlaybackPositionEstimator: [0xab91c220]: Synced playback position: 84004ms Jun 18 04:29:15 zen-stream play[27118]: 20250618 04:29:15.005 [27149.27149] TRACE PlaybackPositionEstimator: [0xab91c220]: Synced playback position: 84006ms Jun 18 04:29:15 zen-stream play[27118]: 20250618 04:29:15.007 [27149.27149] TRACE PlaybackPositionEstimator: [0xab91c220]: Synced playback position: 84007ms Jun 18 04:29:15 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:29:15 zen-stream play[27118]: 20250618 04:29:15.340 [27149.27149] DEBUG StreamingEventReporter: [0xab91c0a8]: Streaming report submitted successfully Jun 18 04:29:15 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:29:15 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:29:20 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:29:20 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:29:20 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:29:24 zen-stream play[27118]: 20250618 04:29:24.995 [27149.27149] TRACE AudioRenderer: [0xab91b770]: Playback position: 94008 Jun 18 04:29:24 zen-stream play[27118]: 20250618 04:29:24.995 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Need to send state update Jun 18 04:29:24 zen-stream play[27118]: 20250618 04:29:24.996 [27149.27149] INFO QConnectMessageSender: [0xab927670]: Sending StateUpdated message Jun 18 04:29:24 zen-stream play[27118]: 20250618 04:29:24.996 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Playback state: Playing Jun 18 04:29:24 zen-stream play[27118]: 20250618 04:29:24.996 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Current position: 94008 Jun 18 04:29:24 zen-stream play[27118]: 20250618 04:29:24.996 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Duration: 201253 Jun 18 04:29:24 zen-stream play[27118]: 20250618 04:29:24.996 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Queue version: 1.1 Jun 18 04:29:24 zen-stream play[27118]: 20250618 04:29:24.996 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Current queue item ID: 11 Jun 18 04:29:24 zen-stream play[27118]: 20250618 04:29:24.996 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Next queue item ID: 0 Jun 18 04:29:24 zen-stream play[27118]: 20250618 04:29:24.996 [27149.27149] INFO QwspMessageSender: [0xab954210]: Sending Payload message Jun 18 04:29:24 zen-stream play[27118]: 20250618 04:29:24.996 [27149.27149] TRACE QwspMessageSender: [0xab954210]: Protocol: QConnect Jun 18 04:29:24 zen-stream play[27118]: 20250618 04:29:24.996 [27149.27149] TRACE QwspMessageSender: [0xab954210]: Channel: Backend Jun 18 04:29:24 zen-stream play[27118]: 20250618 04:29:24.997 [27149.27149] TRACE PlaybackPositionEstimator: [0xab91c220]: Synced playback position: 94008ms Jun 18 04:29:25 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:29:25 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:29:25 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:29:30 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:29:30 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:29:30 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:29:34 zen-stream play[27118]: 20250618 04:29:34.996 [27149.27149] TRACE AudioRenderer: [0xab91b770]: Playback position: 104009 Jun 18 04:29:34 zen-stream play[27118]: 20250618 04:29:34.996 [27149.27149] INFO ProtocolHandler: [0xab91bc90]: Need to send state update Jun 18 04:29:34 zen-stream play[27118]: 20250618 04:29:34.996 [27149.27149] INFO QConnectMessageSender: [0xab927670]: Sending StateUpdated message Jun 18 04:29:34 zen-stream play[27118]: 20250618 04:29:34.996 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Playback state: Playing Jun 18 04:29:34 zen-stream play[27118]: 20250618 04:29:34.996 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Current position: 104009 Jun 18 04:29:34 zen-stream play[27118]: 20250618 04:29:34.996 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Duration: 201253 Jun 18 04:29:34 zen-stream play[27118]: 20250618 04:29:34.996 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Queue version: 1.1 Jun 18 04:29:34 zen-stream play[27118]: 20250618 04:29:34.996 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Current queue item ID: 11 Jun 18 04:29:34 zen-stream play[27118]: 20250618 04:29:34.996 [27149.27149] TRACE QConnectMessageSender: [0xab927670]: Next queue item ID: 0 Jun 18 04:29:34 zen-stream play[27118]: 20250618 04:29:34.997 [27149.27149] INFO QwspMessageSender: [0xab954210]: Sending Payload message Jun 18 04:29:34 zen-stream play[27118]: 20250618 04:29:34.997 [27149.27149] TRACE QwspMessageSender: [0xab954210]: Protocol: QConnect Jun 18 04:29:34 zen-stream play[27118]: 20250618 04:29:34.997 [27149.27149] TRACE QwspMessageSender: [0xab954210]: Channel: Backend Jun 18 04:29:34 zen-stream play[27118]: 20250618 04:29:34.997 [27149.27149] TRACE PlaybackPositionEstimator: [0xab91c220]: Synced playback position: 104009ms Jun 18 04:29:35 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:29:35 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:29:35 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:29:40 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:29:41 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:29:41 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:29:43 zen-stream volumio[859]: info: ++++--++++ callMethod: {"type":"controller","endpoint":"audio_interface/alsa_controller","method":"saveVolumeOptions","data":{"mixer_type":{"value":"Software","label":"Software"},"mixer":{"value":"None","label":"None"},"volumestart":{"value":"disabled","label":"Disabled"},"volumemax":{"value":"100","label":"100"},"volumesteps":{"value":"1","label":"1"},"volumecurvemode":{"value":"logarithmic","label":"Natural"},"mpdvolume":false}} Jun 18 04:29:43 zen-stream volumio[859]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::volumioGetState Jun 18 04:29:43 zen-stream volumio[859]: info: Restoring Previous Volume level: disabled false true Jun 18 04:29:43 zen-stream volumio[859]: info: VolumeController::SetAlsaVolumeNaN Jun 18 04:29:43 zen-stream volumio[859]: info: Enable softmixer device for audio device number 5 Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::volumioStop Jun 18 04:29:43 zen-stream volumio[859]: info: CoreStateMachine::stop Jun 18 04:29:43 zen-stream volumio[859]: info: CoreStateMachine::serviceStop Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::serviceStop Jun 18 04:29:43 zen-stream volumio[859]: error: Could not retrieve plugin music_service qobuz_connect Jun 18 04:29:43 zen-stream volumio[859]: error: WARNING: No stop method for service qobuz_connect Jun 18 04:29:43 zen-stream volumio[859]: info: Enable softmixer device for audio device undefined Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 18 04:29:43 zen-stream volumio[859]: info: Updating Volume Controller Parameters: Device: 5 Name: GO bar KENSEI Mixer: None Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 18 04:29:43 zen-stream volumio[859]: info: Tidal connect: Output device has changed Jun 18 04:29:43 zen-stream volumio[859]: info: Output device has changed, restarting MPD Jun 18 04:29:43 zen-stream volumio[859]: info: Qobuz connect: Output device has changed Jun 18 04:29:43 zen-stream volumio[859]: info: Roon Ready: Output device has changed Jun 18 04:29:43 zen-stream volumio[859]: info: Spotify: Output device has changed Jun 18 04:29:43 zen-stream volumio[859]: info: Volume configurations have been set Jun 18 04:29:43 zen-stream sudo[28357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 04:29:43 zen-stream sudo[28357]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:43 zen-stream sudo[28357]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 18 04:29:43 zen-stream volumio[859]: info: Updating Volume Controller Parameters: Device: 5 Name: GO bar KENSEI Mixer: None Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Jun 18 04:29:43 zen-stream volumio[859]: info: CoreStateMachine::pushState Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::volumioPushState Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Jun 18 04:29:43 zen-stream volumio[859]: info: CoreStateMachine::pushState Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::volumioPushState Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Jun 18 04:29:43 zen-stream volumio[859]: info: CoreStateMachine::pushState Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::volumioPushState Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 04:29:43 zen-stream volumio[859]: info: MPD Permissions set Jun 18 04:29:43 zen-stream volumio[859]: info: Software Volume ALSA configuration written Jun 18 04:29:43 zen-stream volumio[859]: info: Preparing to generate the ALSA configuration file Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:43 zen-stream volumio[859]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 18 04:29:43 zen-stream volumio[859]: info: Reading ALSA contributions from plugins. Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 04:29:43 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 18 04:29:44 zen-stream volumio[859]: info: Asound.conf file written Jun 18 04:29:44 zen-stream sudo[28405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 18 04:29:44 zen-stream sudo[28405]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:44 zen-stream sudo[28405]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:44 zen-stream volumio[859]: No state is present for card rkifispdif Jun 18 04:29:44 zen-stream volumio[859]: Found hardware: "rk-ifi-spdif" "" "" "" "" Jun 18 04:29:44 zen-stream volumio[859]: Hardware is initialized using a generic method Jun 18 04:29:44 zen-stream volumio[859]: No state is present for card rkifispdif Jun 18 04:29:44 zen-stream volumio[859]: No state is present for card KENSEI Jun 18 04:29:44 zen-stream volumio[859]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:3008" "" "" Jun 18 04:29:44 zen-stream volumio[859]: Hardware is initialized using a generic method Jun 18 04:29:44 zen-stream volumio[859]: No state is present for card KENSEI Jun 18 04:29:44 zen-stream volumio[859]: info: Tidal connect: Output device has changed Jun 18 04:29:44 zen-stream volumio[859]: info: Output device has changed, restarting MPD Jun 18 04:29:44 zen-stream volumio[859]: info: Qobuz connect: Output device has changed Jun 18 04:29:44 zen-stream volumio[859]: info: Roon Ready: Output device has changed Jun 18 04:29:44 zen-stream volumio[859]: info: Spotify: Output device has changed Jun 18 04:29:44 zen-stream volumio[859]: info: aplay1++++++++++++++++++++++++++++++++++++*************** Jun 18 04:29:44 zen-stream sudo[28410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 04:29:44 zen-stream sudo[28410]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:44 zen-stream sudo[28410]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:44 zen-stream volumio[859]: aplay: main:828: audio open error: Device or resource busy Jun 18 04:29:44 zen-stream volumio[859]: No state is present for card rkifispdif Jun 18 04:29:44 zen-stream volumio[859]: Found hardware: "rk-ifi-spdif" "" "" "" "" Jun 18 04:29:44 zen-stream volumio[859]: Hardware is initialized using a generic method Jun 18 04:29:44 zen-stream volumio[859]: No state is present for card rkifispdif Jun 18 04:29:44 zen-stream volumio[859]: No state is present for card KENSEI Jun 18 04:29:44 zen-stream volumio[859]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:3008" "" "" Jun 18 04:29:44 zen-stream volumio[859]: Hardware is initialized using a generic method Jun 18 04:29:44 zen-stream volumio[859]: No state is present for card KENSEI Jun 18 04:29:44 zen-stream volumio[859]: info: Tidal connect: Output device has changed Jun 18 04:29:44 zen-stream volumio[859]: info: Output device has changed, restarting MPD Jun 18 04:29:44 zen-stream volumio[859]: info: Qobuz connect: Output device has changed Jun 18 04:29:44 zen-stream volumio[859]: info: Roon Ready: Output device has changed Jun 18 04:29:44 zen-stream volumio[859]: info: Spotify: Output device has changed Jun 18 04:29:44 zen-stream sudo[28417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 18 04:29:44 zen-stream sudo[28417]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:44 zen-stream sudo[28417]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 18 04:29:44 zen-stream volumio[859]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 18 04:29:44 zen-stream volumio[859]: info: Starting RoonReady Sync Jun 18 04:29:44 zen-stream sudo[28429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Jun 18 04:29:44 zen-stream sudo[28429]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Jun 18 04:29:44 zen-stream volumio[859]: info: CoreStateMachine::pushState Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::volumioPushState Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 04:29:44 zen-stream sudo[28432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Jun 18 04:29:44 zen-stream sudo[28432]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:44 zen-stream systemd[1]: Stopping iFi Streamer Qobuz Connect Service... Jun 18 04:29:44 zen-stream systemd[1]: ifi-streamer-qobuz-connect.service: Main process exited, code=killed, status=15/TERM Jun 18 04:29:44 zen-stream volumio[859]: info: MPD Permissions set Jun 18 04:29:44 zen-stream volumio[859]: info: MPD Permissions set Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:44 zen-stream node[489]: ConvSwitch: Drop client roon-ready Jun 18 04:29:44 zen-stream node[489]: ConvSwitch: Drop client qobuz-connect Jun 18 04:29:44 zen-stream systemd[1]: Stopping iFi Streamer Roon Ready Service... Jun 18 04:29:44 zen-stream systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=killed, status=15/TERM Jun 18 04:29:44 zen-stream systemd[1]: ifi-streamer-roon-ready.service: Succeeded. Jun 18 04:29:44 zen-stream systemd[1]: Stopped iFi Streamer Roon Ready Service. Jun 18 04:29:44 zen-stream systemd[1]: ifi-streamer-qobuz-connect.service: Succeeded. Jun 18 04:29:44 zen-stream systemd[1]: Stopped iFi Streamer Qobuz Connect Service. Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 04:29:44 zen-stream sudo[28429]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:44 zen-stream systemd[1]: Started iFi Streamer Roon Ready Service. Jun 18 04:29:44 zen-stream sudo[28432]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 18 04:29:44 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:44 zen-stream volumio[859]: info: Starting RoonReady Sync Jun 18 04:29:44 zen-stream sudo[28461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Jun 18 04:29:44 zen-stream sudo[28461]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:44 zen-stream volumio[859]: info: RoonReady Started Jun 18 04:29:44 zen-stream sudo[28465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Jun 18 04:29:44 zen-stream sudo[28465]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:44 zen-stream sudo[28461]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:44 zen-stream volumio[859]: info: Starting RoonReady Sync Jun 18 04:29:44 zen-stream systemd[1]: Stopping iFi Streamer Roon Ready Service... Jun 18 04:29:44 zen-stream systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=killed, status=15/TERM Jun 18 04:29:44 zen-stream systemd[1]: ifi-streamer-roon-ready.service: Succeeded. Jun 18 04:29:44 zen-stream systemd[1]: Stopped iFi Streamer Roon Ready Service. Jun 18 04:29:44 zen-stream sudo[28470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Jun 18 04:29:44 zen-stream sudo[28470]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:44 zen-stream systemd[1]: Started iFi Streamer Roon Ready Service. Jun 18 04:29:44 zen-stream sudo[28465]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:44 zen-stream volumio[859]: info: RoonReady Started Jun 18 04:29:44 zen-stream sudo[28475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Jun 18 04:29:44 zen-stream sudo[28475]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:44 zen-stream sudo[28470]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:44 zen-stream systemd[1]: Stopping iFi Streamer Roon Ready Service... Jun 18 04:29:44 zen-stream systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=killed, status=15/TERM Jun 18 04:29:44 zen-stream systemd[1]: ifi-streamer-roon-ready.service: Succeeded. Jun 18 04:29:44 zen-stream systemd[1]: Stopped iFi Streamer Roon Ready Service. Jun 18 04:29:45 zen-stream systemd[1]: Started iFi Streamer Roon Ready Service. Jun 18 04:29:45 zen-stream sudo[28475]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:45 zen-stream volumio[859]: info: RoonReady Started Jun 18 04:29:45 zen-stream sudo[28484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Jun 18 04:29:45 zen-stream sudo[28484]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:45 zen-stream systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Jun 18 04:29:45 zen-stream systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Jun 18 04:29:45 zen-stream systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Jun 18 04:29:45 zen-stream systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Jun 18 04:29:45 zen-stream systemd[1]: Stopping iFi Streamer Tidal Connect Service... Jun 18 04:29:45 zen-stream systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Jun 18 04:29:45 zen-stream node[489]: ConvSwitch: Drop client tidal Jun 18 04:29:45 zen-stream systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Jun 18 04:29:45 zen-stream systemd[1]: Stopped iFi Streamer Tidal Connect Service. Jun 18 04:29:45 zen-stream systemd[1]: Started iFi Streamer Tidal Connect Service. Jun 18 04:29:45 zen-stream sudo[28484]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:45 zen-stream systemd[1]: Started iFi Streamer Tidal Metadata Service. Jun 18 04:29:45 zen-stream volumio[859]: info: Tidal Connect Service Started Jun 18 04:29:45 zen-stream ifi_metadata[28488]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:29:45 zen-stream play[28481]: /usr/ifi/ifi-roonready-release/play: line 18: warning: command substitution: ignored null byte in input Jun 18 04:29:45 zen-stream sudo[28522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Jun 18 04:29:45 zen-stream sudo[28522]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:45 zen-stream systemd[1]: mpd.socket: Succeeded. Jun 18 04:29:45 zen-stream systemd[1]: Closed mpd.socket. Jun 18 04:29:45 zen-stream systemd[1]: Stopping Music Player Daemon... Jun 18 04:29:45 zen-stream systemd[1]: mpd.service: Succeeded. Jun 18 04:29:45 zen-stream systemd[1]: Stopped Music Player Daemon. Jun 18 04:29:45 zen-stream sudo[28541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Jun 18 04:29:45 zen-stream sudo[28541]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:45 zen-stream node[489]: ConvSwitch: Add client roon-ready Jun 18 04:29:45 zen-stream sudo[28522]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:45 zen-stream systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Jun 18 04:29:45 zen-stream systemd[1]: Starting Internet Detect for iFi Streamer... Jun 18 04:29:45 zen-stream systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Jun 18 04:29:45 zen-stream systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Jun 18 04:29:45 zen-stream systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Jun 18 04:29:45 zen-stream sudo[28549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Jun 18 04:29:45 zen-stream sudo[28549]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:45 zen-stream volumio[859]: info: ConvSwitch: Get message: request:pause Jun 18 04:29:45 zen-stream volumio[859]: info: CoreCommandRouter::volumioStop Jun 18 04:29:45 zen-stream volumio[859]: info: CoreStateMachine::stop Jun 18 04:29:45 zen-stream volumio[859]: info: CoreStateMachine::serviceStop Jun 18 04:29:45 zen-stream volumio[859]: info: CoreCommandRouter::serviceStop Jun 18 04:29:45 zen-stream systemd[1]: Stopping iFi Streamer Tidal Connect Service... Jun 18 04:29:45 zen-stream volumio[859]: error: Could not retrieve plugin music_service qobuz_connect Jun 18 04:29:45 zen-stream volumio[859]: error: WARNING: No stop method for service qobuz_connect Jun 18 04:29:45 zen-stream systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Jun 18 04:29:45 zen-stream systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Jun 18 04:29:45 zen-stream systemd[1]: Stopped iFi Streamer Tidal Connect Service. Jun 18 04:29:45 zen-stream systemd[1]: Started iFi Streamer Tidal Connect Service. Jun 18 04:29:45 zen-stream sudo[28541]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:45 zen-stream sudo[28554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Jun 18 04:29:45 zen-stream sudo[28554]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:45 zen-stream systemd[1]: Started iFi Streamer Tidal Metadata Service. Jun 18 04:29:45 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 18 04:29:45 zen-stream ifi_metadata[28563]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:29:46 zen-stream sudo[28568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Jun 18 04:29:46 zen-stream sudo[28568]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:46 zen-stream volumio[859]: info: Tidal Connect Service Started Jun 18 04:29:46 zen-stream sudo[28590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Jun 18 04:29:46 zen-stream sudo[28590]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:46 zen-stream sudo[28596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Jun 18 04:29:46 zen-stream sudo[28596]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:46 zen-stream volumio[859]: info: Jun 18 04:29:46 zen-stream volumio[859]: ---------------------------- Client requests Start RoonReady PlaybackRoutine Jun 18 04:29:46 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: roon_ready , startRoonReadyPlayback Jun 18 04:29:46 zen-stream volumio[859]: info: Roon Ready playback start Jun 18 04:29:46 zen-stream volumio[859]: info: CoreCommandRouter::volumioStop Jun 18 04:29:46 zen-stream volumio[859]: info: CoreStateMachine::stop Jun 18 04:29:46 zen-stream volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 04:29:46 zen-stream volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 04:29:46 zen-stream volumio[859]: info: Roon Ready metadata receiver is on Jun 18 04:29:46 zen-stream sudo[28600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Jun 18 04:29:46 zen-stream sudo[28600]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:46 zen-stream sudo[28568]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:46 zen-stream volumio[859]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.socket mpd.service Jun 18 04:29:46 zen-stream volumio[859]: Job for mpd.socket canceled. Jun 18 04:29:46 zen-stream volumio[859]: Job for mpd.service canceled. Jun 18 04:29:46 zen-stream volumio[859]: error: MPD error: Error: connect ECONNREFUSED /run/mpd/socket Jun 18 04:29:46 zen-stream volumio[859]: error: connect ECONNREFUSED /run/mpd/socket {"errno":-111,"code":"ECONNREFUSED","syscall":"connect","address":"/run/mpd/socket"} Jun 18 04:29:46 zen-stream systemd[1]: Started iFi Streamer Qobuz Connect Service. Jun 18 04:29:46 zen-stream sudo[28549]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:46 zen-stream sudo[28596]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:46 zen-stream sudo[28600]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:46 zen-stream systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Jun 18 04:29:46 zen-stream systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Jun 18 04:29:46 zen-stream systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Jun 18 04:29:46 zen-stream systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Jun 18 04:29:46 zen-stream systemd[1]: ifi-system-internet.service: Succeeded. Jun 18 04:29:46 zen-stream volumio[859]: info: Qobuz Connect Service Started Jun 18 04:29:46 zen-stream systemd[1]: Started Internet Detect for iFi Streamer. Jun 18 04:29:46 zen-stream volumio[859]: info: Qobuz Connect Service Started Jun 18 04:29:46 zen-stream volumio[859]: info: Qobuz Connect Service Started Jun 18 04:29:46 zen-stream sudo[28619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Jun 18 04:29:46 zen-stream sudo[28619]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:46 zen-stream sudo[28590]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:46 zen-stream sudo[28628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Jun 18 04:29:46 zen-stream sudo[28628]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:46 zen-stream systemd[1]: Stopping iFi Streamer Tidal Connect Service... Jun 18 04:29:46 zen-stream systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Jun 18 04:29:46 zen-stream systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Jun 18 04:29:46 zen-stream systemd[1]: Stopped iFi Streamer Tidal Connect Service. Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.434 [28638.28638] DEBUG SampleApp: Starting sample application Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.435 [28638.28638] DEBUG SampleApp: Device Name: ZEN Stream Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.435 [28638.28638] DEBUG SampleApp: Manufacturer: Sample manufacturer Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.435 [28638.28638] DEBUG SampleApp: Model: iFi Streamer Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.435 [28638.28638] DEBUG SampleApp: Serial Number: ifi:e6:5a:25:9a:a2:ae Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.435 [28638.28638] DEBUG SampleApp: Application ID: 679318972 Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.435 [28638.28638] DEBUG SampleApp: Application Secret: a32fe515ff9d11ffafcb2b2a1426c244 Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.435 [28638.28638] DEBUG SampleApp: Audio output device: volumio Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.435 [28638.28638] DEBUG SampleApp: Volume control device: hw:5 Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.435 [28638.28638] DEBUG SampleApp: Volume control element: SoftMaster Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.435 [28638.28638] DEBUG SampleApp: Local config server port: 8000 Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.441 [28638.28638] ERROR SampleApp: Unable to find ALSA mixer element 'SoftMaster' on index 0 Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.442 [28638.28638] DEBUG SampleApp: Maximum supported audio quality: HIRES_L3 Jun 18 04:29:46 zen-stream play[28605]: [Info] MetaClient: MetaClient SDK -- v1.02 Jun 18 04:29:46 zen-stream play[28605]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:29:46 zen-stream sudo[28639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Jun 18 04:29:46 zen-stream sudo[28639]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:46 zen-stream sudo[28619]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:46 zen-stream play[28605]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Jun 18 04:29:46 zen-stream node[489]: ConvSwitch: Add client qobuz-connect Jun 18 04:29:46 zen-stream systemd[1]: Started iFi Streamer Tidal Connect Service. Jun 18 04:29:46 zen-stream sudo[28554]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:46 zen-stream volumio[859]: info: Tidal Connect Service Started Jun 18 04:29:46 zen-stream systemd[1]: Started iFi Streamer Tidal Metadata Service. Jun 18 04:29:46 zen-stream sudo[28645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Jun 18 04:29:46 zen-stream sudo[28645]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:46 zen-stream play[28605]: [Info] ConvSwitch: ConvSwitchInit Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.549 [28638.28638] DEBUG ActiveStateManager: [0xaae1bf58]: Creating new Active State Manager Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.550 [28638.28638] DEBUG NetworkActivityTracker: [0xaae1bfc8]: Creating new Network Activity Tracker Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.550 [28638.28638] DEBUG EndpointManager: [0xaae1cf40]: Creating Endpoint Manager Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.550 [28638.28638] DEBUG PlaybackSessionManager: [0xaae1d330]: Creating Playback Session Manager Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.550 [28638.28638] DEBUG BackoffTimer: [0xaae1d4a8]: Creating Backoff Timer Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.550 [28638.28638] DEBUG MediaEngine: [0xaae1d5c8]: Creating new Media Engine Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] DEBUG AudioRenderer: [0xaae1d7e8]: Creating Audio Renderer Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] TRACE MediaEngineCommandSequencer: [0xaae1d940]: Creating Media Engine Command Sequencer Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] DEBUG PlaybackControlsManager: [0xaae1d9a0]: Creating new Playback Controls Manager Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] DEBUG VolumeManager: [0xaae1da30]: Creating new Volume Manager Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] DEBUG ProtocolHandler: [0xaae1dd08]: Creating Protocol Handler Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] DEBUG MessageReceiver: [0xaae1dda8]: Creating Message Receiver Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] DEBUG RendererActivationController: [0xaae1df20]: Creating new Renderer Activation Controller Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] TRACE RendererActivationCommandSequencer: [0xaae1dfc8]: Creating Renderer Activation Command Sequencer Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] DEBUG ReportingManager: [0xaae1e0d8]: Creating new Reporting Manager Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] DEBUG StreamingEventReporter: [0xaae1e120]: Creating Streaming Event Reporter Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] DEBUG BackoffTimer: [0xaae1e200]: Creating Backoff Timer Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] DEBUG TokenExpiryController: [0xaae1e310]: Creating Token Expiry Controller Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] DEBUG UserInactivityManager: [0xaae1e360]: Creating new User Inactivity Manager Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] INFO VolumeManager: [0xaae1da30]: Setting new playback volume: 100 Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] INFO VolumeManager: [0xaae1da30]: Setting new mute state: 1 Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] DEBUG SampleApp: Qobuz Connect has been initialized Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] TRACE SampleApp: Running event loop Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] INFO QobuzConnect: [0xaae1e400]: Client initialized! Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.551 [28638.28638] INFO SampleApp: Starting Avahi advertising, name: ZEN Stream, service name: _qobuz-connect._tcp Jun 18 04:29:46 zen-stream ifi_metadata[28646]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:29:46 zen-stream sudo[28628]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.576 [28638.28638] DEBUG SampleApp: Avahi client state changed: RUNNING Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.582 [28638.28638] DEBUG SampleApp: Avahi entry group state changed: UNCOMMITED Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.590 [28638.28638] INFO LocalConfigManager: [0xaae1d310]: Starting Local Configuration server Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.590 [28638.28638] INFO SampleApp: Starting Local configuration server Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.591 [28638.28638] INFO SampleApp: Playback mute state changed: 1 Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.592 [28638.28638] ERROR SampleApp: Unable to set mute state, no ALSA volume element Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.592 [28638.28638] DEBUG SampleApp: Avahi entry group state changed: REGISTERING Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.592 [28638.28638] INFO SampleApp: Playback volume changed: 100 Jun 18 04:29:46 zen-stream play[28605]: 20250618 04:29:46.592 [28638.28638] ERROR SampleApp: Unable to set volume, no ALSA volume element Jun 18 04:29:46 zen-stream systemd[1]: Listening on mpd.socket. Jun 18 04:29:46 zen-stream systemd[1]: Starting Music Player Daemon... Jun 18 04:29:46 zen-stream systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 18 04:29:46 zen-stream systemd[1]: mpd.service: Succeeded. Jun 18 04:29:46 zen-stream systemd[1]: Stopped Music Player Daemon. Jun 18 04:29:46 zen-stream sudo[28657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Jun 18 04:29:46 zen-stream sudo[28657]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:46 zen-stream systemd[1]: Starting Music Player Daemon... Jun 18 04:29:46 zen-stream systemd[1]: mpd.socket: Succeeded. Jun 18 04:29:46 zen-stream systemd[1]: Closed mpd.socket. Jun 18 04:29:46 zen-stream systemd[1]: Stopping mpd.socket. Jun 18 04:29:46 zen-stream systemd[1]: mpd.socket: Socket service mpd.service already active, refusing. Jun 18 04:29:46 zen-stream systemd[1]: Failed to listen on mpd.socket. Jun 18 04:29:46 zen-stream systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 18 04:29:46 zen-stream systemd[1]: mpd.service: Succeeded. Jun 18 04:29:46 zen-stream systemd[1]: Stopped Music Player Daemon. Jun 18 04:29:46 zen-stream systemd[1]: Listening on mpd.socket. Jun 18 04:29:46 zen-stream systemd[1]: Starting Music Player Daemon... Jun 18 04:29:46 zen-stream sudo[28663]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 18 04:29:46 zen-stream sudo[28663]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:46 zen-stream sudo[28663]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:47 zen-stream volumio[859]: info: CoreCommandRouter::servicePushState Jun 18 04:29:47 zen-stream volumio[859]: info: CoreStateMachine::pushState Jun 18 04:29:47 zen-stream volumio[859]: info: CoreCommandRouter::volumioPushState Jun 18 04:29:47 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 04:29:47 zen-stream play[28605]: 20250618 04:29:47.360 [28638.28638] DEBUG SampleApp: Avahi entry group state changed: ESTABLISHED Jun 18 04:29:47 zen-stream node[489]: ConvSwitch: Drop client roon-ready Jun 18 04:29:47 zen-stream systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=exited, status=1/FAILURE Jun 18 04:29:47 zen-stream systemd[1]: ifi-streamer-roon-ready.service: Failed with result 'exit-code'. Jun 18 04:29:47 zen-stream play[28605]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:29:47 zen-stream sudo[28673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Jun 18 04:29:47 zen-stream sudo[28673]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:47 zen-stream play[27237]: 2025-06-18 04:29:47.559 E esdk select error: 4 -> Interrupted system call Jun 18 04:29:47 zen-stream play[27237]: 2025-06-18 04:29:47.559 I app Exiting with error = 0 Jun 18 04:29:47 zen-stream play[27237]: 2025-06-18 04:29:47.559 I api SpFree() Jun 18 04:29:47 zen-stream play[27237]: 2025-06-18 04:29:47.560 I api SpFree [returned value: 0] Jun 18 04:29:47 zen-stream play[27237]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Jun 18 04:29:47 zen-stream play[27237]: [Info] ConvSwitch: ConvSwitchInit Jun 18 04:29:47 zen-stream play[27237]: [Info] MetaClient: MetaClient SDK -- v1.02 Jun 18 04:29:47 zen-stream play[27237]: [Info] ConvSwitch: ConvSwitchExit Jun 18 04:29:47 zen-stream play[27237]: [Info] MetaClient: MetaClientExit Jun 18 04:29:47 zen-stream node[489]: ConvSwitch: Drop client spotify Jun 18 04:29:47 zen-stream systemd[1]: Stopping iFi Streamer Spotify Connect Service... Jun 18 04:29:47 zen-stream systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Jun 18 04:29:47 zen-stream systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Jun 18 04:29:47 zen-stream systemd[1]: Stopped iFi Streamer Spotify Connect Service. Jun 18 04:29:47 zen-stream ifi_metadata[28646]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:29:47 zen-stream systemd[1]: Started iFi Streamer Spotify Connect Service. Jun 18 04:29:47 zen-stream sudo[28673]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:47 zen-stream volumio[859]: info: Spotify Connect Service Started Jun 18 04:29:47 zen-stream play[28675]: output: volumio mixer: hw:5 Jun 18 04:29:47 zen-stream node[489]: ConvSwitch: Add client spotify Jun 18 04:29:47 zen-stream play[28675]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:29:47 zen-stream sudo[28699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Jun 18 04:29:47 zen-stream sudo[28699]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.831 I api SpRegisterDebugCallbacks [returned value: 0] Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.832 I api SpInit(0xffdf8bc0, API v66) Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.832 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.832 I esdk Successfully registered socket callbacks: cre, set_opt, cl, bind, list, con, acc, rd, wr, rd_from, wr_to, err, readable, writable, local_addresses, address, pump Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.832 I esdk Successfully registered dns callback: dns_lookup_callback Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.833 I download Using 128 kB for cache buffer Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.833 I download Using 621 kB for compressed audio buffer Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.833 I esdk Successfully registered error callback Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.845 I esdk Creating IPv4 socket (domain 2) Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.845 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.845 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.845 I esdk Creating IPv4 socket (domain 2) Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.845 I api SpInit [returned value: 0] Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.845 I api SpRegisterConnectionCallbacks(0xffdf8ba8, (nil)) Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.845 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.845 I api SpRegisterConnectionCallbacks [returned value: 0] Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.845 I api SpRegisterPlaybackCallbacks(0xffdf8b90, (nil)) Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.845 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.846 I api SpRegisterPlaybackCallbacks [returned value: 0] Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.852 W app Couldn't find mixer for volume controls. (tried SoftMaster, SoftMaster) Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.852 I app Initialized! Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.864 I app Exiting with error = 0 Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.865 I api SpFree() Jun 18 04:29:47 zen-stream play[28675]: 2025-06-18 04:29:47.865 I api SpFree [returned value: 0] Jun 18 04:29:47 zen-stream play[28675]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Jun 18 04:29:47 zen-stream play[28675]: [Info] ConvSwitch: ConvSwitchInit Jun 18 04:29:47 zen-stream play[28675]: [Info] MetaClient: MetaClient SDK -- v1.02 Jun 18 04:29:47 zen-stream play[28675]: [Info] ConvSwitch: ConvSwitchExit Jun 18 04:29:47 zen-stream play[28675]: [Info] MetaClient: MetaClientExit Jun 18 04:29:47 zen-stream node[489]: ConvSwitch: Drop client spotify Jun 18 04:29:47 zen-stream systemd[1]: Stopping iFi Streamer Spotify Connect Service... Jun 18 04:29:47 zen-stream systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Jun 18 04:29:47 zen-stream systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Jun 18 04:29:47 zen-stream systemd[1]: Stopped iFi Streamer Spotify Connect Service. Jun 18 04:29:47 zen-stream systemd[1]: Started iFi Streamer Spotify Connect Service. Jun 18 04:29:47 zen-stream sudo[28699]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:47 zen-stream volumio[859]: info: Spotify Connect Service Started Jun 18 04:29:47 zen-stream sudo[28709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Jun 18 04:29:47 zen-stream sudo[28709]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 04:29:47 zen-stream systemd[1]: Stopping iFi Streamer Spotify Connect Service... Jun 18 04:29:48 zen-stream systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Jun 18 04:29:48 zen-stream systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Jun 18 04:29:48 zen-stream systemd[1]: Stopped iFi Streamer Spotify Connect Service. Jun 18 04:29:48 zen-stream systemd[1]: Started iFi Streamer Spotify Connect Service. Jun 18 04:29:48 zen-stream volumio[859]: info: VolumeController::SetAlsaVolumeNaN Jun 18 04:29:48 zen-stream sudo[28709]: pam_unix(sudo:session): session closed for user root Jun 18 04:29:48 zen-stream volumio[859]: info: Spotify Connect Service Started Jun 18 04:29:48 zen-stream volumio[859]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jun 18 04:29:48 zen-stream volumio[859]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Jun 18 04:29:48 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveCurrentVolume Jun 18 04:29:48 zen-stream volumio[859]: info: CoreStateMachine::pushState Jun 18 04:29:48 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 04:29:48 zen-stream volumio[859]: info: CoreCommandRouter::volumioPushState Jun 18 04:29:48 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 04:29:48 zen-stream play[28713]: output: volumio mixer: hw:5 Jun 18 04:29:48 zen-stream node[489]: ConvSwitch: Add client spotify Jun 18 04:29:48 zen-stream play[28713]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.292 I api SpRegisterDebugCallbacks [returned value: 0] Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.292 I api SpInit(0xffc40fd0, API v66) Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.292 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.293 I esdk Successfully registered socket callbacks: cre, set_opt, cl, bind, list, con, acc, rd, wr, rd_from, wr_to, err, readable, writable, local_addresses, address, pump Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.293 I esdk Successfully registered dns callback: dns_lookup_callback Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.293 I download Using 128 kB for cache buffer Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.293 I download Using 621 kB for compressed audio buffer Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.293 I esdk Successfully registered error callback Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.305 I esdk Creating IPv4 socket (domain 2) Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.305 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.305 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.305 I esdk Creating IPv4 socket (domain 2) Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.305 I api SpInit [returned value: 0] Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.305 I api SpRegisterConnectionCallbacks(0xffc40fb8, (nil)) Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.306 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.306 I api SpRegisterConnectionCallbacks [returned value: 0] Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.306 I api SpRegisterPlaybackCallbacks(0xffc40fa0, (nil)) Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.306 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.306 I api SpRegisterPlaybackCallbacks [returned value: 0] Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.312 W app Couldn't find mixer for volume controls. (tried SoftMaster, SoftMaster) Jun 18 04:29:48 zen-stream play[28713]: 2025-06-18 04:29:48.312 I app Initialized! Jun 18 04:29:48 zen-stream play[28605]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:29:48 zen-stream systemd[1]: ifi-streamer-roon-ready.service: Service RestartSec=1s expired, scheduling restart. Jun 18 04:29:48 zen-stream play[28605]: 20250618 04:29:48.463 [28638.28653] INFO SampleApp: API endpoint invoked: get-display-info Jun 18 04:29:48 zen-stream systemd[1]: ifi-streamer-roon-ready.service: Scheduled restart job, restart counter is at 1. Jun 18 04:29:48 zen-stream systemd[1]: Stopped iFi Streamer Roon Ready Service. Jun 18 04:29:48 zen-stream systemd[1]: Started iFi Streamer Roon Ready Service. Jun 18 04:29:48 zen-stream ifi_metadata[28646]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:29:48 zen-stream volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 04:29:48 zen-stream volumio[859]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 04:29:48 zen-stream volumio[859]: TypeError: Cannot read property 'vol' of undefined Jun 18 04:29:48 zen-stream volumio[859]: at CoreVolumeController.setStartupVolume (/volumio/app/volumecontrol.js:649:25) Jun 18 04:29:48 zen-stream volumio[859]: at Timeout._onTimeout (/volumio/app/volumecontrol.js:302:12) Jun 18 04:29:48 zen-stream volumio[859]: at listOnTimeout (internal/timers.js:557:17) Jun 18 04:29:48 zen-stream volumio[859]: at processTimers (internal/timers.js:500:7) Jun 18 04:29:48 zen-stream play[28739]: /usr/ifi/ifi-roonready-release/play: line 18: warning: command substitution: ignored null byte in input Jun 18 04:29:48 zen-stream volumio[859]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 04:29:48 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:48 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:48 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:29:48 zen-stream kernel: input: iFi (by AMR) GO bar KENSEI as /devices/platform/usb@ff600000/ff600000.dwc3/xhci-hcd.0.auto/usb4/4-1/4-1.3/4-1.3:1.4/0003:20B1:3008.0005/input/input3 Jun 18 04:29:48 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:48 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:48 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:29:48 zen-stream kernel: hid-generic 0003:20B1:3008.0005: input,hidraw0: USB HID v1.11 Device [iFi (by AMR) GO bar KENSEI] on usb-xhci-hcd.0.auto-1.3/input4 Jun 18 04:29:48 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:48 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:48 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:29:48 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:48 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:48 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:29:48 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:48 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:48 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:29:48 zen-stream systemd-udevd[28754]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 18 04:29:48 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:48 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:29:48 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:48 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:29:49 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:49 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:49 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:29:49 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:49 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:49 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:29:49 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:49 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:49 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:29:49 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:49 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:49 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:29:49 zen-stream node[489]: ConvSwitch: Add client roon-ready Jun 18 04:29:49 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:49 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:49 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:29:49 zen-stream play[28713]: [Error] Socket: Unable to connect /run/ifi/awtk.sock. No such file or directory Jun 18 04:29:49 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:49 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 18 04:29:49 zen-stream kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 18 04:29:49 zen-stream kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 18 04:29:49 zen-stream node[489]: ConvSwitch: Add client tidal Jun 18 04:29:49 zen-stream tidal_connect_application[28822]: *** WARNING *** The program 'tidal_connect_application' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 04:29:49 zen-stream tidal_connect_application[28822]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 04:29:49 zen-stream tidal_connect_application[28822]: *** WARNING *** For more information see Jun 18 04:29:49 zen-stream ifi_metadata[28646]: [Info] MetaClient: MetaClient SDK -- v1.02 Jun 18 04:29:49 zen-stream ifi_metadata[28646]: Could not connect: Reason::CONNECTION_ERROR Jun 18 04:29:49 zen-stream ifi_metadata[28646]: Connected Jun 18 04:29:49 zen-stream sudo[28841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-18 04:28 Jun 18 04:29:49 zen-stream sudo[28841]: 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="1aa86d140f52e6978b55808c200d7f336afa9f78" VOLUMIO_FE_VERSION="0548e367fe44b194ccf1306c8af35deb040eccd2" VOLUMIO_FE3_VERSION="c63cc070c36379a8d79dc0d7d4a24908bebc2472" VOLUMIO_BE_VERSION="64dfbad964d654fe0e7d7d53305e712ecad248a1" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="ifi" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="2025年 06月 17日 星期二 16:39:50 CST" VOLUMIO_VERSION="0.00.1.01" VOLUMIO_HARDWARE="zen-stream3" VOLUMIO_DEVICENAME="ZEN Stream3" VOLUMIO_HASH="727c66af336dfbcf9f387633fda0970b"