-- Logs begin at Sat 2025-05-17 09:28:50 JST, end at Sat 2025-05-17 09:35:55 JST. -- May 17 09:34:01 rivo-toshi qobuz-connect[3820]: 20250517 09:34:01.987 [3820.3820] INFO ProtocolHandler: [0xab156538]: Need to send state update May 17 09:34:01 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:01 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:34:01 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:34:01 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:34:01 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:34:01 rivo-toshi qobuz-connect[3820]: 20250517 09:34:01.992 [3820.3820] INFO QConnectMessageSender: [0xab232eb8]: Sending StateUpdated message May 17 09:34:01 rivo-toshi qobuz-connect[3820]: 20250517 09:34:01.992 [3820.3820] INFO QwspMessageSender: [0xab232ea8]: Sending Payload message May 17 09:34:01 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:01 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:34:02 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:34:02 rivo-toshi volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 09:34:11 rivo-toshi qobuz-connect[3820]: 20250517 09:34:11.988 [3820.3820] INFO ProtocolHandler: [0xab156538]: Need to send state update May 17 09:34:11 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:11 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:34:11 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:34:11 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:34:11 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:34:11 rivo-toshi qobuz-connect[3820]: 20250517 09:34:11.993 [3820.3820] INFO QConnectMessageSender: [0xab232eb8]: Sending StateUpdated message May 17 09:34:11 rivo-toshi qobuz-connect[3820]: 20250517 09:34:11.993 [3820.3820] INFO QwspMessageSender: [0xab232ea8]: Sending Payload message May 17 09:34:11 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:11 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:34:12 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:34:12 rivo-toshi volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 09:34:21 rivo-toshi qobuz-connect[3820]: 20250517 09:34:21.989 [3820.3820] INFO ProtocolHandler: [0xab156538]: Need to send state update May 17 09:34:21 rivo-toshi qobuz-connect[3820]: 20250517 09:34:21.989 [3820.3820] INFO QConnectMessageSender: [0xab232eb8]: Sending StateUpdated message May 17 09:34:21 rivo-toshi qobuz-connect[3820]: 20250517 09:34:21.989 [3820.3820] INFO QwspMessageSender: [0xab232ea8]: Sending Payload message May 17 09:34:21 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:21 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:34:21 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:34:21 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:34:21 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:34:21 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:21 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:34:22 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:34:22 rivo-toshi volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.199 [3820.3820] INFO ProtocolHandler: [0xab156538]: Received SetState message: May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.200 [3820.3820] INFO ProtocolHandler: [0xab156538]: Playing state: Playing May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.200 [3820.3820] INFO ProtocolHandler: [0xab156538]: Playback position: 0 May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.200 [3820.3820] INFO ProtocolHandler: [0xab156538]: Queue version: 4.1 May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.200 [3820.3820] INFO ProtocolHandler: [0xab156538]: Current track: TID: 205458789, QID: 0, Context UUID: 7a00e8b6-f8d9-4cbc-bc1b-116044df1411 May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.200 [3820.3820] INFO ProtocolHandler: [0xab156538]: Next track: TID: 205458790, QID: 1, Context UUID: 7a00e8b6-f8d9-4cbc-bc1b-116044df1411 May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.200 [3820.3820] INFO MediaEngine: [0xab155df8]: Stopping playback, clearing tracks May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.200 [3820.3820] INFO MediaEngine: [0xab155df8]: Initiating playback May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.200 [3820.3820] INFO AudioRenderer: [0xab156018]: Stopping audio renderer May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.200 [3820.3820] INFO SampleApp: Stopping playback May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.210 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Clearing all streams May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.210 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: [Stream 3]: Disposing of audio stream May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.210 [3820.3820] INFO ContentFetcher: [0xab4efd80]: Canceling download May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.218 [3820.3820] INFO SampleApp: Playback state changed: Stopped May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:27 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.231 [3820.3820] INFO StreamingEventReporter: [0xab156950]: Reporting streaming ended event May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.231 [3820.3820] INFO HttpDownloader: [0xab1569a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.231 [3820.3820] INFO ProtocolHandler: [0xab156538]: Need to send state update May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.231 [3820.3820] INFO QConnectMessageSender: [0xab232eb8]: Sending StateUpdated message May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.232 [3820.3820] INFO SampleApp: [Stream 3]: Audio stream has been disposed of May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.232 [3820.3820] INFO MediaEngine: [0xab155df8]: Setting current track: 205458789, initial offset: 0ms May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.232 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Clearing all streams May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.232 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: New stream: 5 May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.232 [3820.3820] INFO HttpDownloader: [0xab632988]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=1aa81405c8c2d8e25af47793a06b25af&request_ts=1747442067&track_id=205458789 May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.232 [3820.3820] INFO HttpDownloader: [0xab64d4b0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=205458789 May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.232 [3820.3820] INFO HttpDownloader: [0xab65f060]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=80110ae61e1f0d10386ed8cf89f362f8&request_ts=1747442067&track_id=205458789 May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.232 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: [Stream 5]: Running audio stream May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.232 [3820.3820] INFO QwspMessageSender: [0xab232ea8]: Sending Payload message May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.235 [3820.3820] INFO MediaEngine: [0xab155df8]: Setting next track: 205458790 May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.235 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: New stream: 6 May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.235 [3820.3820] INFO HttpDownloader: [0xab6329b0]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=63ba5ec6a8c3d600d2a1b180068dbc22&request_ts=1747442067&track_id=205458790 May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.235 [3820.3820] INFO HttpDownloader: [0xab1ede68]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=205458790 May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.235 [3820.3820] INFO HttpDownloader: [0xab63ade8]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=7b39eb1ebeb8784ddac47b7e602ee19f&request_ts=1747442067&track_id=205458790 May 17 09:34:27 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.237 [3820.3820] INFO MediaEngine: [0xab155df8]: Waiting for current stream to start before starting audio renderer May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:27 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:34:27 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:34:27 rivo-toshi volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 09:34:27 rivo-toshi volumio[3267]: info: MCU Signalled Playback Inactive May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.505 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: [Stream 6]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=205458790&fmt=6&profile=raw&app_id=174516466&cid=2464732&etsp=1747445667&hmac=l0XVlXoBg9QUg_texLiebmuty_A May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.507 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: [Stream 5]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=205458789&fmt=6&profile=raw&app_id=174516466&cid=2464732&etsp=1747445667&hmac=qof9l3bD1L5Far7O0JPcepQofTo May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.547 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: [Stream 5]: Metadata became available: May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.547 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Title: Piano Concerto No. 2 in B-Flat Major, Op. 83, IJB 83: I. Allegro non troppo May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.547 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Artist: NBC Symphony Orchestra May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.547 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Album: BRAHMS: PIANO CONCERTO No. 2 May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.547 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Album art URL: https://static.qobuz.com/images/covers/cc/ak/p64b1f82uakcc_600.jpg May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.560 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: [Stream 6]: Metadata became available: May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.560 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Title: Piano Concerto No. 2 in B-Flat Major, Op. 83, IJB 83: II. Allegro appassionato May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.560 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Artist: NBC Symphony Orchestra May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.560 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Album: BRAHMS: PIANO CONCERTO No. 2 May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.560 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Album art URL: https://static.qobuz.com/images/covers/cc/ak/p64b1f82uakcc_600.jpg May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.563 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: [Stream 6]: stream information have been fetched May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.607 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: [Stream 5]: stream information have been fetched May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.607 [3820.3820] INFO UrlAudioSource: [0xab2e40e8]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=205458789&fmt=6&profile=raw&app_id=174516466&cid=2464732&etsp=1747445667&hmac=qof9l3bD1L5Far7O0JPcepQofTo May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.607 [3820.3820] INFO ContentFetcher: [0xab4ea5e8]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=205458789&fmt=6&profile=raw&app_id=174516466&cid=2464732&etsp=1747445667&hmac=qof9l3bD1L5Far7O0JPcepQofTo, offset: 0 May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.608 [3820.3820] INFO AudioRenderer: [0xab156018]: Starting audio renderer, initial playback state: Playing May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.608 [3820.3820] INFO SampleApp: [Stream 5]: New audio stream (starting from 0ms) May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.609 [3820.3820] INFO SampleApp: [Stream 5]: Stream metadata became available: May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.610 [3820.3820] INFO SampleApp: Title: Piano Concerto No. 2 in B-Flat Major, Op. 83, IJB 83: I. Allegro non troppo May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.610 [3820.3820] INFO SampleApp: Artist: NBC Symphony Orchestra May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.611 [3820.3820] INFO SampleApp: Album: BRAHMS: PIANO CONCERTO No. 2 May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.611 [3820.3820] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/cc/ak/p64b1f82uakcc_600.jpg May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:27 rivo-toshi volumio[3267]: info: QobuzConnect: alread currrent service, send playback confirmation May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.615 [3820.3820] INFO SampleApp: Initiating playback, initial state: Playing May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.878 [3820.3820] INFO SampleApp: Playback state changed: Playing May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.879 [3820.3820] INFO StreamingEventReporter: [0xab156950]: Reporting streaming started event May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.879 [3820.3820] INFO HttpDownloader: [0xab1569a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.879 [3820.3820] INFO ProtocolHandler: [0xab156538]: Need to send state update May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.879 [3820.3820] INFO QConnectMessageSender: [0xab232eb8]: Sending StateUpdated message May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.880 [3820.3820] INFO QwspMessageSender: [0xab232ea8]: Sending Payload message May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.880 [3820.3820] INFO QConnectMessageSender: [0xab232eb8]: Sending FileAudioQualityChanged message May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.881 [3820.3820] INFO QwspMessageSender: [0xab232ea8]: Sending Payload message May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.881 [3820.3820] INFO ProtocolHandler: [0xab156538]: Received SetState message: May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.881 [3820.3820] INFO ProtocolHandler: [0xab156538]: Playing state: May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.881 [3820.3820] INFO ProtocolHandler: [0xab156538]: Playback position: May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.881 [3820.3820] INFO ProtocolHandler: [0xab156538]: Queue version: 4.2 May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.881 [3820.3820] INFO ProtocolHandler: [0xab156538]: Current track: May 17 09:34:27 rivo-toshi qobuz-connect[3820]: 20250517 09:34:27.881 [3820.3820] INFO ProtocolHandler: [0xab156538]: Next track: TID: 205458790, QID: 1, Context UUID: 7a00e8b6-f8d9-4cbc-bc1b-116044df1411 May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:27 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:34:27 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:27 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:34:27 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:27 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:34:27 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:34:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:27 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:34:27 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:34:28 rivo-toshi volumio[3267]: info: [LastFM] Current track has sufficient metadata: title (Piano Concerto No. 2 in B-Flat Major, Op. 83, IJB 83: I. Allegro non troppo) and artist (NBC Symphony Orchestra) passed on explicitly May 17 09:34:28 rivo-toshi volumio[3267]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) May 17 09:34:28 rivo-toshi volumio[3267]: info: Signalling Playback active due to playback status change May 17 09:34:28 rivo-toshi volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 09:34:28 rivo-toshi volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 09:34:28 rivo-toshi volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 09:34:28 rivo-toshi volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 09:34:28 rivo-toshi volumio[3267]: info: MCU Signalled Playback Active May 17 09:34:29 rivo-toshi qobuz-connect[3820]: 20250517 09:34:29.245 [3820.3820] INFO UrlAudioSource: [0xab2e40e8]: Content info extracted May 17 09:34:29 rivo-toshi qobuz-connect[3820]: 20250517 09:34:29.245 [3820.3820] INFO UrlAudioSource: [0xab2e40e8]: Size: 63266925 May 17 09:34:29 rivo-toshi qobuz-connect[3820]: 20250517 09:34:29.253 [3820.4914] INFO AudioDecoder: [0xab2def00]: Stream info: May 17 09:34:29 rivo-toshi qobuz-connect[3820]: 20250517 09:34:29.253 [3820.4914] INFO AudioDecoder: [0xab2def00]: Total samples: 43037484 May 17 09:34:29 rivo-toshi qobuz-connect[3820]: 20250517 09:34:29.253 [3820.4914] INFO AudioDecoder: [0xab2def00]: Sample rate: 44100 May 17 09:34:29 rivo-toshi qobuz-connect[3820]: 20250517 09:34:29.253 [3820.4914] INFO AudioDecoder: [0xab2def00]: Channel count: 2 May 17 09:34:29 rivo-toshi qobuz-connect[3820]: 20250517 09:34:29.253 [3820.4914] INFO AudioDecoder: [0xab2def00]: Bits per sample: 16 May 17 09:34:29 rivo-toshi qobuz-connect[3820]: 20250517 09:34:29.253 [3820.4914] INFO AudioDecoder: [0xab2def00]: Duration: 975906 May 17 09:34:29 rivo-toshi qobuz-connect[3820]: 20250517 09:34:29.253 [3820.3820] INFO UrlAudioSource: [0xab2e40e8]: Audio properties have changed May 17 09:34:29 rivo-toshi volumio[3267]: info: Executing endpoint metavolumio May 17 09:34:29 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 17 09:34:29 rivo-toshi volumio[3267]: info: Executing endpoint metavolumio May 17 09:34:29 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 17 09:34:29 rivo-toshi volumio[3267]: info: Executing endpoint metavolumio May 17 09:34:29 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 17 09:34:37 rivo-toshi qobuz-connect[3820]: 20250517 09:34:37.858 [3820.3820] INFO ProtocolHandler: [0xab156538]: Need to send state update May 17 09:34:37 rivo-toshi qobuz-connect[3820]: 20250517 09:34:37.859 [3820.3820] INFO QConnectMessageSender: [0xab232eb8]: Sending StateUpdated message May 17 09:34:37 rivo-toshi qobuz-connect[3820]: 20250517 09:34:37.859 [3820.3820] INFO QwspMessageSender: [0xab232ea8]: Sending Payload message May 17 09:34:37 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:37 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:34:37 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:34:37 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:34:37 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:34:37 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:37 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:34:37 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:34:37 rivo-toshi volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 09:34:47 rivo-toshi qobuz-connect[3820]: 20250517 09:34:47.859 [3820.3820] INFO ProtocolHandler: [0xab156538]: Need to send state update May 17 09:34:47 rivo-toshi qobuz-connect[3820]: 20250517 09:34:47.859 [3820.3820] INFO QConnectMessageSender: [0xab232eb8]: Sending StateUpdated message May 17 09:34:47 rivo-toshi qobuz-connect[3820]: 20250517 09:34:47.859 [3820.3820] INFO QwspMessageSender: [0xab232ea8]: Sending Payload message May 17 09:34:47 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:47 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:34:47 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:34:47 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:34:47 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:34:47 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:47 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:34:47 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:34:47 rivo-toshi volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 09:34:55 rivo-toshi volumiologrotate[2829]: ls: cannot access '/var/log/samba/log.wb-RIVO': No such file or directory May 17 09:34:55 rivo-toshi volumiologrotate[2829]: ls: cannot access 'TOSHI': No such file or directory May 17 09:34:57 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:57 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:34:57 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:34:57 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:34:57 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:34:57 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:34:57 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:34:57 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:34:57 rivo-toshi qobuz-connect[3820]: 20250517 09:34:57.873 [3820.3820] INFO ProtocolHandler: [0xab156538]: Need to send state update May 17 09:34:57 rivo-toshi qobuz-connect[3820]: 20250517 09:34:57.873 [3820.3820] INFO QConnectMessageSender: [0xab232eb8]: Sending StateUpdated message May 17 09:34:57 rivo-toshi qobuz-connect[3820]: 20250517 09:34:57.873 [3820.3820] INFO QwspMessageSender: [0xab232ea8]: Sending Payload message May 17 09:34:57 rivo-toshi volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 09:35:07 rivo-toshi qobuz-connect[3820]: 20250517 09:35:07.859 [3820.3820] INFO ProtocolHandler: [0xab156538]: Need to send state update May 17 09:35:07 rivo-toshi qobuz-connect[3820]: 20250517 09:35:07.859 [3820.3820] INFO QConnectMessageSender: [0xab232eb8]: Sending StateUpdated message May 17 09:35:07 rivo-toshi qobuz-connect[3820]: 20250517 09:35:07.859 [3820.3820] INFO QwspMessageSender: [0xab232ea8]: Sending Payload message May 17 09:35:07 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:07 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:35:07 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:35:07 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:35:07 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:35:07 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:07 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:35:07 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:35:07 rivo-toshi volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 09:35:17 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:17 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:35:17 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:35:17 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:35:17 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:35:17 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:17 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:35:17 rivo-toshi qobuz-connect[3820]: 20250517 09:35:17.869 [3820.3820] INFO ProtocolHandler: [0xab156538]: Need to send state update May 17 09:35:17 rivo-toshi qobuz-connect[3820]: 20250517 09:35:17.869 [3820.3820] INFO QConnectMessageSender: [0xab232eb8]: Sending StateUpdated message May 17 09:35:17 rivo-toshi qobuz-connect[3820]: 20250517 09:35:17.870 [3820.3820] INFO QwspMessageSender: [0xab232ea8]: Sending Payload message May 17 09:35:17 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:35:17 rivo-toshi volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 09:35:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:35:27 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:35:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:35:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:35:27 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:27 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:35:27 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:35:27 rivo-toshi qobuz-connect[3820]: 20250517 09:35:27.876 [3820.3820] INFO ProtocolHandler: [0xab156538]: Need to send state update May 17 09:35:27 rivo-toshi qobuz-connect[3820]: 20250517 09:35:27.876 [3820.3820] INFO QConnectMessageSender: [0xab232eb8]: Sending StateUpdated message May 17 09:35:27 rivo-toshi qobuz-connect[3820]: 20250517 09:35:27.876 [3820.3820] INFO QwspMessageSender: [0xab232ea8]: Sending Payload message May 17 09:35:27 rivo-toshi volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 09:35:37 rivo-toshi qobuz-connect[3820]: 20250517 09:35:37.859 [3820.3820] INFO ProtocolHandler: [0xab156538]: Need to send state update May 17 09:35:37 rivo-toshi qobuz-connect[3820]: 20250517 09:35:37.860 [3820.3820] INFO QConnectMessageSender: [0xab232eb8]: Sending StateUpdated message May 17 09:35:37 rivo-toshi qobuz-connect[3820]: 20250517 09:35:37.860 [3820.3820] INFO QwspMessageSender: [0xab232ea8]: Sending Payload message May 17 09:35:37 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:37 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:35:37 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:35:37 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:35:37 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:35:37 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:37 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:35:37 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:35:37 rivo-toshi volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 09:35:47 rivo-toshi qobuz-connect[3820]: 20250517 09:35:47.859 [3820.3820] INFO ProtocolHandler: [0xab156538]: Need to send state update May 17 09:35:47 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:47 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:35:47 rivo-toshi qobuz-connect[3820]: 20250517 09:35:47.864 [3820.3820] INFO QConnectMessageSender: [0xab232eb8]: Sending StateUpdated message May 17 09:35:47 rivo-toshi qobuz-connect[3820]: 20250517 09:35:47.864 [3820.3820] INFO QwspMessageSender: [0xab232ea8]: Sending Payload message May 17 09:35:47 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:35:47 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:35:47 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:35:47 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:47 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:35:47 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:35:47 rivo-toshi volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.140 [3820.3820] INFO ProtocolHandler: [0xab156538]: Received SetState message: May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.142 [3820.3820] INFO ProtocolHandler: [0xab156538]: Playing state: Playing May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.142 [3820.3820] INFO ProtocolHandler: [0xab156538]: Playback position: 0 May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.143 [3820.3820] INFO ProtocolHandler: [0xab156538]: Queue version: 5.1 May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.143 [3820.3820] INFO ProtocolHandler: [0xab156538]: Current track: TID: 94896793, QID: 16, Context UUID: 1f3d14ee-93c9-49c8-9752-2adc2523e3ea May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.144 [3820.3820] INFO ProtocolHandler: [0xab156538]: Next track: TID: 85358528, QID: 17, Context UUID: 1f3d14ee-93c9-49c8-9752-2adc2523e3ea May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.144 [3820.3820] INFO MediaEngine: [0xab155df8]: Stopping playback, clearing tracks May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.144 [3820.3820] INFO MediaEngine: [0xab155df8]: Initiating playback May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.145 [3820.3820] INFO AudioRenderer: [0xab156018]: Stopping audio renderer May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.146 [3820.3820] INFO SampleApp: Stopping playback May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.153 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Clearing all streams May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.154 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: [Stream 5]: Disposing of audio stream May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.154 [3820.3820] INFO ContentFetcher: [0xab4ea5e8]: Canceling download May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.163 [3820.3820] INFO SampleApp: Playback state changed: Stopped May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.164 [3820.3820] INFO StreamingEventReporter: [0xab156950]: Reporting streaming ended event May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.164 [3820.3820] INFO HttpDownloader: [0xab1569a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingEndJson May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.168 [3820.3820] INFO ProtocolHandler: [0xab156538]: Need to send state update May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.168 [3820.3820] INFO QConnectMessageSender: [0xab232eb8]: Sending StateUpdated message May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.169 [3820.3820] INFO SampleApp: [Stream 5]: Audio stream has been disposed of May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.169 [3820.3820] INFO MediaEngine: [0xab155df8]: Setting current track: 94896793, initial offset: 0ms May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.170 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Clearing all streams May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.171 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: New stream: 7 May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.171 [3820.3820] INFO HttpDownloader: [0xab553ac8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=ad5f227c19a1ac356e29310f56da70a1&request_ts=1747442154&track_id=94896793 May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.173 [3820.3820] INFO HttpDownloader: [0xab3ab198]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=94896793 May 17 09:35:54 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.174 [3820.3820] INFO HttpDownloader: [0xab445b38]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=a38887f2d3ea6b89b9255bca44126193&request_ts=1747442154&track_id=94896793 May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.174 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: [Stream 7]: Running audio stream May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.174 [3820.3820] INFO QwspMessageSender: [0xab232ea8]: Sending Payload message May 17 09:35:54 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:54 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:35:54 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.218 [3820.3820] INFO MediaEngine: [0xab155df8]: Setting next track: 85358528 May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.219 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: New stream: 8 May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.219 [3820.3820] INFO HttpDownloader: [0xab15fa60]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=9aad943399f2d1e02913f0d9def81ded&request_ts=1747442154&track_id=85358528 May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.219 [3820.3820] INFO HttpDownloader: [0xab36cf90]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=85358528 May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.219 [3820.3820] INFO HttpDownloader: [0xab3712a0]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=05130cd5d38f0cdc90686379a0b1ff0b&request_ts=1747442154&track_id=85358528 May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.221 [3820.3820] INFO MediaEngine: [0xab155df8]: Waiting for current stream to start before starting audio renderer May 17 09:35:54 rivo-toshi volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 09:35:54 rivo-toshi volumio[3267]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 17 09:35:54 rivo-toshi volumio[3267]: info: MCU Signalled Playback Inactive May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.548 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: [Stream 7]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=94896793&fmt=27&profile=raw&app_id=174516466&cid=2464732&etsp=1747445754&hmac=rLUorzaooIQiskzA6mtZKsxo0ns May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.548 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: [Stream 8]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=85358528&fmt=27&profile=raw&app_id=174516466&cid=2464732&etsp=1747445754&hmac=i_EbkpmcwNPju_sqBeCZPaiGCSM May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.550 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: [Stream 8]: stream information have been fetched May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.567 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: [Stream 7]: stream information have been fetched May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.567 [3820.3820] INFO UrlAudioSource: [0xab56e9c0]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=94896793&fmt=27&profile=raw&app_id=174516466&cid=2464732&etsp=1747445754&hmac=rLUorzaooIQiskzA6mtZKsxo0ns May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.567 [3820.3820] INFO ContentFetcher: [0xab509c08]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3451974&eid=94896793&fmt=27&profile=raw&app_id=174516466&cid=2464732&etsp=1747445754&hmac=rLUorzaooIQiskzA6mtZKsxo0ns, offset: 0 May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.568 [3820.3820] INFO AudioRenderer: [0xab156018]: Starting audio renderer, initial playback state: Playing May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.568 [3820.3820] INFO SampleApp: [Stream 7]: New audio stream (starting from 0ms) May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:54 rivo-toshi volumio[3267]: info: QobuzConnect: alread currrent service, send playback confirmation May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.571 [3820.3820] INFO SampleApp: Initiating playback, initial state: Playing May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.941 [3820.3820] INFO SampleApp: Playback state changed: Playing May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.941 [3820.3820] INFO StreamingEventReporter: [0xab156950]: Reporting streaming started event May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.941 [3820.3820] INFO HttpDownloader: [0xab1569a8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/reportStreamingStart May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.941 [3820.3820] INFO ProtocolHandler: [0xab156538]: Need to send state update May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.941 [3820.3820] INFO QConnectMessageSender: [0xab232eb8]: Sending StateUpdated message May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.942 [3820.3820] INFO QwspMessageSender: [0xab232ea8]: Sending Payload message May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.943 [3820.3820] INFO QConnectMessageSender: [0xab232eb8]: Sending FileAudioQualityChanged message May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.943 [3820.3820] INFO ProtocolHandler: [0xab156538]: Received SetState message: May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.944 [3820.3820] INFO ProtocolHandler: [0xab156538]: Playing state: May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.944 [3820.3820] INFO ProtocolHandler: [0xab156538]: Playback position: May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.944 [3820.3820] INFO ProtocolHandler: [0xab156538]: Queue version: 5.2 May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.944 [3820.3820] INFO ProtocolHandler: [0xab156538]: Current track: May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.944 [3820.3820] INFO ProtocolHandler: [0xab156538]: Next track: TID: 85358528, QID: 17, Context UUID: 1f3d14ee-93c9-49c8-9752-2adc2523e3ea May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.945 [3820.3820] INFO QwspMessageSender: [0xab232ea8]: Sending Payload message May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.945 [3820.3820] INFO QConnectMessageSender: [0xab232eb8]: Sending DeviceAudioQualityChanged message May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.945 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: [Stream 7]: Metadata became available: May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.945 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Title: 歌劇《カルメン》 / 第1幕 - 「セビーリャの城壁の近く」（カルメン、ドン・ホセ） May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.945 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Artist: テレサ・ベルガンサ May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.945 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Album: ビゼー：歌劇《カルメン》 May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.945 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Album art URL: https://static.qobuz.com/images/covers/4a/my/o3tnaijhcmy4a_600.jpg May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.946 [3820.3820] INFO QwspMessageSender: [0xab232ea8]: Sending Payload message May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.946 [3820.3820] INFO SampleApp: [Stream 7]: Stream metadata became available: May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.946 [3820.3820] INFO SampleApp: Title: 歌劇《カルメン》 / 第1幕 - 「セビーリャの城壁の近く」（カルメン、ドン・ホセ） May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.946 [3820.3820] INFO SampleApp: Artist: テレサ・ベルガンサ May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.946 [3820.3820] INFO SampleApp: Album: ビゼー：歌劇《カルメン》 May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.946 [3820.3820] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/4a/my/o3tnaijhcmy4a_600.jpg May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.948 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: [Stream 8]: Metadata became available: May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.948 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Title: Theme & Variations in E-Flat Major, WoO 24, 'Ghost Variations' - VI. Variation 5 May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.948 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Artist: Eric Lu May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.948 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Album: Chopin - Brahms - Schumann May 17 09:35:54 rivo-toshi qobuz-connect[3820]: 20250517 09:35:54.948 [3820.3820] INFO AudioStreamManager: [0xab155ee0]: Album art URL: https://static.qobuz.com/images/covers/da/hf/j3xqptif7hfda_600.jpg May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:54 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:35:54 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:54 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:35:54 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:35:54 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:54 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:35:54 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:35:55 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:55 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:35:55 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:35:55 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:35:55 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:55 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:35:55 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:35:55 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:55 rivo-toshi volumio[3267]: info: CoreCommandRouter::servicePushState May 17 09:35:55 rivo-toshi volumio[3267]: info: CoreStateMachine::pushState May 17 09:35:55 rivo-toshi volumio[3267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 09:35:55 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioPushState May 17 09:35:55 rivo-toshi volumio[3267]: info: CoreCommandRouter::volumioGetState May 17 09:35:55 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output update for this device May 17 09:35:55 rivo-toshi volumio[3267]: info: MRS: Pushing multiroomSync output May 17 09:35:55 rivo-toshi volumio[3267]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! May 17 09:35:55 rivo-toshi volumio[3267]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 09:35:55 rivo-toshi volumio[3267]: TypeError: Cannot read property 'indexOf' of undefined May 17 09:35:55 rivo-toshi volumio[3267]: at ControllerLastFM.formatScrobbleData (/data/plugins/user_interface/lastfm/index.js:873:25) May 17 09:35:55 rivo-toshi volumio[3267]: at ControllerLastFM.checkStateUpdate (/data/plugins/user_interface/lastfm/index.js:825:26) May 17 09:35:55 rivo-toshi volumio[3267]: at Socket. (/data/plugins/user_interface/lastfm/index.js:110:52) May 17 09:35:55 rivo-toshi volumio[3267]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 17 09:35:55 rivo-toshi volumio[3267]: at Socket.onevent (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:270:10) May 17 09:35:55 rivo-toshi volumio[3267]: at Socket.onpacket (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/socket.js:228:12) May 17 09:35:55 rivo-toshi volumio[3267]: at Manager. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 17 09:35:55 rivo-toshi volumio[3267]: at Manager.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 17 09:35:55 rivo-toshi volumio[3267]: at Manager.ondecoded (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:332:8) May 17 09:35:55 rivo-toshi volumio[3267]: at Decoder. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 17 09:35:55 rivo-toshi volumio[3267]: at Decoder.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) May 17 09:35:55 rivo-toshi volumio[3267]: at Decoder.add (/data/plugins/user_interface/lastfm/node_modules/socket.io-parser/index.js:246:12) May 17 09:35:55 rivo-toshi volumio[3267]: at Manager.ondata (/data/plugins/user_interface/lastfm/node_modules/socket.io-client/lib/manager.js:322:16) May 17 09:35:55 rivo-toshi volumio[3267]: at Socket. (/data/plugins/user_interface/lastfm/node_modules/component-bind/index.js:21:15) May 17 09:35:55 rivo-toshi volumio[3267]: at Socket.Emitter.emit (/data/plugins/user_interface/lastfm/node_modules/component-emitter/index.js:133:20) May 17 09:35:55 rivo-toshi volumio[3267]: at Socket.onPacket (/data/plugins/user_interface/lastfm/node_modules/engine.io-client/lib/socket.js:451:14) May 17 09:35:55 rivo-toshi volumio[3267]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 09:35:55 rivo-toshi volumiologrotate[2829]: ls: cannot access '/var/log/samba/log.wb-RIVO': No such file or directory May 17 09:35:55 rivo-toshi volumiologrotate[2829]: ls: cannot access 'TOSHI': No such file or directory May 17 09:35:55 rivo-toshi sudo[5156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-17 09:34 May 17 09:35:55 rivo-toshi sudo[5156]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 06:24:39 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="c8a59422b1bfedffc72f72cef58ad20b"