-- Logs begin at Mon 2025-04-28 09:07:49 UTC, end at Mon 2025-04-28 09:50:54 UTC. -- Apr 28 09:49:04 ifi-phmidsd-idsd-phantom ethernet-detect[493]: No change in avahi-daemon interfaces. Skipping restart. Apr 28 09:49:08 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 01 20 5c ed Apr 28 09:49:08 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:08 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 00 e9 27 b9 Apr 28 09:49:08 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:08 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 00 d7 f0 24 Apr 28 09:49:08 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:14 ifi-phmidsd-idsd-phantom ethernet-detect[493]: No change in avahi-daemon interfaces. Skipping restart. Apr 28 09:49:18 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:18.970 [11253.11265] INFO SampleApp: API endpoint invoked: get-display-info Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.156 [11253.11253] TRACE WebsocketClient: [0xab24bc10]: Binary data received, size: 40 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.156 [11253.11253] TRACE WebsocketClient: [0xab24bc10]: Binary data received, size: 119 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.156 [11253.11253] INFO ProtocolHandler: [0xab245720]: Received SetActive message: active Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.156 [11253.11253] DEBUG RendererActivationCommandSequencer: [0xab2459e0]: Enqueuing command: Activate Renderer Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.156 [11253.11253] DEBUG RendererActivationController: [0xab245938]: Executing command: Activate Renderer Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.156 [11253.11253] DEBUG RendererActivationController: [0xab245938]: Making the renderer active Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.156 [11253.11253] INFO ActiveStateManager: [0xab243970]: Setting new active state: active Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.156 [11253.11253] DEBUG RendererActivationController: [0xab245938]: Starting playback session maintenance Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.156 [11253.11253] INFO PlaybackSessionManager: [0xab244d48]: Starting playback session maintenance Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.156 [11253.11253] TRACE PlaybackSessionManager: [0xab244d48]: Not refreshing playback session because it's not needed Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.156 [11253.11253] TRACE RendererActivationController: [0xab245938]: State changed: Inactive (Connected) -> Active Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.156 [11253.11253] DEBUG RendererActivationCommandSequencer: [0xab2459e0]: Confirming command: Activate Renderer Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.156 [11253.11253] INFO QConnectMessageSender: [0xab278fe8]: Sending VolumeChanged message Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.156 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Volume: 100 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.156 [11253.11253] INFO QConnectMessageSender: [0xab278fe8]: Sending VolumeMuted message Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.156 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Muted: true Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] INFO QConnectMessageSender: [0xab278fe8]: Sending MaxAudioQualityChanged message Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Max quality: HIRES_L3 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] INFO SampleApp: Renderer is now active Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] INFO QwspMessageSender: [0xab278fd8]: Sending Payload message Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] TRACE QwspMessageSender: [0xab278fd8]: Protocol: QConnect Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] TRACE QwspMessageSender: [0xab278fd8]: Channel: Backend Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] INFO ProtocolHandler: [0xab245720]: Received SetState message: Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] INFO ProtocolHandler: [0xab245720]: Playing state: Playing Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] INFO ProtocolHandler: [0xab245720]: Playback position: 12026 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] INFO ProtocolHandler: [0xab245720]: Queue version: 4.2 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] INFO ProtocolHandler: [0xab245720]: Current track: TID: 255738654, QID: 3, Context UUID: 61f6df5c-0ab2-458e-939b-830685ad4748 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] INFO ProtocolHandler: [0xab245720]: Next track: TID: 255733178, QID: 4, Context UUID: 61f6df5c-0ab2-458e-939b-830685ad4748 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] INFO MediaEngine: [0xab244fe0]: Stopping playback, clearing tracks Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Enqueuing command: Stop Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Enqueuing command: SetCurrentTrack Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Enqueuing command: SetNextTrack Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] INFO MediaEngine: [0xab244fe0]: Initiating playback Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Enqueuing command: InitiatePlayback Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Executing command: Stop Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.157 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Confirming command: Stop Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] INFO ProtocolHandler: [0xab245720]: Received SetLoopMode message: Off Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] INFO PlaybackControlsManager: [0xab2453b8]: Setting new loop mode: Off Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Executing command: SetCurrentTrack Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] INFO MediaEngine: [0xab244fe0]: Setting current track: 255738654, initial offset: 12026ms Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: Clearing all streams Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: New stream: 25 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] DEBUG PlaybackUrlFetcher: [0xab2786b8]: Creating Playback URL Fetcher for track: 255738654 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] INFO HttpDownloader: [0xab3297a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=4ddc67c9c1bdebdfa436c20cbc935418&request_ts=1745833760&track_id=255738654 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] DEBUG MetadataFetcher: [0xab27e7c8]: Creating Metadata Fetcher for track: 255738654 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] INFO HttpDownloader: [0xab467748]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=255738654 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] DEBUG AudioStreamInfoFetcher: [0xab48ba78]: Creating Audio Stream Info Fetcher for track: 255738654 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] INFO HttpDownloader: [0xab324750]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=6a65a45274dcfb0d3dfc41bcee20d80d&request_ts=1745833760&track_id=255738654 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: [Stream 25]: Running audio stream Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Confirming command: SetCurrentTrack Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] INFO ProtocolHandler: [0xab245720]: Received SetShuffleMode message: disabled Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] INFO PlaybackControlsManager: [0xab2453b8]: Setting new shuffle mode: disabled Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Current stream changed state: Nonexistent -> Created Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Executing command: SetNextTrack Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] INFO MediaEngine: [0xab244fe0]: Setting next track: 255733178 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: New stream: 26 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] DEBUG PlaybackUrlFetcher: [0xab24bbf0]: Creating Playback URL Fetcher for track: 255733178 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.158 [11253.11253] INFO HttpDownloader: [0xab359bd0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=562a7bb54641c19746b436dd560c610c&request_ts=1745833760&track_id=255733178 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.159 [11253.11253] DEBUG MetadataFetcher: [0xab3a8040]: Creating Metadata Fetcher for track: 255733178 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.159 [11253.11253] INFO HttpDownloader: [0xab3c13e0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=255733178 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.159 [11253.11253] DEBUG AudioStreamInfoFetcher: [0xab32a238]: Creating Audio Stream Info Fetcher for track: 255733178 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.159 [11253.11253] INFO HttpDownloader: [0xab4677b8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=3f4e8fec6e0ccd682b6e2f9d7e3681cf&request_ts=1745833760&track_id=255733178 Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.159 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Confirming command: SetNextTrack Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.179 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Next stream changed state: Nonexistent -> Created Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.179 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Executing command: InitiatePlayback Apr 28 09:49:20 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:20.179 [11253.11253] INFO MediaEngine: [0xab244fe0]: Waiting for current stream to start before starting audio renderer Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.287 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: [Stream 26]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2679157&eid=255733178&fmt=7&profile=raw&app_id=679318972&cid=2334679&etsp=1745837361&hmac=twANmAqmGZl_6pMRlvWYF2-WTjE Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.338 [11253.11253] TRACE MetadataFetcher: [0xab3a8040]: Track 255733178 doesn't have a valid version field Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.338 [11253.11253] TRACE MetadataFetcher: [0xab3a8040]: Track 255733178 doesn't have a valid work field Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.338 [11253.11253] TRACE MetadataFetcher: [0xab3a8040]: Track 255733178 doesn't have a valid album version field Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.338 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: [Stream 25]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=2679157&eid=255738654&fmt=7&profile=raw&app_id=679318972&cid=2334679&etsp=1745837361&hmac=qTudGwIdAnqJ5JmcbX08k1tVnUI Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.338 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: [Stream 26]: Metadata became available: Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.338 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: Title: 好不容易 Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.338 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: Artist: 告五人 Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.338 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: Album: 好不容易 Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.338 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: Album art URL: https://static.qobuz.com/images/covers/kb/s6/akv1xnv9es6kb_600.jpg Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.598 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: [Stream 25]: stream information have been fetched Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.598 [11253.11253] INFO UrlAudioSource: [0xab679a38]: Starting URL audio source, initial position: 12026ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=2679157&eid=255738654&fmt=7&profile=raw&app_id=679318972&cid=2334679&etsp=1745837361&hmac=qTudGwIdAnqJ5JmcbX08k1tVnUI Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.598 [11253.11253] TRACE AudioSource: [0xab679a38]: State changed: Idle -> Running Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.598 [11253.11253] DEBUG Ringbuffer: [0xab5d9450]: Creating Audio Ringbuffer Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.599 [11253.11253] INFO ContentFetcher: [0xab250c40]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=2679157&eid=255738654&fmt=7&profile=raw&app_id=679318972&cid=2334679&etsp=1745837361&hmac=qTudGwIdAnqJ5JmcbX08k1tVnUI, offset: 0 Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.599 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Current stream changed state: Created -> Info Retrieved Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.599 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Current stream changed state: Info Retrieved -> Running Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.600 [11253.11253] INFO AudioRenderer: [0xab245200]: Starting audio renderer, initial playback state: Playing Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.600 [11253.11253] TRACE AudioRenderer: [0xab245200]: State changed: Stopped -> Initiating (Playing) Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.600 [11253.11253] INFO SampleApp: [Stream 25]: New audio stream (starting from 12026ms) Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.600 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Audio renderer state changed: Stopped -> Initiating (Playing) Apr 28 09:49:21 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:21.600 [11253.11253] INFO SampleApp: Initiating playback, initial state: Playing Apr 28 09:49:22 ifi-phmidsd-idsd-phantom demo[764]: [receive from xmos]: ff 05 00 04 2d 00 ac 7a Apr 28 09:49:22 ifi-phmidsd-idsd-phantom demo[764]: SET_FILTER_IO_STATE Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.685 [11253.11253] TRACE SampleApp: ALSA PCM's descriptor count: 1 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.685 [11253.11253] DEBUG AudioRenderer: [0xab245200]: Playback initiated (playing state) Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.685 [11253.11253] TRACE AudioRenderer: [0xab245200]: State changed: Initiating (Playing) -> Playing Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.687 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Audio renderer state changed: Initiating (Playing) -> Playing Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.687 [11253.11253] TRACE MediaEngine: [0xab244fe0]: State changed: Stopped -> Playing Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.687 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Confirming command: InitiatePlayback Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.688 [11253.11253] DEBUG PlaybackProgressManager: [0xab245258]: Audio playback started, time until playback gets near the end: 327747ms Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.688 [11253.11253] INFO SampleApp: Playback state changed: Playing Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.688 [11253.11253] TRACE AudioRenderer: [0xab245200]: Playback position: 12026 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.688 [11253.11253] INFO StreamingEventReporter: [0xab245b38]: Reporting streaming started event Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.688 [11253.11253] TRACE StreamingEventReporter: [0xab245b38]: Track ID: 255738654 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.688 [11253.11253] TRACE StreamingEventReporter: [0xab245b38]: Start position: 12026ms Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.688 [11253.11253] TRACE StreamingEventReporter: [0xab245b38]: Context UUID: 61f6df5c-0ab2-458e-939b-830685ad4748 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.688 [11253.11253] TRACE StreamingEventReporter: [0xab245b38]: Blob ID: 100000.SGk4pQCuTItoqVoyM_PCdYHnrUuQ6T7LygsMYY7YyB4xFS3V8gbgpulrb5MWkwH_.XoXInboxJ4AMt2M6GzuhQLBDCyg Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.688 [11253.11253] DEBUG StreamingEventReporter: [0xab245b38]: Sending streaming reports Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.688 [11253.11253] INFO HttpDownloader: [0xab245b90]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.688 [11253.11253] TRACE AudioRenderer: [0xab245200]: Playback position: 12026 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom node[488]: metadata.title changed Apr 28 09:49:22 ifi-phmidsd-idsd-phantom node[488]: in qobuz-connect Apr 28 09:49:22 ifi-phmidsd-idsd-phantom node[488]: metadata.album: https://static.qobuz.com/images/covers/9a/c5/e1vrae24bc59a_600.jpg Apr 28 09:49:22 ifi-phmidsd-idsd-phantom node[488]: message: {"title":"醜人多作怪","artist":"告五人","album":"運氣來得若有似無","albumart":"https://static.qobuz.com/images/covers/9a/c5/e1vrae24bc59a_600.jpg","duration":"257","seek":"0"} Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.688 [11253.11253] INFO ProtocolHandler: [0xab245720]: Need to send state update Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.688 [11253.11253] INFO QConnectMessageSender: [0xab278fe8]: Sending StateUpdated message Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.688 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Playback state: Playing Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.688 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Current position: 12026 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.688 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Duration: 349773 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.689 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Queue version: 4.2 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.689 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Current queue item ID: 3 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.689 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Next queue item ID: 4 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.689 [11253.11253] DEBUG UserInactivityManager: [0xab245d78]: Renderer is actively playing Apr 28 09:49:22 ifi-phmidsd-idsd-phantom demo[764]: [receive from xmos]: ff 05 00 04 31 00 ea 64 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom demo[764]: CMD_XMOS_STREAM_IDLE_STATE Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.767 [11253.11253] INFO QwspMessageSender: [0xab278fd8]: Sending Payload message Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.767 [11253.11253] TRACE QwspMessageSender: [0xab278fd8]: Protocol: QConnect Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.767 [11253.11253] TRACE QwspMessageSender: [0xab278fd8]: Channel: Backend Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.767 [11253.11253] INFO QConnectMessageSender: [0xab278fe8]: Sending FileAudioQualityChanged message Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.768 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Sample rate: 48000 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.768 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Bit depth: 24 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.768 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Channel count: 2 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.768 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Audio quality: HIRES_L1 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.769 [11253.11253] INFO QwspMessageSender: [0xab278fd8]: Sending Payload message Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.769 [11253.11253] TRACE QwspMessageSender: [0xab278fd8]: Protocol: QConnect Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.769 [11253.11253] TRACE QwspMessageSender: [0xab278fd8]: Channel: Backend Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.769 [11253.11253] INFO QConnectMessageSender: [0xab278fe8]: Sending DeviceAudioQualityChanged message Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.769 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Sample rate: 48000 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.769 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Bit depth: 24 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.769 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Channel count: 2 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.769 [11253.11253] TRACE MetadataFetcher: [0xab27e7c8]: Track 255738654 doesn't have a valid version field Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.769 [11253.11253] TRACE MetadataFetcher: [0xab27e7c8]: Track 255738654 doesn't have a valid work field Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.769 [11253.11253] TRACE MetadataFetcher: [0xab27e7c8]: Track 255738654 doesn't have a valid album version field Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.770 [11253.11253] INFO QwspMessageSender: [0xab278fd8]: Sending Payload message Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.770 [11253.11253] TRACE QwspMessageSender: [0xab278fd8]: Protocol: QConnect Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.770 [11253.11253] TRACE QwspMessageSender: [0xab278fd8]: Channel: Backend Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.770 [11253.11253] TRACE PlaybackPositionEstimator: [0xab245cb0]: Synced playback position: 12026ms Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.770 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: [Stream 26]: stream information have been fetched Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.770 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: [Stream 25]: Metadata became available: Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.770 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: Title: 披星戴月的想你 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.770 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: Artist: 告五人 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.770 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: Album: 我肯定在幾百年前就說過愛你 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.770 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: Album art URL: https://static.qobuz.com/images/covers/za/hb/do2305kpehbza_600.jpg Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.771 [11253.11253] TRACE PlaybackPositionEstimator: [0xab245cb0]: Synced playback position: 12026ms Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.771 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Next stream changed state: Created -> Info Retrieved Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.771 [11253.11253] TRACE PlaybackPositionEstimator: [0xab245cb0]: Synced playback position: 12026ms Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.771 [11253.11253] INFO SampleApp: [Stream 25]: Stream metadata became available: Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.771 [11253.11253] INFO SampleApp: Title: 披星戴月的想你 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.771 [11253.11253] INFO SampleApp: Artist: 告五人 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.771 [11253.11253] INFO SampleApp: Album: 我肯定在幾百年前就說過愛你 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:22.771 [11253.11253] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/za/hb/do2305kpehbza_600.jpg Apr 28 09:49:22 ifi-phmidsd-idsd-phantom node[488]: metadata.title changed Apr 28 09:49:22 ifi-phmidsd-idsd-phantom node[488]: in qobuz-connect Apr 28 09:49:22 ifi-phmidsd-idsd-phantom node[488]: metadata.album: https://static.qobuz.com/images/covers/za/hb/do2305kpehbza_600.jpg Apr 28 09:49:22 ifi-phmidsd-idsd-phantom node[488]: message: {"title":"披星戴月的想你","artist":"告五人","album":"我肯定在幾百年前就說過愛你","albumart":"https://static.qobuz.com/images/covers/za/hb/do2305kpehbza_600.jpg","duration":"349","seek":"0"} Apr 28 09:49:22 ifi-phmidsd-idsd-phantom demo[764]: [receive from xmos]: ff 05 00 0c 20 81 01 81 01 01 01 1f 03 0f 98 b5 Apr 28 09:49:22 ifi-phmidsd-idsd-phantom demo[764]: STREAM_INFO MESSAGE Apr 28 09:49:23 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:23.125 [11253.11253] INFO UrlAudioSource: [0xab679a38]: Content info extracted Apr 28 09:49:23 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:23.125 [11253.11253] INFO UrlAudioSource: [0xab679a38]: Size: 71254661 Apr 28 09:49:23 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:23.131 [11253.18289] INFO AudioDecoder: [0xab3f0000]: Stream info: Apr 28 09:49:23 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:23.131 [11253.18289] INFO AudioDecoder: [0xab3f0000]: Total samples: 16789144 Apr 28 09:49:23 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:23.131 [11253.18289] INFO AudioDecoder: [0xab3f0000]: Sample rate: 48000 Apr 28 09:49:23 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:23.131 [11253.18289] INFO AudioDecoder: [0xab3f0000]: Channel count: 2 Apr 28 09:49:23 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:23.131 [11253.18289] INFO AudioDecoder: [0xab3f0000]: Bits per sample: 24 Apr 28 09:49:23 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:23.131 [11253.18289] INFO AudioDecoder: [0xab3f0000]: Duration: 349773 Apr 28 09:49:23 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:23.132 [11253.11253] INFO UrlAudioSource: [0xab679a38]: Audio properties have changed Apr 28 09:49:23 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:23.132 [11253.11253] INFO UrlAudioSource: [0xab679a38]: Seeking input stream to byte offset: 1817954 Apr 28 09:49:23 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:23.132 [11253.11253] INFO ContentFetcher: [0xab250c40]: Adjusting download offset to: 1817954 Apr 28 09:49:23 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:23.210 [11253.11253] DEBUG StreamingEventReporter: [0xab245b38]: Streaming report submitted successfully Apr 28 09:49:23 ifi-phmidsd-idsd-phantom node[488]: down load data end Apr 28 09:49:23 ifi-phmidsd-idsd-phantom node[488]: down load data end Apr 28 09:49:24 ifi-phmidsd-idsd-phantom ethernet-detect[493]: No change in avahi-daemon interfaces. Skipping restart. Apr 28 09:49:28 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ volatilePlay: null Apr 28 09:49:28 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::volumioVolatilePlay Apr 28 09:49:28 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreStateMachine::volatilePlay Apr 28 09:49:28 ifi-phmidsd-idsd-phantom volumio[10651]: info: ControllerMpd::play undefined Apr 28 09:49:28 ifi-phmidsd-idsd-phantom volumio[10651]: verbose: ControllerMpd::sendMpdCommand play Apr 28 09:49:28 ifi-phmidsd-idsd-phantom volumio[10651]: error: MPD error: Cannot read property 'toString' of undefined Apr 28 09:49:28 ifi-phmidsd-idsd-phantom volumio[10651]: error: Cannot read property 'toString' of undefined Apr 28 09:49:31 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:31.796 [11253.11253] TRACE WebsocketClient: [0xab24bc10]: Binary data received, size: 40 Apr 28 09:49:31 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:31.796 [11253.11253] INFO ProtocolHandler: [0xab245720]: Received SetState message: Apr 28 09:49:31 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:31.796 [11253.11253] INFO ProtocolHandler: [0xab245720]: Playing state: Paused Apr 28 09:49:31 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:31.796 [11253.11253] INFO ProtocolHandler: [0xab245720]: Playback position: Apr 28 09:49:31 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:31.796 [11253.11253] INFO ProtocolHandler: [0xab245720]: Queue version: Apr 28 09:49:31 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:31.796 [11253.11253] INFO ProtocolHandler: [0xab245720]: Current track: Apr 28 09:49:31 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:31.796 [11253.11253] INFO ProtocolHandler: [0xab245720]: Next track: Apr 28 09:49:31 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:31.796 [11253.11253] INFO MediaEngine: [0xab244fe0]: Pausing playback Apr 28 09:49:31 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:31.796 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Enqueuing command: Pause Apr 28 09:49:31 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:31.796 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Executing command: Pause Apr 28 09:49:31 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:31.796 [11253.11253] INFO AudioRenderer: [0xab245200]: Pausing audio renderer Apr 28 09:49:31 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:31.796 [11253.11253] DEBUG PlaybackProgressManager: [0xab245258]: Audio playback is paused Apr 28 09:49:31 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:31.796 [11253.11253] TRACE AudioRenderer: [0xab245200]: State changed: Playing -> Pausing Apr 28 09:49:31 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:31.796 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Audio renderer state changed: Playing -> Pausing Apr 28 09:49:31 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:31.796 [11253.11253] INFO SampleApp: Pausing playback Apr 28 09:49:32 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ volatilePlay: null Apr 28 09:49:32 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::volumioVolatilePlay Apr 28 09:49:32 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreStateMachine::volatilePlay Apr 28 09:49:32 ifi-phmidsd-idsd-phantom volumio[10651]: info: ControllerMpd::play undefined Apr 28 09:49:32 ifi-phmidsd-idsd-phantom volumio[10651]: verbose: ControllerMpd::sendMpdCommand play Apr 28 09:49:32 ifi-phmidsd-idsd-phantom volumio[10651]: error: MPD error: Cannot read property 'toString' of undefined Apr 28 09:49:32 ifi-phmidsd-idsd-phantom volumio[10651]: error: Cannot read property 'toString' of undefined Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.828 [11253.11253] DEBUG AudioRenderer: [0xab245200]: Transition to paused state has been acknowledged Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.828 [11253.11253] TRACE AudioRenderer: [0xab245200]: State changed: Pausing -> Paused Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.829 [11253.11253] ERROR SampleApp: Unable to get buffered frame count from ALSA audio sink: device is not open Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.829 [11253.11253] TRACE AudioRenderer: [0xab245200]: Playback position: 23816 Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.829 [11253.11253] INFO ProtocolHandler: [0xab245720]: Need to send state update Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.829 [11253.11253] INFO QConnectMessageSender: [0xab278fe8]: Sending StateUpdated message Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.829 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Playback state: Playing Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.829 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Current position: 23816 Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.829 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Duration: 349773 Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.829 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Queue version: 4.2 Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.829 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Current queue item ID: 3 Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.829 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Next queue item ID: 4 Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.829 [11253.11253] INFO QwspMessageSender: [0xab278fd8]: Sending Payload message Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.829 [11253.11253] TRACE QwspMessageSender: [0xab278fd8]: Protocol: QConnect Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.829 [11253.11253] TRACE QwspMessageSender: [0xab278fd8]: Channel: Backend Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.829 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Audio renderer state changed: Pausing -> Paused Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.829 [11253.11253] TRACE MediaEngine: [0xab244fe0]: State changed: Playing -> Paused Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.829 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Confirming command: Pause Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] INFO SampleApp: Playback state changed: Paused Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] ERROR SampleApp: Unable to get buffered frame count from ALSA audio sink: device is not open Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] TRACE AudioRenderer: [0xab245200]: Playback position: 23816 Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] INFO StreamingEventReporter: [0xab245b38]: Reporting streaming ended event Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] TRACE StreamingEventReporter: [0xab245b38]: End position: 23816ms Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] TRACE StreamingEventReporter: [0xab245b38]: Duration: 11790ms Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] DEBUG StreamingEventReporter: [0xab245b38]: Sending streaming reports Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] INFO HttpDownloader: [0xab245b90]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] ERROR SampleApp: Unable to get buffered frame count from ALSA audio sink: device is not open Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] TRACE AudioRenderer: [0xab245200]: Playback position: 23816 Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] INFO ProtocolHandler: [0xab245720]: Need to send state update Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] INFO QConnectMessageSender: [0xab278fe8]: Sending StateUpdated message Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Playback state: Paused Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Current position: 23816 Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Duration: 349773 Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Queue version: 4.2 Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Current queue item ID: 3 Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Next queue item ID: 4 Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] TRACE ProtocolHandler: [0xab245720]: Stopping periodic state updates Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.830 [11253.11253] DEBUG UserInactivityManager: [0xab245d78]: Renderer is active but not playing, will deactivate in 3600s Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.831 [11253.11253] INFO QwspMessageSender: [0xab278fd8]: Sending Payload message Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.831 [11253.11253] TRACE QwspMessageSender: [0xab278fd8]: Protocol: QConnect Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.831 [11253.11253] TRACE QwspMessageSender: [0xab278fd8]: Channel: Backend Apr 28 09:49:32 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:32.832 [11253.11253] TRACE PlaybackPositionEstimator: [0xab245cb0]: Synced playback position: 23816ms Apr 28 09:49:33 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:33.299 [11253.11253] DEBUG StreamingEventReporter: [0xab245b38]: Streaming report submitted successfully Apr 28 09:49:33 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:33.319 [11253.11253] TRACE WebsocketClient: [0xab24bc10]: Binary data received, size: 40 Apr 28 09:49:33 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:33.320 [11253.11253] INFO ProtocolHandler: [0xab245720]: Received SetState message: Apr 28 09:49:33 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:33.320 [11253.11253] INFO ProtocolHandler: [0xab245720]: Playing state: Paused Apr 28 09:49:33 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:33.320 [11253.11253] INFO ProtocolHandler: [0xab245720]: Playback position: Apr 28 09:49:33 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:33.320 [11253.11253] INFO ProtocolHandler: [0xab245720]: Queue version: Apr 28 09:49:33 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:33.320 [11253.11253] INFO ProtocolHandler: [0xab245720]: Current track: Apr 28 09:49:33 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:33.320 [11253.11253] INFO ProtocolHandler: [0xab245720]: Next track: Apr 28 09:49:33 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:33.320 [11253.11253] INFO MediaEngine: [0xab244fe0]: Pausing playback Apr 28 09:49:33 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:33.320 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Enqueuing command: Pause Apr 28 09:49:33 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:33.320 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Executing command: Pause Apr 28 09:49:33 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:33.320 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Confirming command: Pause Apr 28 09:49:35 ifi-phmidsd-idsd-phantom ethernet-detect[493]: No change in avahi-daemon interfaces. Skipping restart. Apr 28 09:49:35 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:35.372 [11253.11253] TRACE WebsocketClient: [0xab24bc10]: Binary data received, size: 40 Apr 28 09:49:35 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:35.373 [11253.11253] INFO ProtocolHandler: [0xab245720]: Received SetState message: Apr 28 09:49:35 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:35.373 [11253.11253] INFO ProtocolHandler: [0xab245720]: Playing state: Playing Apr 28 09:49:35 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:35.373 [11253.11253] INFO ProtocolHandler: [0xab245720]: Playback position: Apr 28 09:49:35 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:35.373 [11253.11253] INFO ProtocolHandler: [0xab245720]: Queue version: Apr 28 09:49:35 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:35.373 [11253.11253] INFO ProtocolHandler: [0xab245720]: Current track: Apr 28 09:49:35 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:35.373 [11253.11253] INFO ProtocolHandler: [0xab245720]: Next track: Apr 28 09:49:35 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:35.373 [11253.11253] INFO MediaEngine: [0xab244fe0]: Resuming playback Apr 28 09:49:35 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:35.373 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Enqueuing command: Resume Apr 28 09:49:35 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:35.373 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Executing command: Resume Apr 28 09:49:35 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:35.373 [11253.11253] INFO AudioRenderer: [0xab245200]: Resuming audio renderer Apr 28 09:49:35 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:35.373 [11253.11253] TRACE AudioRenderer: [0xab245200]: State changed: Paused -> Resuming Apr 28 09:49:35 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:35.373 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Audio renderer state changed: Paused -> Resuming Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: *************************************************** Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: format: PCM Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: base: 1 times: 1 Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: in rate: 48000 Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: base: 1 times: 1 Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: out rate: 48000 Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: current filter index: 0 Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: current k2 index: 0 Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: current dsd index: 0 Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: current filter bit mask: 0x1 Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: available filter bit mask: 0x1f Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: current k2 bit mask: 0x1 Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: available k2 bit mask: 0x7 Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: current dsd remaster bit mask: 0x1 Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: available dsd remaster bit mask: 0xf Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: *************************************************** Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: alsa has stream Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: [receive from xmos]: ff 05 00 04 31 01 fa 45 Apr 28 09:49:35 ifi-phmidsd-idsd-phantom demo[764]: CMD_XMOS_STREAM_IDLE_STATE Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.381 [11253.11253] TRACE SampleApp: ALSA PCM's descriptor count: 1 Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.381 [11253.11253] INFO SampleApp: Resuming playback Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.381 [11253.11253] DEBUG AudioRenderer: [0xab245200]: Transition to playing state (resume) has been acknowledged Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.381 [11253.11253] TRACE AudioRenderer: [0xab245200]: State changed: Resuming -> Playing Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.382 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Audio renderer state changed: Resuming -> Playing Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.382 [11253.11253] TRACE MediaEngine: [0xab244fe0]: State changed: Paused -> Playing Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.382 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Confirming command: Resume Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.383 [11253.11253] DEBUG PlaybackProgressManager: [0xab245258]: Audio playback started, time until playback gets near the end: 315966ms Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.383 [11253.11253] INFO SampleApp: Playback state changed: Playing Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.383 [11253.11253] TRACE AudioRenderer: [0xab245200]: Playback position: 23808 Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.383 [11253.11253] INFO StreamingEventReporter: [0xab245b38]: Reporting streaming started event Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.383 [11253.11253] TRACE StreamingEventReporter: [0xab245b38]: Track ID: 255738654 Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.383 [11253.11253] TRACE StreamingEventReporter: [0xab245b38]: Start position: 23808ms Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.383 [11253.11253] TRACE StreamingEventReporter: [0xab245b38]: Context UUID: 61f6df5c-0ab2-458e-939b-830685ad4748 Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.383 [11253.11253] TRACE StreamingEventReporter: [0xab245b38]: Blob ID: 100000.SGk4pQCuTItoqVoyM_PCdYHnrUuQ6T7LygsMYY7YyB4xFS3V8gbgpulrb5MWkwH_.XoXInboxJ4AMt2M6GzuhQLBDCyg Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.383 [11253.11253] DEBUG StreamingEventReporter: [0xab245b38]: Sending streaming reports Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.383 [11253.11253] INFO HttpDownloader: [0xab245b90]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.384 [11253.11253] TRACE AudioRenderer: [0xab245200]: Playback position: 23808 Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.384 [11253.11253] INFO ProtocolHandler: [0xab245720]: Need to send state update Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.384 [11253.11253] INFO QConnectMessageSender: [0xab278fe8]: Sending StateUpdated message Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.384 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Playback state: Playing Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.384 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Current position: 23808 Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.384 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Duration: 349773 Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.384 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Queue version: 4.2 Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.384 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Current queue item ID: 3 Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.384 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Next queue item ID: 4 Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.384 [11253.11253] DEBUG UserInactivityManager: [0xab245d78]: Renderer is actively playing Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.385 [11253.11253] INFO QwspMessageSender: [0xab278fd8]: Sending Payload message Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.385 [11253.11253] TRACE QwspMessageSender: [0xab278fd8]: Protocol: QConnect Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.385 [11253.11253] TRACE QwspMessageSender: [0xab278fd8]: Channel: Backend Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.388 [11253.11253] TRACE PlaybackPositionEstimator: [0xab245cb0]: Synced playback position: 23807ms Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.389 [11253.11253] TRACE PlaybackPositionEstimator: [0xab245cb0]: Synced playback position: 23808ms Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.390 [11253.11253] TRACE PlaybackPositionEstimator: [0xab245cb0]: Synced playback position: 23808ms Apr 28 09:49:36 ifi-phmidsd-idsd-phantom node[488]: metadata.title changed Apr 28 09:49:36 ifi-phmidsd-idsd-phantom node[488]: in qobuz-connect Apr 28 09:49:36 ifi-phmidsd-idsd-phantom node[488]: metadata.album: https://static.qobuz.com/images/covers/za/hb/do2305kpehbza_600.jpg Apr 28 09:49:36 ifi-phmidsd-idsd-phantom node[488]: message: {"title":"披星戴月的想你","artist":"告五人","album":"我肯定在幾百年前就說過愛你","albumart":"https://static.qobuz.com/images/covers/za/hb/do2305kpehbza_600.jpg","duration":"349","seek":"0"} Apr 28 09:49:36 ifi-phmidsd-idsd-phantom demo[764]: [receive from xmos]: ff 05 00 04 31 00 ea 64 Apr 28 09:49:36 ifi-phmidsd-idsd-phantom demo[764]: CMD_XMOS_STREAM_IDLE_STATE Apr 28 09:49:36 ifi-phmidsd-idsd-phantom demo[764]: [receive from xmos]: ff 05 00 0c 20 81 01 81 01 01 01 1f 03 0f 98 b5 Apr 28 09:49:36 ifi-phmidsd-idsd-phantom demo[764]: STREAM_INFO MESSAGE Apr 28 09:49:36 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:36.794 [11253.11253] DEBUG StreamingEventReporter: [0xab245b38]: Streaming report submitted successfully Apr 28 09:49:36 ifi-phmidsd-idsd-phantom node[488]: down load data end Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: *************************************************** Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: format: PCM Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: base: 1 times: 1 Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: in rate: 48000 Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: base: 1 times: 1 Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: out rate: 48000 Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: current filter index: 0 Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: current k2 index: 0 Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: current dsd index: 0 Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: current filter bit mask: 0x1 Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: available filter bit mask: 0x1f Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: current k2 bit mask: 0x1 Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: available k2 bit mask: 0x7 Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: current dsd remaster bit mask: 0x1 Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: available dsd remaster bit mask: 0xf Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: *************************************************** Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 00 ff 55 4e Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 01 6f e5 c6 Apr 28 09:49:40 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:41 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 01 7e e7 d6 Apr 28 09:49:41 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:41 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 01 90 fb 36 Apr 28 09:49:41 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:42 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 01 6c d5 a5 Apr 28 09:49:42 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:42 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 01 46 50 8d Apr 28 09:49:42 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:45 ifi-phmidsd-idsd-phantom ethernet-detect[493]: No change in avahi-daemon interfaces. Skipping restart. Apr 28 09:49:45 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 01 7b b7 73 Apr 28 09:49:45 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:45 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 01 88 68 0f Apr 28 09:49:45 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:45 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 01 72 26 5a Apr 28 09:49:45 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:46 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 01 5e c3 b4 Apr 28 09:49:46 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.383 [11253.11253] TRACE AudioRenderer: [0xab245200]: Playback position: 33810 Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.383 [11253.11253] INFO ProtocolHandler: [0xab245720]: Need to send state update Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.383 [11253.11253] INFO QConnectMessageSender: [0xab278fe8]: Sending StateUpdated message Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.383 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Playback state: Playing Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.383 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Current position: 33810 Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.383 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Duration: 349773 Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.383 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Queue version: 4.2 Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.383 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Current queue item ID: 3 Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.384 [11253.11253] TRACE QConnectMessageSender: [0xab278fe8]: Next queue item ID: 4 Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.384 [11253.11253] INFO QwspMessageSender: [0xab278fd8]: Sending Payload message Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.384 [11253.11253] TRACE QwspMessageSender: [0xab278fd8]: Protocol: QConnect Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.384 [11253.11253] TRACE QwspMessageSender: [0xab278fd8]: Channel: Backend Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.384 [11253.11253] TRACE PlaybackPositionEstimator: [0xab245cb0]: Synced playback position: 33810ms Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.864 [11253.11253] TRACE WebsocketClient: [0xab24bc10]: Binary data received, size: 38 Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] INFO ProtocolHandler: [0xab245720]: Received SetActive message: inactive Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] DEBUG RendererActivationCommandSequencer: [0xab2459e0]: Enqueuing command: Deactivate Renderer Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] DEBUG RendererActivationController: [0xab245938]: Executing command: Deactivate Renderer Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] DEBUG RendererActivationController: [0xab245938]: Stopping playback session maintenance Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] INFO PlaybackSessionManager: [0xab244d48]: Stopping playback session maintenance Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] DEBUG RendererActivationController: [0xab245938]: Making the renderer inactive Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] INFO ActiveStateManager: [0xab243970]: Setting new active state: inactive Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] DEBUG RendererActivationController: [0xab245938]: Media Engine needs to be stopped Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] INFO MediaEngine: [0xab244fe0]: Stopping playback, clearing tracks Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Enqueuing command: Stop Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] INFO MediaEngine: [0xab244fe0]: Reseting Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Enqueuing command: Reset Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Enqueuing command: SetCurrentTrack Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Enqueuing command: SetNextTrack Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] TRACE ProtocolHandler: [0xab245720]: Stopping periodic state updates Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] INFO SampleApp: Renderer is now inactive Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Executing command: Stop Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] INFO AudioRenderer: [0xab245200]: Stopping audio renderer Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] DEBUG PlaybackProgressManager: [0xab245258]: Audio playback is stopped Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] TRACE AudioRenderer: [0xab245200]: State changed: Playing -> Stopping Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Audio renderer state changed: Playing -> Stopping Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.865 [11253.11253] INFO SampleApp: Stopping playback Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.876 [11253.11253] DEBUG AudioRenderer: [0xab245200]: Transition to stopped state has been acknowledged Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.876 [11253.11253] TRACE AudioRenderer: [0xab245200]: State changed: Stopping -> Stopped Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.876 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Audio renderer state changed: Stopping -> Stopped Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.876 [11253.11253] TRACE MediaEngine: [0xab244fe0]: State changed: Playing -> Stopped Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.876 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: Clearing all streams Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.876 [11253.11253] INFO AudioStreamManager: [0xab2450c8]: [Stream 25]: Disposing of audio stream Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.876 [11253.11253] INFO ContentFetcher: [0xab250c40]: Canceling download Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.884 [11253.11253] DEBUG Ringbuffer: [0xab5d9450]: Freeing Audio Ringbuffer Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.886 [11253.11253] DEBUG AudioStreamInfoFetcher: [0xab48ba78]: Freeing Audio Stream Info Fetcher Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] DEBUG MetadataFetcher: [0xab27e7c8]: Freeing Metadata Fetcher Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] DEBUG PlaybackUrlFetcher: [0xab2786b8]: Freeing Playback URL Fetcher Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] DEBUG AudioStreamInfoFetcher: [0xab32a238]: Freeing Audio Stream Info Fetcher Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] DEBUG MetadataFetcher: [0xab3a8040]: Freeing Metadata Fetcher Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] DEBUG PlaybackUrlFetcher: [0xab24bbf0]: Freeing Playback URL Fetcher Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Confirming command: Stop Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] INFO SampleApp: Playback state changed: Stopped Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] INFO StreamingEventReporter: [0xab245b38]: Reporting streaming ended event Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] TRACE StreamingEventReporter: [0xab245b38]: End position: 34313ms Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] TRACE StreamingEventReporter: [0xab245b38]: Duration: 10505ms Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] DEBUG StreamingEventReporter: [0xab245b38]: Sending streaming reports Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] INFO HttpDownloader: [0xab245b90]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] TRACE ProtocolHandler: [0xab245720]: Not sending state update because the renderer is not active Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] DEBUG UserInactivityManager: [0xab245d78]: Renderer is active but not playing, will deactivate in 3600s Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Current stream changed state: Running -> Nonexistent Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] INFO SampleApp: [Stream 25]: Audio stream has been disposed of Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Executing command: Reset Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Confirming command: Reset Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] DEBUG RendererActivationController: [0xab245938]: Media Engine has been stopped Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Invalidating track properties and device audio format Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.887 [11253.11253] TRACE RendererActivationController: [0xab245938]: State changed: Active -> Inactive (Connected) Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.888 [11253.11253] DEBUG RendererActivationCommandSequencer: [0xab2459e0]: Confirming command: Deactivate Renderer Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.888 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Current stream changed state: Info Retrieved -> Nonexistent Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.888 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Executing command: SetCurrentTrack Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.888 [11253.11253] INFO MediaEngine: [0xab244fe0]: Setting current track: NONE Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.888 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Confirming command: SetCurrentTrack Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.889 [11253.11253] DEBUG MediaEngine: [0xab244fe0]: Executing command: SetNextTrack Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.889 [11253.11253] INFO MediaEngine: [0xab244fe0]: Setting next track: NONE Apr 28 09:49:46 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:46.889 [11253.11253] DEBUG MediaEngineCommandSequencer: [0xab245358]: Confirming command: SetNextTrack Apr 28 09:49:47 ifi-phmidsd-idsd-phantom play[11227]: 20250428 09:49:47.384 [11253.11253] DEBUG StreamingEventReporter: [0xab245b38]: Streaming report submitted successfully Apr 28 09:49:49 ifi-phmidsd-idsd-phantom demo[764]: alsa no stream Apr 28 09:49:49 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 01 32 6e 9e Apr 28 09:49:49 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:49 ifi-phmidsd-idsd-phantom demo[764]: [receive from xmos]: ff 05 00 04 31 01 fa 45 Apr 28 09:49:49 ifi-phmidsd-idsd-phantom demo[764]: CMD_XMOS_STREAM_IDLE_STATE Apr 28 09:49:50 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 01 43 00 28 Apr 28 09:49:50 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:50 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 01 79 97 31 Apr 28 09:49:50 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:51 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 01 96 9b f0 Apr 28 09:49:51 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:52 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 01 72 26 5a Apr 28 09:49:52 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:53 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 01 67 64 ce Apr 28 09:49:53 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:53 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 01 40 30 4b Apr 28 09:49:54 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:54 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 01 2f ad 02 Apr 28 09:49:54 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:49:55 ifi-phmidsd-idsd-phantom ethernet-detect[493]: No change in avahi-daemon interfaces. Skipping restart. Apr 28 09:50:05 ifi-phmidsd-idsd-phantom ethernet-detect[493]: No change in avahi-daemon interfaces. Skipping restart. Apr 28 09:50:06 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ getMenuItems Apr 28 09:50:07 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ getUiConfig: {"page":"audio_interface/alsa_controller"} Apr 28 09:50:07 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 09:50:07 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 28 09:50:07 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 09:50:07 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 28 09:50:07 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 09:50:07 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 09:50:07 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 09:50:07 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 09:50:07 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 09:50:07 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 09:50:07 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 09:50:07 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 28 09:50:07 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 28 09:50:09 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ getMenuItems Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ getUiConfig: {"page":"miscellanea/my_music"} Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: error: Could not retrieve plugin music_service streaming_services Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::volumioGetBrowseSources Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::volumioGetBrowseSources Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::volumioGetBrowseSources Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ getMyCollectionStats: null Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: sendMpdCommand play took 43309 milliseconds Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ getListShares: null Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 28 09:50:11 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ getMyMusicPlugins Apr 28 09:50:14 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 00 fa 05 eb Apr 28 09:50:14 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:50:14 ifi-phmidsd-idsd-phantom demo[764]: [ send to IOT]: ff 05 00 06 21 0a 00 c2 b2 b0 Apr 28 09:50:14 ifi-phmidsd-idsd-phantom demo[764]: [receive from IOT]: ff 05 00 04 02 21 8e e1 Apr 28 09:50:15 ifi-phmidsd-idsd-phantom ethernet-detect[493]: No change in avahi-daemon interfaces. Skipping restart. Apr 28 09:50:15 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ getMyCollectionStats: null Apr 28 09:50:15 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 09:50:15 ifi-phmidsd-idsd-phantom volumio[10651]: info: sendMpdCommand play took 43415 milliseconds Apr 28 09:50:19 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ getMyCollectionStats: null Apr 28 09:50:19 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 09:50:23 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ getMyCollectionStats: null Apr 28 09:50:23 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 09:50:25 ifi-phmidsd-idsd-phantom ethernet-detect[493]: No change in avahi-daemon interfaces. Skipping restart. Apr 28 09:50:26 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ getNetworkSharesDiscovery Apr 28 09:50:26 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 28 09:50:26 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 28 09:50:27 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ getMyCollectionStats: null Apr 28 09:50:27 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 09:50:31 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ getMyCollectionStats: null Apr 28 09:50:31 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 09:50:33 ifi-phmidsd-idsd-phantom ntpd[1131]: 202.181.103.212 local addr 192.168.50.37 -> Apr 28 09:50:35 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ getMyCollectionStats: null Apr 28 09:50:35 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 09:50:35 ifi-phmidsd-idsd-phantom ethernet-detect[493]: No change in avahi-daemon interfaces. Skipping restart. Apr 28 09:50:39 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ getMyCollectionStats: null Apr 28 09:50:39 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 09:50:43 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ getMyCollectionStats: null Apr 28 09:50:43 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 09:50:45 ifi-phmidsd-idsd-phantom ethernet-detect[493]: No change in avahi-daemon interfaces. Skipping restart. Apr 28 09:50:47 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ getMyCollectionStats: null Apr 28 09:50:47 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 09:50:51 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ getMyCollectionStats: null Apr 28 09:50:51 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 28 09:50:53 ifi-phmidsd-idsd-phantom volumio[10651]: info: ++++--++++ addShare: {"fstype":"cifs","name":"192.168.1.220","ip":"192.168.1.220","path":"usr2\\R&D\\USERS\\polo\\POLOxiong\\music\\testmusic","username":"Administrator","password":"P455w0rd111"} Apr 28 09:50:53 ifi-phmidsd-idsd-phantom volumio[10651]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Apr 28 09:50:53 ifi-phmidsd-idsd-phantom volumio[10651]: info: Adding a new share Apr 28 09:50:53 ifi-phmidsd-idsd-phantom volumio[10651]: info: No correspondence found in configuration for share 192.168.1.220 on IP 192.168.1.220 Apr 28 09:50:53 ifi-phmidsd-idsd-phantom sudo[19203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Administrator,password=P455w0rd111,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.220/usr2\R&D\USERS\polo\POLOxiong\music\testmusic /mnt/NAS/192.168.1.220 Apr 28 09:50:53 ifi-phmidsd-idsd-phantom sudo[19203]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 09:50:53 ifi-phmidsd-idsd-phantom kernel: FS-Cache: Loaded Apr 28 09:50:53 ifi-phmidsd-idsd-phantom kernel: FS-Cache: Netfs 'cifs' registered for caching Apr 28 09:50:53 ifi-phmidsd-idsd-phantom sudo[19203]: pam_unix(sudo:session): session closed for user root Apr 28 09:50:53 ifi-phmidsd-idsd-phantom volumio[10651]: verbose: ControllerMpd::sendMpdCommand status Apr 28 09:50:53 ifi-phmidsd-idsd-phantom volumio[10651]: info: Apr 28 09:50:53 ifi-phmidsd-idsd-phantom volumio[10651]: ---------------------------- MPD announces state update: update Apr 28 09:50:53 ifi-phmidsd-idsd-phantom volumio[10651]: info: ControllerMpd::getState Apr 28 09:50:53 ifi-phmidsd-idsd-phantom volumio[10651]: verbose: ControllerMpd::sendMpdCommand status Apr 28 09:50:53 ifi-phmidsd-idsd-phantom volumio[10651]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 09:50:54 ifi-phmidsd-idsd-phantom volumio[10651]: TypeError: Cannot read property 'substring' of undefined Apr 28 09:50:54 ifi-phmidsd-idsd-phantom volumio[10651]: at /volumio/app/plugins/music_service/mpd/index.js:3120:70 Apr 28 09:50:54 ifi-phmidsd-idsd-phantom volumio[10651]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 28 09:50:54 ifi-phmidsd-idsd-phantom volumio[10651]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 28 09:50:54 ifi-phmidsd-idsd-phantom volumio[10651]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 28 09:50:54 ifi-phmidsd-idsd-phantom volumio[10651]: at Socket.emit (events.js:400:28) Apr 28 09:50:54 ifi-phmidsd-idsd-phantom volumio[10651]: at addChunk (internal/streams/readable.js:293:12) Apr 28 09:50:54 ifi-phmidsd-idsd-phantom volumio[10651]: at readableAddChunk (internal/streams/readable.js:263:11) Apr 28 09:50:54 ifi-phmidsd-idsd-phantom volumio[10651]: at Socket.Readable.push (internal/streams/readable.js:206:10) Apr 28 09:50:54 ifi-phmidsd-idsd-phantom volumio[10651]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 28 09:50:54 ifi-phmidsd-idsd-phantom volumio[10651]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 09:50:54 ifi-phmidsd-idsd-phantom sudo[19234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-28 09:49 Apr 28 09:50:54 ifi-phmidsd-idsd-phantom sudo[19234]: 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="8a0defaac7e920a11527bed08dfa1cf7bce6cbbc" VOLUMIO_FE_VERSION="0548e367fe44b194ccf1306c8af35deb040eccd2" VOLUMIO_FE3_VERSION="c63cc070c36379a8d79dc0d7d4a24908bebc2472" VOLUMIO_BE_VERSION="679648e2cde0726b3cc817882082a0e2ad4c7511" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="ifi" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Apr 28 17:12:50 CST 2025" VOLUMIO_VERSION="2.01.0" VOLUMIO_HARDWARE="idsd-phantom" VOLUMIO_DEVICENAME="iDSD PHANTOM" VOLUMIO_HASH="f060e09acd311d3cf81c91eab926abad"