-- Logs begin at Mon 2025-09-08 16:50:07 JST, end at Sun 2025-09-14 14:38:09 JST. -- Sep 14 14:37:09 rivo volumio[3502]: info: Clearing queue after UPNP request Sep 14 14:37:09 rivo volumio[3502]: verbose: UNSET VOLATILE: Service: qobuzconnect Sep 14 14:37:09 rivo volumio[3502]: info: QobuzConnect: onUnsetVolatile() called, relinquishing Volumio State to another service Sep 14 14:37:09 rivo volumio[3502]: info: QobuzConnect: Received stop Sep 14 14:37:09 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:09.999 [4664.4664] INFO MediaEngine: [0xac75bdd8]: Stopping playback, keeping tracks Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:09.999 [4664.4664] INFO AudioRenderer: [0xac75bff8]: Stopping audio renderer Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.000 [4664.4664] INFO SampleApp: Stopping playback Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.023 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: Clearing all streams Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.024 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: [Stream 1]: Disposing of audio stream Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.024 [4664.4664] INFO ContentFetcher: [0xac765770]: Canceling download Sep 14 14:37:10 rivo volumio[3502]: info: QobuzConnect: playback_stop received, deferred solved Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.032 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: New stream: 3 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.032 [4664.4664] INFO HttpDownloader: [0xac839238]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=efc7373893baaabdfcfc15bed7efd52d&request_ts=1757828230&track_id=303285278 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.032 [4664.4664] INFO HttpDownloader: [0xac760788]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=303285278 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.032 [4664.4664] INFO HttpDownloader: [0xac75f408]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=21b5a2477d92f5008b00721a85047ec9&request_ts=1757828230&track_id=303285278 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.033 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: [Stream 3]: Running audio stream Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.033 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: New stream: 4 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.033 [4664.4664] INFO HttpDownloader: [0xac9147d8]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&request_sig=3e3a565398ed0181a3791c8930b5dd2e&request_ts=1757828230&track_id=264920269 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.033 [4664.4664] INFO HttpDownloader: [0xac7ec760]: Downloading content from: https://www.qobuz.com/api.json/0.2/track/get?track_id=264920269 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.033 [4664.4664] INFO HttpDownloader: [0xac790288]: Downloading content from: https://www.qobuz.com/api.json/0.2/file/url?format_id=27&intent=stream&request_sig=d1c9d0e1fe7e2fdac64c86e4bc52b2c5&request_ts=1757828230&track_id=264920269 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.035 [4664.4664] INFO SampleApp: Playback state changed: Stopped Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.035 [4664.4664] INFO ProtocolHandler: [0xac75c518]: Need to send state update Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.035 [4664.4664] INFO QConnectMessageSender: [0xac8e6cd0]: Sending StateUpdated message Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.035 [4664.4664] INFO SampleApp: [Stream 1]: Audio stream has been disposed of Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.036 [4664.4664] INFO QwspMessageSender: [0xac8e6cc0]: Sending Payload message Sep 14 14:37:10 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:10 rivo volumio[3502]: info: CorePlayQueue::getTrack 0 Sep 14 14:37:10 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:10 rivo volumio[3502]: info: CorePlayQueue::getTrack 0 Sep 14 14:37:10 rivo volumio[3502]: info: CoreStateMachine::ClearQueue Sep 14 14:37:10 rivo volumio[3502]: info: CoreStateMachine::stop Sep 14 14:37:10 rivo volumio[3502]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 14:37:10 rivo volumio[3502]: info: CoreStateMachine::updateTrackBlock Sep 14 14:37:10 rivo volumio[3502]: info: CorePlayQueue::getTrackBlock Sep 14 14:37:10 rivo volumio[3502]: info: CoreStateMachine::stPlaybackTimer Sep 14 14:37:10 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:10 rivo volumio[3502]: info: CorePlayQueue::getTrack 0 Sep 14 14:37:10 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 14:37:10 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:10 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:10 rivo volumio[3502]: info: CorePlayQueue::getTrack 0 Sep 14 14:37:10 rivo volumio[3502]: info: CoreStateMachine::serviceStop Sep 14 14:37:10 rivo volumio[3502]: info: CorePlayQueue::getTrack 0 Sep 14 14:37:10 rivo volumio[3502]: info: ControllerMpd::stop Sep 14 14:37:10 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 14:37:10 rivo volumio[3502]: info: CorePlayQueue::clearPlayQueue Sep 14 14:37:10 rivo volumio[3502]: info: CorePlayQueue::saveQueue Sep 14 14:37:10 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:10 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:10 rivo volumio[3502]: info: CorePlayQueue::getTrack 0 Sep 14 14:37:10 rivo volumio[3502]: info: CoreCommandRouter::volumioPushQueue Sep 14 14:37:10 rivo volumio[3502]: error: Upnp client error: Error: This socket has been ended by the other party Sep 14 14:37:10 rivo volumio[3502]: info: sendMpdCommand stop took 42 milliseconds Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.367 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: [Stream 3]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3815980&eid=303285278&fmt=6&profile=raw&app_id=174516466&cid=2754666&etsp=1757831830&hmac=qI4hBSBwlp0MBUPSJk6121I69Ig Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.386 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: [Stream 4]: track URL resolved to: https://streaming-qobuz-std.akamaized.net/file?uid=3815980&eid=264920269&fmt=7&profile=raw&app_id=174516466&cid=2754666&etsp=1757831830&hmac=XbdykKW_Ss7Ny2AVdWtL6vwR5n4 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.396 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: [Stream 4]: stream information have been fetched Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.408 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: [Stream 4]: Metadata became available: Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.409 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: Title: 難破船 (30th Anniversary Mix; 2024 Lacquer Master Sound) Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.409 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: Artist: 中森　明菜 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.409 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: Album: ベスト・コレクション ～ラブ・ソングス＆ポップ・ソングス～ (30th Anniversary Mix; 2024 Lacquer Master Sound) Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.409 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: Album art URL: https://static.qobuz.com/images/covers/qb/s8/c8zbc2q4bs8qb_600.jpg Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.410 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: [Stream 3]: stream information have been fetched Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.410 [4664.4664] INFO UrlAudioSource: [0xac9087c8]: Starting URL audio source, initial position: 0ms, URL: https://streaming-qobuz-std.akamaized.net/file?uid=3815980&eid=303285278&fmt=6&profile=raw&app_id=174516466&cid=2754666&etsp=1757831830&hmac=qI4hBSBwlp0MBUPSJk6121I69Ig Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.411 [4664.4664] INFO ContentFetcher: [0xac8d7720]: Fetching content from: https://streaming-qobuz-std.akamaized.net/file?uid=3815980&eid=303285278&fmt=6&profile=raw&app_id=174516466&cid=2754666&etsp=1757831830&hmac=qI4hBSBwlp0MBUPSJk6121I69Ig, offset: 0 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.411 [4664.4664] INFO SampleApp: [Stream 3]: New audio stream (starting from 0ms) Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.444 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: [Stream 3]: Metadata became available: Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.444 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: Title: まっ白 (2024Ver.) Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.444 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: Artist: 小田和正 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.444 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: Album: 自己ベスト-2 (2024Ver.) Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.445 [4664.4664] INFO AudioStreamManager: [0xac75bec0]: Album art URL: https://static.qobuz.com/images/covers/8b/dq/ibe1rjdf4dq8b_600.jpg Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.445 [4664.4664] INFO SampleApp: [Stream 3]: Stream metadata became available: Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.445 [4664.4664] INFO SampleApp: Title: まっ白 (2024Ver.) Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.445 [4664.4664] INFO SampleApp: Artist: 小田和正 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.445 [4664.4664] INFO SampleApp: Album: 自己ベスト-2 (2024Ver.) Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.445 [4664.4664] INFO SampleApp: Album art URL: https://static.qobuz.com/images/covers/8b/dq/ibe1rjdf4dq8b_600.jpg Sep 14 14:37:10 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:10 rivo volumio[3502]: info: CorePlayQueue::getTrack 0 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.575 [4664.4664] INFO UrlAudioSource: [0xac9087c8]: Content info extracted Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.575 [4664.4664] INFO UrlAudioSource: [0xac9087c8]: Size: 32607000 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.577 [4664.4935] INFO AudioDecoder: [0xac992e80]: Stream info: Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.578 [4664.4935] INFO AudioDecoder: [0xac992e80]: Total samples: 11617704 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.578 [4664.4935] INFO AudioDecoder: [0xac992e80]: Sample rate: 44100 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.578 [4664.4935] INFO AudioDecoder: [0xac992e80]: Channel count: 2 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.578 [4664.4935] INFO AudioDecoder: [0xac992e80]: Bits per sample: 16 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.578 [4664.4935] INFO AudioDecoder: [0xac992e80]: Duration: 263440 Sep 14 14:37:10 rivo qobuz-connect[4664]: 20250914 14:37:10.578 [4664.4664] INFO UrlAudioSource: [0xac9087c8]: Audio properties have changed Sep 14 14:37:12 rivo volumio[3502]: info: Starting UPNP Playback Sep 14 14:37:12 rivo volumio[3502]: info: Preparing playback through UPNP Sep 14 14:37:12 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:12 rivo volumio[3502]: info: CorePlayQueue::getTrack 0 Sep 14 14:37:12 rivo volumio[3502]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 14:37:12 rivo volumio[3502]: info: Sep 14 14:37:12 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:12 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:12 rivo volumio[3502]: info: Sep 14 14:37:12 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:12 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:12 rivo volumio[3502]: info: Sep 14 14:37:12 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:12 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:12 rivo volumio[3502]: info: ------------------------------ 8ms Sep 14 14:37:12 rivo volumio[3502]: info: ------------------------------ 5ms Sep 14 14:37:12 rivo volumio[3502]: info: Sep 14 14:37:12 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:12 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:12 rivo volumio[3502]: info: ------------------------------ 6ms Sep 14 14:37:12 rivo volumio[3502]: info: ------------------------------ 3ms Sep 14 14:37:12 rivo volumio[3502]: info: Sep 14 14:37:12 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:12 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:12 rivo volumio[3502]: info: Sep 14 14:37:12 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:12 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:12 rivo volumio[3502]: info: ------------------------------ 4ms Sep 14 14:37:12 rivo volumio[3502]: info: ------------------------------ 3ms Sep 14 14:37:12 rivo volumio[3502]: info: Sep 14 14:37:12 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:12 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:12 rivo volumio[3502]: info: Sep 14 14:37:12 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:12 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:12 rivo volumio[3502]: info: ------------------------------ 5ms Sep 14 14:37:12 rivo volumio[3502]: info: ------------------------------ 2ms Sep 14 14:37:12 rivo volumio[3502]: info: Sep 14 14:37:12 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:12 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:12 rivo volumio[3502]: info: Sep 14 14:37:12 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:12 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:12 rivo volumio[3502]: info: ------------------------------ 4ms Sep 14 14:37:12 rivo volumio[3502]: info: Sep 14 14:37:12 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:12 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:12 rivo volumio[3502]: info: ------------------------------ 5ms Sep 14 14:37:12 rivo volumio[3502]: info: Sep 14 14:37:12 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:12 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:12 rivo volumio[3502]: info: ------------------------------ 5ms Sep 14 14:37:12 rivo volumio[3502]: info: ------------------------------ 3ms Sep 14 14:37:13 rivo volumio[3502]: info: Sep 14 14:37:13 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:13 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:13 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:13 rivo volumio[3502]: info: Sep 14 14:37:13 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:13 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:13 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:13 rivo volumio[3502]: info: Sep 14 14:37:13 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:13 rivo volumio[3502]: info: sendMpdCommand status took 103 milliseconds Sep 14 14:37:13 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:13 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:13 rivo volumio[3502]: info: sendMpdCommand status took 96 milliseconds Sep 14 14:37:13 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:13 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:13 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:13 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:13 rivo volumio[3502]: info: sendMpdCommand status took 9 milliseconds Sep 14 14:37:13 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 14 14:37:13 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 14 14:37:13 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:13 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:13 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:13 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:13 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:13 rivo volumio[3502]: verbose: In UPNP mode Sep 14 14:37:13 rivo volumio[3502]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":290,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:13 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::syncState stateService play Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus stop Sep 14 14:37:13 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:13 rivo volumio[3502]: verbose: In UPNP mode Sep 14 14:37:13 rivo volumio[3502]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":290,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:13 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::syncState stateService play Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus play Sep 14 14:37:13 rivo volumio[3502]: info: Received an update from plugin. extracting info from payload Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:13 rivo volumio[3502]: info: ------------------------------ 144ms Sep 14 14:37:13 rivo volumio[3502]: info: ------------------------------ 156ms Sep 14 14:37:13 rivo volumio[3502]: info: Sep 14 14:37:13 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:13 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:13 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:13 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 44 milliseconds Sep 14 14:37:13 rivo volumio[3502]: info: sendMpdCommand status took 3 milliseconds Sep 14 14:37:13 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:13 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:13 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:13 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:13 rivo volumio[3502]: verbose: In UPNP mode Sep 14 14:37:13 rivo volumio[3502]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":290,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:13 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::syncState stateService play Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus play Sep 14 14:37:13 rivo volumio[3502]: info: Received an update from plugin. extracting info from payload Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:13 rivo volumio[3502]: info: ------------------------------ 100ms Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:13 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:13 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:13 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:13 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:13 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:13 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:13 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:13 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:13 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 63 milliseconds Sep 14 14:37:13 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:13 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:13 rivo volumio[3502]: verbose: In UPNP mode Sep 14 14:37:13 rivo volumio[3502]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":290,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1392 Kbps","isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:13 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::syncState stateService play Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus play Sep 14 14:37:13 rivo volumio[3502]: info: Received an update from plugin. extracting info from payload Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:13 rivo volumio[3502]: info: ------------------------------ 111ms Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:13 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:13 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:13 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:13 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:13 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:13 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:13 rivo volumio[3502]: info: MCU Signalled Playback Active Sep 14 14:37:26 rivo volumio[3502]: info: Sep 14 14:37:26 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:26 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:26 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:26 rivo volumio[3502]: info: Sep 14 14:37:26 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:26 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:26 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:26 rivo volumio[3502]: info: sendMpdCommand status took 5 milliseconds Sep 14 14:37:26 rivo volumio[3502]: info: sendMpdCommand status took 2 milliseconds Sep 14 14:37:26 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:26 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:26 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:26 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:26 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 14 14:37:26 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 14 14:37:26 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:26 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:26 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:26 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:26 rivo volumio[3502]: verbose: In UPNP mode Sep 14 14:37:26 rivo volumio[3502]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14238,"duration":290,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1258 Kbps","isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:26 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:26 rivo volumio[3502]: info: CoreStateMachine::syncState stateService pause Sep 14 14:37:26 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus play Sep 14 14:37:26 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:26 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 14:37:26 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:26 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:26 rivo volumio[3502]: info: CoreStateMachine::stPlaybackTimer Sep 14 14:37:26 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:26 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:26 rivo volumio[3502]: verbose: In UPNP mode Sep 14 14:37:26 rivo volumio[3502]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14238,"duration":290,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1258 Kbps","isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:26 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:26 rivo volumio[3502]: info: CoreStateMachine::syncState stateService pause Sep 14 14:37:26 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus play Sep 14 14:37:26 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:26 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:26 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:26 rivo volumio[3502]: info: CoreStateMachine::stPlaybackTimer Sep 14 14:37:26 rivo volumio[3502]: info: ------------------------------ 61ms Sep 14 14:37:26 rivo volumio[3502]: info: ------------------------------ 59ms Sep 14 14:37:26 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:26 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:26 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:26 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:26 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:26 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:26 rivo volumio[3502]: info: MCU Signalled Playback Inactive Sep 14 14:37:30 rivo volumio[3502]: info: Sep 14 14:37:30 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:30 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:30 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:30 rivo volumio[3502]: info: Sep 14 14:37:30 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:30 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:30 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:30 rivo volumio[3502]: info: sendMpdCommand status took 3 milliseconds Sep 14 14:37:30 rivo volumio[3502]: info: sendMpdCommand status took 2 milliseconds Sep 14 14:37:30 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:30 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:30 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:30 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:30 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 14 14:37:30 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 14 14:37:30 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:30 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:30 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:30 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:30 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:30 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:30 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:30 rivo volumio[3502]: info: CorePlayQueue::getTrack 0 Sep 14 14:37:30 rivo volumio[3502]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:30 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:30 rivo volumio[3502]: info: CoreStateMachine::syncState stateService stop Sep 14 14:37:30 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus play Sep 14 14:37:30 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:30 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:30 rivo volumio[3502]: info: CoreStateMachine::stPlaybackTimer Sep 14 14:37:30 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:30 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:30 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:30 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 14:37:30 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:30 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:30 rivo volumio[3502]: info: CorePlayQueue::getTrack 0 Sep 14 14:37:30 rivo volumio[3502]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:30 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:30 rivo volumio[3502]: info: CoreStateMachine::syncState stateService stop Sep 14 14:37:30 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus stop Sep 14 14:37:30 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:30 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:30 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:30 rivo volumio[3502]: info: No code Sep 14 14:37:30 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:30 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:30 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:30 rivo volumio[3502]: info: ------------------------------ 87ms Sep 14 14:37:30 rivo volumio[3502]: info: ------------------------------ 87ms Sep 14 14:37:30 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:30 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:30 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:31 rivo volumio[3502]: info: Clearing queue after UPNP request Sep 14 14:37:31 rivo volumio[3502]: info: CoreStateMachine::ClearQueue Sep 14 14:37:31 rivo volumio[3502]: info: CoreStateMachine::stop Sep 14 14:37:31 rivo volumio[3502]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 14:37:31 rivo volumio[3502]: info: CorePlayQueue::clearPlayQueue Sep 14 14:37:31 rivo volumio[3502]: info: CorePlayQueue::saveQueue Sep 14 14:37:31 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:31 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:31 rivo volumio[3502]: info: CorePlayQueue::getTrack 0 Sep 14 14:37:31 rivo volumio[3502]: info: CoreCommandRouter::volumioPushQueue Sep 14 14:37:31 rivo volumio[3502]: info: Sep 14 14:37:31 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:31 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:31 rivo volumio[3502]: info: Sep 14 14:37:31 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:31 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:31 rivo volumio[3502]: error: updateQueue error: null Sep 14 14:37:31 rivo volumio[3502]: error: updateQueue error: null Sep 14 14:37:31 rivo volumio[3502]: info: ------------------------------ 7ms Sep 14 14:37:31 rivo volumio[3502]: info: ------------------------------ 5ms Sep 14 14:37:31 rivo volumio[3502]: info: Starting UPNP Playback Sep 14 14:37:31 rivo volumio[3502]: info: Preparing playback through UPNP Sep 14 14:37:31 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:31 rivo volumio[3502]: info: CorePlayQueue::getTrack 0 Sep 14 14:37:31 rivo volumio[3502]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 14:37:32 rivo volumio[3502]: info: Sep 14 14:37:32 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:32 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:32 rivo volumio[3502]: info: Sep 14 14:37:32 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:32 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:32 rivo volumio[3502]: info: ------------------------------ 4ms Sep 14 14:37:32 rivo volumio[3502]: info: ------------------------------ 2ms Sep 14 14:37:32 rivo volumio[3502]: info: Sep 14 14:37:32 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:32 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:32 rivo volumio[3502]: info: Sep 14 14:37:32 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:32 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:32 rivo volumio[3502]: info: Sep 14 14:37:32 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:32 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:32 rivo volumio[3502]: info: ------------------------------ 5ms Sep 14 14:37:32 rivo volumio[3502]: info: ------------------------------ 4ms Sep 14 14:37:32 rivo volumio[3502]: info: Sep 14 14:37:32 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:32 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:32 rivo volumio[3502]: info: ------------------------------ 4ms Sep 14 14:37:32 rivo volumio[3502]: info: ------------------------------ 3ms Sep 14 14:37:32 rivo volumio[3502]: info: Sep 14 14:37:32 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:32 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:32 rivo volumio[3502]: info: Sep 14 14:37:32 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:32 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:32 rivo volumio[3502]: info: ------------------------------ 14ms Sep 14 14:37:32 rivo volumio[3502]: info: ------------------------------ 12ms Sep 14 14:37:32 rivo volumio[3502]: info: Sep 14 14:37:32 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:32 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:32 rivo volumio[3502]: info: Sep 14 14:37:32 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:32 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:32 rivo volumio[3502]: info: ------------------------------ 4ms Sep 14 14:37:32 rivo volumio[3502]: info: ------------------------------ 3ms Sep 14 14:37:32 rivo volumio[3502]: info: Sep 14 14:37:32 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:32 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:32 rivo volumio[3502]: info: Sep 14 14:37:32 rivo volumio[3502]: ---------------------------- MPD announces system playlist update Sep 14 14:37:32 rivo volumio[3502]: info: Ignoring MPD Status Update Sep 14 14:37:32 rivo volumio[3502]: info: ------------------------------ 5ms Sep 14 14:37:32 rivo volumio[3502]: info: ------------------------------ 2ms Sep 14 14:37:33 rivo volumio[3502]: info: Sep 14 14:37:33 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:33 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:33 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:33 rivo volumio[3502]: info: Sep 14 14:37:33 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:33 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:33 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:33 rivo volumio[3502]: info: Sep 14 14:37:33 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:33 rivo volumio[3502]: info: sendMpdCommand status took 31 milliseconds Sep 14 14:37:33 rivo volumio[3502]: info: sendMpdCommand status took 29 milliseconds Sep 14 14:37:33 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:33 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:33 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:33 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:33 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:33 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:33 rivo volumio[3502]: info: Sep 14 14:37:33 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:33 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:33 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:33 rivo volumio[3502]: info: sendMpdCommand status took 10 milliseconds Sep 14 14:37:33 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 14 14:37:33 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 14 14:37:33 rivo volumio[3502]: info: sendMpdCommand status took 7 milliseconds Sep 14 14:37:33 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:33 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:33 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:33 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:33 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:33 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:33 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:33 rivo volumio[3502]: verbose: In UPNP mode Sep 14 14:37:33 rivo volumio[3502]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":290,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:33 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::syncState stateService play Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus stop Sep 14 14:37:33 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:33 rivo volumio[3502]: verbose: In UPNP mode Sep 14 14:37:33 rivo volumio[3502]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":290,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:33 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::syncState stateService play Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus play Sep 14 14:37:33 rivo volumio[3502]: info: Received an update from plugin. extracting info from payload Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:33 rivo volumio[3502]: info: ------------------------------ 72ms Sep 14 14:37:33 rivo volumio[3502]: info: ------------------------------ 88ms Sep 14 14:37:33 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 45 milliseconds Sep 14 14:37:33 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 44 milliseconds Sep 14 14:37:33 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:33 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:33 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:33 rivo volumio[3502]: verbose: In UPNP mode Sep 14 14:37:33 rivo volumio[3502]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":290,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:33 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::syncState stateService play Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus play Sep 14 14:37:33 rivo volumio[3502]: info: Received an update from plugin. extracting info from payload Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:33 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:33 rivo volumio[3502]: verbose: In UPNP mode Sep 14 14:37:33 rivo volumio[3502]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":290,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:33 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::syncState stateService play Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus play Sep 14 14:37:33 rivo volumio[3502]: info: Received an update from plugin. extracting info from payload Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:33 rivo volumio[3502]: info: ------------------------------ 156ms Sep 14 14:37:33 rivo volumio[3502]: info: ------------------------------ 150ms Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:33 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:33 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:33 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:33 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:33 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:33 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:33 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:33 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:33 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:33 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:33 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:33 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:33 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:33 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:33 rivo volumio[3502]: info: MCU Signalled Playback Active Sep 14 14:37:35 rivo volumio[3502]: info: Sep 14 14:37:35 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:35 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:35 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:35 rivo volumio[3502]: info: Sep 14 14:37:35 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:35 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:35 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:35 rivo volumio[3502]: info: sendMpdCommand status took 4 milliseconds Sep 14 14:37:35 rivo volumio[3502]: info: sendMpdCommand status took 3 milliseconds Sep 14 14:37:35 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:35 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:35 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:35 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:35 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 14 14:37:35 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 14 14:37:35 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:35 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:35 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:35 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:35 rivo volumio[3502]: verbose: In UPNP mode Sep 14 14:37:35 rivo volumio[3502]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":290,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1407 Kbps","isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:35 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:35 rivo volumio[3502]: info: CoreStateMachine::syncState stateService play Sep 14 14:37:35 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus play Sep 14 14:37:35 rivo volumio[3502]: info: Received an update from plugin. extracting info from payload Sep 14 14:37:35 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 14 14:37:35 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:35 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 14:37:35 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:35 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:35 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:35 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:35 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:35 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:35 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:35 rivo volumio[3502]: verbose: In UPNP mode Sep 14 14:37:35 rivo volumio[3502]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":290,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1407 Kbps","isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:35 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:35 rivo volumio[3502]: info: CoreStateMachine::syncState stateService play Sep 14 14:37:35 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus play Sep 14 14:37:35 rivo volumio[3502]: info: Received an update from plugin. extracting info from payload Sep 14 14:37:35 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 14 14:37:35 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:35 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:35 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:35 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:35 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:35 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:35 rivo volumio[3502]: info: ------------------------------ 88ms Sep 14 14:37:35 rivo volumio[3502]: info: ------------------------------ 87ms Sep 14 14:37:35 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:35 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:35 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:35 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:35 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:35 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:35 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:35 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:35 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:35 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:35 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:35 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:35 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:35 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:35 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:35 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:39 rivo volumio[3502]: info: Sep 14 14:37:39 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:39 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:39 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:39 rivo volumio[3502]: info: Sep 14 14:37:39 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:39 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:39 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:39 rivo volumio[3502]: info: sendMpdCommand status took 7 milliseconds Sep 14 14:37:39 rivo volumio[3502]: info: sendMpdCommand status took 7 milliseconds Sep 14 14:37:39 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:39 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:39 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:39 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:39 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 14 14:37:39 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 14 14:37:39 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:39 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:39 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:39 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:39 rivo volumio[3502]: verbose: In UPNP mode Sep 14 14:37:39 rivo volumio[3502]: verbose: STATE SERVICE {"status":"play","position":0,"seek":194000,"duration":290,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1367 Kbps","isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:39 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:39 rivo volumio[3502]: info: CoreStateMachine::syncState stateService play Sep 14 14:37:39 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus play Sep 14 14:37:39 rivo volumio[3502]: info: Received an update from plugin. extracting info from payload Sep 14 14:37:39 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 14 14:37:39 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:39 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 14:37:39 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:39 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:39 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:39 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:39 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:39 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:39 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:39 rivo volumio[3502]: verbose: In UPNP mode Sep 14 14:37:39 rivo volumio[3502]: verbose: STATE SERVICE {"status":"play","position":0,"seek":194000,"duration":290,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1367 Kbps","isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:39 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:39 rivo volumio[3502]: info: CoreStateMachine::syncState stateService play Sep 14 14:37:39 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus play Sep 14 14:37:39 rivo volumio[3502]: info: Received an update from plugin. extracting info from payload Sep 14 14:37:39 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 14 14:37:39 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:39 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:39 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:39 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:39 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:39 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:39 rivo volumio[3502]: info: ------------------------------ 80ms Sep 14 14:37:39 rivo volumio[3502]: info: ------------------------------ 79ms Sep 14 14:37:39 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:39 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:39 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:39 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:39 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:39 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:39 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:39 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:39 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:39 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:39 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:39 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:39 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:39 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:39 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:39 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:43 rivo volumio[3502]: Upnp client error: Error: This socket has been ended by the other party Sep 14 14:37:43 rivo volumio[3502]: info: Sep 14 14:37:43 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:43 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:43 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:43 rivo volumio[3502]: info: Sep 14 14:37:43 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:43 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:43 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:43 rivo volumio[3502]: info: sendMpdCommand status took 4 milliseconds Sep 14 14:37:43 rivo volumio[3502]: info: sendMpdCommand status took 3 milliseconds Sep 14 14:37:43 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:43 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:43 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:43 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:43 rivo volumio[3502]: info: Sep 14 14:37:43 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:43 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 14 14:37:43 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 14 14:37:43 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:43 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:43 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:43 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:43 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:43 rivo volumio[3502]: verbose: In UPNP mode Sep 14 14:37:43 rivo volumio[3502]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102000,"duration":290,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1367 Kbps","isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:43 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::syncState stateService play Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus play Sep 14 14:37:43 rivo volumio[3502]: info: Received an update from plugin. extracting info from payload Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:43 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:43 rivo volumio[3502]: verbose: In UPNP mode Sep 14 14:37:43 rivo volumio[3502]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102000,"duration":290,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1367 Kbps","isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:43 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::syncState stateService play Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus play Sep 14 14:37:43 rivo volumio[3502]: info: Received an update from plugin. extracting info from payload Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:43 rivo volumio[3502]: info: ------------------------------ 71ms Sep 14 14:37:43 rivo volumio[3502]: info: ------------------------------ 70ms Sep 14 14:37:43 rivo volumio[3502]: info: Sep 14 14:37:43 rivo volumio[3502]: ---------------------------- MPD announces state update: player Sep 14 14:37:43 rivo volumio[3502]: info: ControllerMpd::getState Sep 14 14:37:43 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand status Sep 14 14:37:43 rivo volumio[3502]: info: sendMpdCommand status took 67 milliseconds Sep 14 14:37:43 rivo volumio[3502]: info: sendMpdCommand status took 5 milliseconds Sep 14 14:37:43 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:43 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:43 rivo volumio[3502]: verbose: ControllerMpd::parseState Sep 14 14:37:43 rivo volumio[3502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:43 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:43 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:43 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:43 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:43 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:43 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:43 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:43 rivo volumio[3502]: info: Signalling Playback active due to playback status change Sep 14 14:37:43 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 26 milliseconds Sep 14 14:37:43 rivo volumio[3502]: info: sendMpdCommand playlistinfo took 26 milliseconds Sep 14 14:37:43 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:43 rivo volumio[3502]: verbose: ControllerMpd::parseTrackInfo Sep 14 14:37:43 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:43 rivo volumio[3502]: verbose: In UPNP mode Sep 14 14:37:43 rivo volumio[3502]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":102000,"duration":290,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1367 Kbps","isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:43 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::syncState stateService pause Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus play Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::stPlaybackTimer Sep 14 14:37:43 rivo volumio[3502]: info: ControllerMpd::pushState Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::servicePushState Sep 14 14:37:43 rivo volumio[3502]: verbose: In UPNP mode Sep 14 14:37:43 rivo volumio[3502]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":102000,"duration":290,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1367 Kbps","isStreaming":false,"title":"道化師のソネット","artist":"歌心りえ","album":"SONGS","uri":"http://192.168.0.7:13351/qobuz/325802217.flac","trackType":"qobuz"} Sep 14 14:37:43 rivo volumio[3502]: verbose: CURRENT POSITION 0 Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::syncState stateService pause Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::syncState currentStatus play Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::stPlaybackTimer Sep 14 14:37:43 rivo volumio[3502]: info: ------------------------------ 129ms Sep 14 14:37:43 rivo volumio[3502]: info: ------------------------------ 68ms Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:43 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:43 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:43 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:43 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:43 rivo volumio[3502]: info: MCU Signalled Playback Inactive Sep 14 14:37:52 rivo volumio[3502]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Sep 14 14:37:52 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 14 14:37:52 rivo volumio[3502]: info: Preparing to save Alsa Options, stopping services first Sep 14 14:37:52 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:52 rivo volumio[3502]: info: CoreCommandRouter::volumioPause Sep 14 14:37:52 rivo volumio[3502]: info: CoreStateMachine::pause Sep 14 14:37:52 rivo volumio[3502]: info: CoreStateMachine::stPlaybackTimer Sep 14 14:37:52 rivo volumio[3502]: info: CoreStateMachine::servicePause Sep 14 14:37:52 rivo volumio[3502]: info: CorePlayQueue::getTrack 0 Sep 14 14:37:52 rivo volumio[3502]: info: Error: no service or no trackblock to pause Sep 14 14:37:52 rivo volumio[3502]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Accuphase USB Audio Product7"}} Sep 14 14:37:52 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 14 14:37:52 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 14 14:37:52 rivo volumio[3502]: info: Setting mixer PCM for card Accuphase USB Audio Product7 Sep 14 14:37:52 rivo volumio[3502]: info: QobuzConnect: setDeactiveState invoked Sep 14 14:37:52 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:52 rivo volumio[3502]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 14 14:37:52 rivo volumio[3502]: info: Updating Volume Controller Parameters: Device: 5 Name: Accuphase USB Audio Product7 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 14 14:37:52 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 14 14:37:52 rivo volumio[3502]: info: Disabling external Volume Control Sep 14 14:37:52 rivo volumio[3502]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 14:37:52 rivo volumio[3502]: info: Preparing to generate the ALSA configuration file Sep 14 14:37:52 rivo sudo[5117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 14 14:37:52 rivo sudo[5117]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 14:37:52 rivo sudo[5117]: pam_unix(sudo:session): session closed for user root Sep 14 14:37:53 rivo sudo[5121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 14 14:37:53 rivo sudo[5121]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 14:37:53 rivo qobuz-connect[4664]: 20250914 14:37:53.039 [4664.4664] INFO ContentFetcher: [0xac8d7720]: Canceling download Sep 14 14:37:53 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 14 14:37:53 rivo qobuz-connect[4664]: 20250914 14:37:53.047 [4664.4664] INFO CloudClient: [0xac75c3b0]: Disconnecting from the cloud Sep 14 14:37:53 rivo qobuz-connect[4664]: 20250914 14:37:53.047 [4664.4664] INFO CloudClient: [0xac75c3b0]: Connection closed Sep 14 14:37:53 rivo qobuz-connect[4664]: 20250914 14:37:53.065 [4664.4664] INFO SampleApp: Stopping Local configuration server Sep 14 14:37:53 rivo qobuz-connect[4664]: 20250914 14:37:53.076 [4664.4664] INFO SampleApp: shat down connection on UNIX socket Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 14 14:37:53 rivo systemd[1]: qobuz-connect.service: Succeeded. Sep 14 14:37:53 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 14:37:53 rivo systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 14:37:53 rivo sudo[5121]: pam_unix(sudo:session): session closed for user root Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 14 14:37:53 rivo volumio[3502]: info: Asound.conf file unchanged, so no further update is needed Sep 14 14:37:53 rivo volumio[3502]: info: Output device has changed, restarting MPD Sep 14 14:37:53 rivo sudo[5139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 14:37:53 rivo volumio[3502]: info: QobuzConnect: setDeactiveState invoked Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 14:37:53 rivo sudo[5139]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 14:37:53 rivo sudo[5139]: pam_unix(sudo:session): session closed for user root Sep 14 14:37:53 rivo sudo[5142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 14:37:53 rivo sudo[5142]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 14:37:53 rivo systemd[1]: Stopping Music Player Daemon... Sep 14 14:37:53 rivo sudo[5150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 14 14:37:53 rivo sudo[5150]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 14:37:53 rivo volumio[3502]: info: MPD Permissions set Sep 14 14:37:53 rivo volumio[3502]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 14 14:37:53 rivo volumio[3502]: info: QobuzConnect: setDeactiveState invoked Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:53 rivo volumio[3502]: info: VolumeController:: Volume=100 Mute =false Sep 14 14:37:53 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:53 rivo sudo[5150]: pam_unix(sudo:session): session closed for user root Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:53 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:53 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:53 rivo volumio[3502]: info: Executing endpoint qc_getconfig Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 14:37:53 rivo qobuz-connect[5128]: 20250914 14:37:53.373 [5128.5128] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 14:37:53 rivo qobuz-connect[5128]: 20250914 14:37:53.384 [5128.5128] INFO VolumeManager: [0xac39f240]: Setting new playback volume: 75 Sep 14 14:37:53 rivo qobuz-connect[5128]: 20250914 14:37:53.384 [5128.5128] INFO VolumeManager: [0xac39f240]: Setting new mute state: 0 Sep 14 14:37:53 rivo qobuz-connect[5128]: 20250914 14:37:53.384 [5128.5128] INFO QobuzConnect: [0xac39fc10]: Client initialized! Sep 14 14:37:53 rivo qobuz-connect[5128]: 20250914 14:37:53.384 [5128.5128] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Sep 14 14:37:53 rivo sudo[5152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 14 14:37:53 rivo sudo[5152]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 14:37:53 rivo qobuz-connect[5128]: 20250914 14:37:53.412 [5128.5128] INFO LocalConfigManager: [0xac39eb20]: Starting Local Configuration server Sep 14 14:37:53 rivo qobuz-connect[5128]: 20250914 14:37:53.413 [5128.5128] INFO SampleApp: Starting Local configuration server Sep 14 14:37:53 rivo qobuz-connect[5128]: 20250914 14:37:53.413 [5128.5128] INFO SampleApp: Connected to UNIX socket client 0xac394ed8 Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 14 14:37:53 rivo qobuz-connect[5128]: 20250914 14:37:53.421 [5128.5128] INFO SampleApp: Stopping Local configuration server Sep 14 14:37:53 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 14 14:37:53 rivo qobuz-connect[5128]: 20250914 14:37:53.431 [5128.5128] INFO SampleApp: shat down connection on UNIX socket Sep 14 14:37:53 rivo systemd[1]: qobuz-connect.service: Succeeded. Sep 14 14:37:53 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 14:37:53 rivo systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 14:37:53 rivo volumio[3502]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 14 14:37:53 rivo volumio[3502]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 14 14:37:53 rivo sudo[5152]: pam_unix(sudo:session): session closed for user root Sep 14 14:37:53 rivo volumio[3502]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 14 14:37:53 rivo volumio[3502]: info: Preparing to save Alsa Options, stopping services first Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::volumioPause Sep 14 14:37:53 rivo volumio[3502]: info: CoreStateMachine::pause Sep 14 14:37:53 rivo volumio[3502]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Accuphase USB Audio Product7"}} Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 14 14:37:53 rivo volumio[3502]: info: Setting mixer PCM for card Accuphase USB Audio Product7 Sep 14 14:37:53 rivo volumio[3502]: info: QobuzConnect: setDeactiveState invoked Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 14 14:37:53 rivo volumio[3502]: info: Updating Volume Controller Parameters: Device: 5 Name: Accuphase USB Audio Product7 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 14 14:37:53 rivo volumio[3502]: info: Disabling external Volume Control Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 14:37:53 rivo volumio[3502]: info: Preparing to generate the ALSA configuration file Sep 14 14:37:53 rivo volumio[3502]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 14 14:37:53 rivo volumio[3502]: info: QobuzConnect: setDeactiveState invoked Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:53 rivo sudo[5176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 14 14:37:53 rivo sudo[5176]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 14:37:53 rivo volumio[3502]: info: Executing endpoint qc_getconfig Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 14:37:53 rivo qobuz-connect[5161]: 20250914 14:37:53.868 [5161.5161] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 14:37:53 rivo qobuz-connect[5161]: 20250914 14:37:53.875 [5161.5161] INFO VolumeManager: [0xad074240]: Setting new playback volume: 75 Sep 14 14:37:53 rivo qobuz-connect[5161]: 20250914 14:37:53.875 [5161.5161] INFO VolumeManager: [0xad074240]: Setting new mute state: 0 Sep 14 14:37:53 rivo qobuz-connect[5161]: 20250914 14:37:53.876 [5161.5161] INFO QobuzConnect: [0xad074c10]: Client initialized! Sep 14 14:37:53 rivo qobuz-connect[5161]: 20250914 14:37:53.876 [5161.5161] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Sep 14 14:37:53 rivo qobuz-connect[5161]: 20250914 14:37:53.891 [5161.5161] INFO LocalConfigManager: [0xad073b20]: Starting Local Configuration server Sep 14 14:37:53 rivo qobuz-connect[5161]: 20250914 14:37:53.892 [5161.5161] INFO SampleApp: Starting Local configuration server Sep 14 14:37:53 rivo qobuz-connect[5161]: 20250914 14:37:53.893 [5161.5161] INFO SampleApp: Connected to UNIX socket client 0xad069ed8 Sep 14 14:37:53 rivo sudo[5176]: pam_unix(sudo:session): session closed for user root Sep 14 14:37:53 rivo sudo[5180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 14 14:37:53 rivo sudo[5180]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 14:37:53 rivo qobuz-connect[5161]: 20250914 14:37:53.957 [5161.5161] INFO SampleApp: Stopping Local configuration server Sep 14 14:37:53 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 14 14:37:53 rivo qobuz-connect[5161]: 20250914 14:37:53.967 [5161.5161] INFO SampleApp: shat down connection on UNIX socket Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 14:37:53 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 14 14:37:53 rivo systemd[1]: qobuz-connect.service: Succeeded. Sep 14 14:37:53 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 14:37:53 rivo systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 14:37:53 rivo sudo[5180]: pam_unix(sudo:session): session closed for user root Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 14 14:37:54 rivo volumio[3502]: info: Asound.conf file unchanged, so no further update is needed Sep 14 14:37:54 rivo volumio[3502]: info: Output device has changed, restarting MPD Sep 14 14:37:54 rivo sudo[5190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 14 14:37:54 rivo sudo[5190]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 14:37:54 rivo volumio[3502]: info: QobuzConnect: setDeactiveState invoked Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 14:37:54 rivo sudo[5190]: pam_unix(sudo:session): session closed for user root Sep 14 14:37:54 rivo sudo[5193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 14 14:37:54 rivo sudo[5193]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 14:37:54 rivo volumio[3502]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 14 14:37:54 rivo volumio[3502]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 14 14:37:54 rivo sudo[5202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 14 14:37:54 rivo sudo[5202]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 14:37:54 rivo volumio[3502]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 14 14:37:54 rivo volumio[3502]: info: QobuzConnect: setDeactiveState invoked Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:54 rivo volumio[3502]: info: MPD Permissions set Sep 14 14:37:54 rivo volumio[3502]: info: VolumeController:: Volume=100 Mute =false Sep 14 14:37:54 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:54 rivo sudo[5202]: pam_unix(sudo:session): session closed for user root Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:54 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:54 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:54 rivo sudo[5204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 14 14:37:54 rivo sudo[5204]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 14:37:54 rivo volumio[3502]: info: Executing endpoint qc_getconfig Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 14:37:54 rivo qobuz-connect[5185]: 20250914 14:37:54.268 [5185.5185] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 14:37:54 rivo qobuz-connect[5185]: 20250914 14:37:54.280 [5185.5185] INFO VolumeManager: [0xab485240]: Setting new playback volume: 75 Sep 14 14:37:54 rivo qobuz-connect[5185]: 20250914 14:37:54.280 [5185.5185] INFO VolumeManager: [0xab485240]: Setting new mute state: 0 Sep 14 14:37:54 rivo qobuz-connect[5185]: 20250914 14:37:54.280 [5185.5185] INFO QobuzConnect: [0xab485c10]: Client initialized! Sep 14 14:37:54 rivo qobuz-connect[5185]: 20250914 14:37:54.280 [5185.5185] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Sep 14 14:37:54 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 14 14:37:54 rivo qobuz-connect[5185]: 20250914 14:37:54.304 [5185.5185] INFO LocalConfigManager: [0xab484b20]: Starting Local Configuration server Sep 14 14:37:54 rivo qobuz-connect[5185]: 20250914 14:37:54.304 [5185.5185] INFO SampleApp: Starting Local configuration server Sep 14 14:37:54 rivo qobuz-connect[5185]: 20250914 14:37:54.306 [5185.5185] INFO SampleApp: Stopping Local configuration server Sep 14 14:37:54 rivo qobuz-connect[5185]: 20250914 14:37:54.317 [5185.5185] INFO SampleApp: shat down connection on UNIX socket Sep 14 14:37:54 rivo qobuz-connect[5185]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 14:37:54 rivo systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 14:37:54 rivo systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 14:37:54 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 14 14:37:54 rivo systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 14:37:54 rivo sudo[5204]: pam_unix(sudo:session): session closed for user root Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 14 14:37:54 rivo volumio[3502]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 14 14:37:54 rivo volumio[3502]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 14 14:37:54 rivo volumio[3502]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 14 14:37:54 rivo volumio[3502]: info: QobuzConnect: setDeactiveState invoked Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:54 rivo volumio[3502]: info: Executing endpoint qc_getconfig Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 14:37:54 rivo qobuz-connect[5211]: 20250914 14:37:54.423 [5211.5211] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 14:37:54 rivo volumio[3502]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 14 14:37:54 rivo volumio[3502]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 14 14:37:54 rivo qobuz-connect[5211]: 20250914 14:37:54.429 [5211.5211] INFO VolumeManager: [0xac822240]: Setting new playback volume: 75 Sep 14 14:37:54 rivo qobuz-connect[5211]: 20250914 14:37:54.430 [5211.5211] INFO VolumeManager: [0xac822240]: Setting new mute state: 0 Sep 14 14:37:54 rivo qobuz-connect[5211]: 20250914 14:37:54.430 [5211.5211] INFO QobuzConnect: [0xac822c10]: Client initialized! Sep 14 14:37:54 rivo qobuz-connect[5211]: 20250914 14:37:54.430 [5211.5211] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Sep 14 14:37:54 rivo qobuz-connect[5211]: 20250914 14:37:54.441 [5211.5211] INFO LocalConfigManager: [0xac821b20]: Starting Local Configuration server Sep 14 14:37:54 rivo qobuz-connect[5211]: 20250914 14:37:54.441 [5211.5211] INFO SampleApp: Starting Local configuration server Sep 14 14:37:54 rivo qobuz-connect[5211]: 20250914 14:37:54.442 [5211.5211] INFO SampleApp: Connected to UNIX socket client 0xac817ed8 Sep 14 14:37:54 rivo qobuz-connect[5211]: 20250914 14:37:54.573 [5211.5211] INFO SampleApp: Playback volume changed: 75 Sep 14 14:37:54 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:56 rivo volumio[3502]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 14 14:37:56 rivo volumio[3502]: info: CoreStateMachine::getcurrentVolume Sep 14 14:37:56 rivo volumio[3502]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 14:37:56 rivo volumio[3502]: info: VolumeController:: Volume=100 Mute =false Sep 14 14:37:56 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:56 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:56 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:56 rivo volumio[3502]: info: CoreStateMachine::updateTrackBlock Sep 14 14:37:56 rivo volumio[3502]: info: CorePlayQueue::getTrackBlock Sep 14 14:37:56 rivo volumio[3502]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 14:37:56 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:56 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:56 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:57 rivo volumio[3502]: info: VolumeController:: Volume=100 Mute =false Sep 14 14:37:57 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:57 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:57 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:57 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:57 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:57 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:57 rivo volumio[3502]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 14 14:37:57 rivo volumio[3502]: info: CoreStateMachine::getcurrentVolume Sep 14 14:37:57 rivo volumio[3502]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 14:37:57 rivo volumio[3502]: info: VolumeController:: Volume=100 Mute =false Sep 14 14:37:57 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:57 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:57 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:57 rivo volumio[3502]: info: CoreStateMachine::updateTrackBlock Sep 14 14:37:57 rivo volumio[3502]: info: CorePlayQueue::getTrackBlock Sep 14 14:37:57 rivo volumio[3502]: info: CoreCommandRouter::volumioRetrievevolume Sep 14 14:37:57 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:57 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:57 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:37:57 rivo volumio[3502]: info: VolumeController:: Volume=100 Mute =false Sep 14 14:37:57 rivo volumio[3502]: info: CoreStateMachine::pushState Sep 14 14:37:57 rivo volumio[3502]: info: CoreCommandRouter::volumioPushState Sep 14 14:37:57 rivo volumio[3502]: info: CoreCommandRouter::volumioGetState Sep 14 14:37:57 rivo volumio[3502]: info: CoreCommandRouter::volumioGetQueue Sep 14 14:37:57 rivo volumio[3502]: info: CoreStateMachine::getQueue Sep 14 14:37:57 rivo volumio[3502]: info: CorePlayQueue::getQueue Sep 14 14:38:02 rivo volumio[3502]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 14:38:02 rivo volumio[3502]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 14:38:02 rivo volumio[3502]: info: Retrieving Cloud Streaming UI Sep 14 14:38:02 rivo volumio[3502]: info: Getting Tidal Cloud Configuration Sep 14 14:38:02 rivo volumio[3502]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 14:38:02 rivo volumio[3502]: info: Getting Qobuz Cloud Configuration Sep 14 14:38:02 rivo volumio[3502]: info: Asking plugin for UI Config Sep 14 14:38:02 rivo volumio[3502]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 14:38:02 rivo volumio[3502]: info: Getting Spotify Cloud Configuration Sep 14 14:38:02 rivo volumio[3502]: info: Asking plugin for UI Config Sep 14 14:38:02 rivo volumio[3502]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 14:38:02 rivo volumio[3502]: info: Saving Spotify Acccount Sep 14 14:38:02 rivo volumio[3502]: info: Got it Sep 14 14:38:02 rivo volumio[3502]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 14 14:38:02 rivo volumio[3502]: info: Got Tidal Cloud Configuration Sep 14 14:38:02 rivo volumio[3502]: info: Got it Sep 14 14:38:02 rivo volumio[3502]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 14:38:02 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 14 14:38:02 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 14 14:38:02 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 14 14:38:02 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 14 14:38:02 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 14:38:02 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 14:38:02 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 14:38:02 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 14:38:02 rivo volumio[3502]: info: CoreCommandRouter::volumioGetBrowseSources Sep 14 14:38:02 rivo volumio[3502]: info: CoreCommandRouter::volumioGetBrowseSources Sep 14 14:38:02 rivo volumio[3502]: info: CoreCommandRouter::volumioGetBrowseSources Sep 14 14:38:03 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 14:38:03 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 14 14:38:07 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 14:38:08 rivo volumio[3502]: info: Disabling MyMusic plugin upnp Sep 14 14:38:08 rivo sudo[5331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 14 14:38:08 rivo sudo[5331]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 14:38:08 rivo volumio[3502]: error: Upnp client error: Error: This socket has been ended by the other party Sep 14 14:38:08 rivo systemd[1]: Stopping UPnP Renderer front-end to MPD... Sep 14 14:38:08 rivo volumio[3502]: info: Enabling MyMusic plugin upnp Sep 14 14:38:08 rivo volumio[3502]: info: Enabling plugin upnp Sep 14 14:38:08 rivo volumio[3502]: info: Loading plugin "upnp"... Sep 14 14:38:08 rivo volumio[3502]: info: [1757828288703] Starting Upmpd Daemon Sep 14 14:38:08 rivo volumio[3502]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 14:38:08 rivo volumio[3502]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 14:38:08 rivo volumio[3502]: Error: listen EADDRINUSE: address already in use :::6599 Sep 14 14:38:08 rivo volumio[3502]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Sep 14 14:38:08 rivo volumio[3502]: at listenInCluster (net.js:1379:12) Sep 14 14:38:08 rivo volumio[3502]: at Server.listen (net.js:1465:7) Sep 14 14:38:08 rivo volumio[3502]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Sep 14 14:38:08 rivo volumio[3502]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Sep 14 14:38:08 rivo volumio[3502]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Sep 14 14:38:08 rivo volumio[3502]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 14 14:38:08 rivo volumio[3502]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Sep 14 14:38:08 rivo volumio[3502]: code: 'EADDRINUSE', Sep 14 14:38:08 rivo volumio[3502]: errno: -98, Sep 14 14:38:08 rivo volumio[3502]: syscall: 'listen', Sep 14 14:38:08 rivo volumio[3502]: address: '::', Sep 14 14:38:08 rivo volumio[3502]: port: 6599 Sep 14 14:38:08 rivo volumio[3502]: } Sep 14 14:38:08 rivo volumio[3502]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 14:38:09 rivo sudo[5344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 14:37 Sep 14 14:38:09 rivo sudo[5344]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:43:09 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="fc7d09bc07ffd188bff4808d8f79c746"