-- Logs begin at Sun 2026-03-22 16:54:26 JST, end at Wed 2026-03-25 23:58:18 JST. -- Mar 25 23:57:00 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:00.359+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:57:04 primo-plus volumio[1044]: Searching plugin music_service/webradio Mar 25 23:57:04 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 25 23:57:04 primo-plus volumio[1044]: info: SHOUTCASTのクエリ中にエラーが発生しました Mar 25 23:57:04 primo-plus volumio[1044]: info: All search sources collected, pushing search results Mar 25 23:57:04 primo-plus volumio[1044]: Searching plugin music_service/webradio Mar 25 23:57:04 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 25 23:57:04 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:04.872+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:57:05 primo-plus volumio[1044]: info: All search sources collected, pushing search results Mar 25 23:57:05 primo-plus volumio[1044]: Searching plugin music_service/webradio Mar 25 23:57:05 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 25 23:57:06 primo-plus volumio[1044]: Searching plugin music_service/webradio Mar 25 23:57:06 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 25 23:57:06 primo-plus volumio[1044]: info: All search sources collected, pushing search results Mar 25 23:57:07 primo-plus volumio[1044]: info: All search sources collected, pushing search results Mar 25 23:57:07 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:07.880+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:57:11 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:11.187+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:57:12 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:12.091+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:57:13 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:13.514+09:00 level=INFO msg="new address was allocated" component=ble/conn old=84 new=85 Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.075+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:57:16 primo-plus volumio[1044]: info: Mar 25 23:57:16 primo-plus volumio[1044]: ---------------------------- MPD announces state update: player Mar 25 23:57:16 primo-plus volumio[1044]: info: ControllerMpd::getState Mar 25 23:57:16 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:57:16 primo-plus volumio[1044]: info: Mar 25 23:57:16 primo-plus volumio[1044]: ---------------------------- MPD announces state update: player Mar 25 23:57:16 primo-plus volumio[1044]: info: ControllerMpd::getState Mar 25 23:57:16 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:57:16 primo-plus volumio[1044]: info: Mar 25 23:57:16 primo-plus volumio[1044]: ---------------------------- MPD announces state update: player Mar 25 23:57:16 primo-plus volumio[1044]: info: ControllerMpd::getState Mar 25 23:57:16 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:57:16 primo-plus volumio[1044]: info: sendMpdCommand status took 5 milliseconds Mar 25 23:57:16 primo-plus volumio[1044]: info: sendMpdCommand status took 3 milliseconds Mar 25 23:57:16 primo-plus volumio[1044]: verbose: ControllerMpd::parseState Mar 25 23:57:16 primo-plus volumio[1044]: verbose: ControllerMpd::parseState Mar 25 23:57:16 primo-plus volumio[1044]: info: ControllerMpd::pushState Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreCommandRouter::servicePushState Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:16 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:16 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:16 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 13 Mar 25 23:57:16 primo-plus volumio[1044]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 23:57:16 primo-plus volumio[1044]: verbose: CURRENT POSITION 13 Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreStateMachine::syncState stateService stop Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreStateMachine::play index undefined Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:16 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:16 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:16 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:16 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:16 primo-plus volumio[1044]: info: ControllerMpd::pushState Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreCommandRouter::servicePushState Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:16 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:16 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:16 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:16 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:16 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:16 primo-plus volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.913+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=30799 volume=35 Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.913+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=30799 volume=35 Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.914+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_STOPPED positionMs= volume=35 Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.914+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/6006470 title="So' Tinha Ser Com Voce" Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.914+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_STOPPED positionMs= volume=35 Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.914+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/6006470 title="So' Tinha Ser Com Voce" Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.914+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.914+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_STOPPED positionMs= volume=35 Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.915+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.915+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_STOPPED positionMs= volume=35 Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.915+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.916+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:16 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreStateMachine::startPlaybackTimer Mar 25 23:57:16 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:16 primo-plus volumio[1044]: info: [1774450636924] ControllerQobuz::clearAddPlayTrack Mar 25 23:57:16 primo-plus volumio[1044]: info: ------------------------------ 42ms Mar 25 23:57:16 primo-plus volumio[1044]: info: ------------------------------ 42ms Mar 25 23:57:16 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:16 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:16 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:16 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:16 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.937+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_STOPPED positionMs=0 volume=35 Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.938+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_STOPPED positionMs=0 volume=35 Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.938+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.938+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:16 primo-plus volumio[1044]: info: sendMpdCommand status took 53 milliseconds Mar 25 23:57:16 primo-plus volumio[1044]: info: sendMpdCommand stop took 14 milliseconds Mar 25 23:57:16 primo-plus volumio[1044]: verbose: ControllerMpd::parseState Mar 25 23:57:16 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 23:57:16 primo-plus volumio[1044]: info: ControllerMpd::pushState Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreCommandRouter::servicePushState Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:16 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:16 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:16 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:16 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:16 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:16 primo-plus volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.949+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_STOPPED positionMs=16 volume=35 Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.950+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_STOPPED positionMs=16 volume=35 Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.950+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:16.950+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:16 primo-plus volumio[1044]: info: ------------------------------ 71ms Mar 25 23:57:16 primo-plus volumio[1044]: info: Signalling Playback active due to playback status change Mar 25 23:57:16 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:16 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:16 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:16 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:16 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:16 primo-plus volumio[1044]: info: Mar 25 23:57:16 primo-plus volumio[1044]: ---------------------------- MPD announces system playlist update Mar 25 23:57:16 primo-plus volumio[1044]: info: Ignoring MPD Status Update Mar 25 23:57:16 primo-plus volumio[1044]: info: sendMpdCommand clear took 39 milliseconds Mar 25 23:57:16 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/76339122" Mar 25 23:57:16 primo-plus volumio[1044]: info: Mar 25 23:57:16 primo-plus volumio[1044]: ---------------------------- MPD announces system playlist update Mar 25 23:57:16 primo-plus volumio[1044]: info: Ignoring MPD Status Update Mar 25 23:57:16 primo-plus volumio[1044]: info: Mar 25 23:57:16 primo-plus volumio[1044]: ---------------------------- MPD announces system playlist update Mar 25 23:57:16 primo-plus volumio[1044]: info: Ignoring MPD Status Update Mar 25 23:57:16 primo-plus volumio[1044]: STREAMING PROXY: Handling url /?data=qobuz://song/76339122 Mar 25 23:57:16 primo-plus volumio[1044]: error: updateQueue error: null Mar 25 23:57:16 primo-plus volumio[1044]: info: ------------------------------ 4ms Mar 25 23:57:16 primo-plus volumio[1044]: info: Executing endpoint getStreamUrlqobuz Mar 25 23:57:16 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 25 23:57:17 primo-plus volumio[1044]: info: MCU Signalled Playback Inactive Mar 25 23:57:17 primo-plus volumio[1044]: info: getStreamUrl took 471 milliseconds Mar 25 23:57:17 primo-plus volumio[1044]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=11307679&eid=76339122&fmt=7&profile=raw&range=20-30&app_id=539451548&etsp=1774537037&hmac=mVVQ3eNH9eDM6RmmNmtcxS4Zb-E Mar 25 23:57:17 primo-plus volumio[1044]: STREAMING PROXY: Requesting upstream with range: none Mar 25 23:57:17 primo-plus volumio[1044]: BT PLUGIN MESSAGE: Duration is 162000 Mar 25 23:57:18 primo-plus volumio[1044]: info: Executing endpoint metavolumio Mar 25 23:57:18 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 25 23:57:18 primo-plus volumio[1044]: info: Executing endpoint metavolumio Mar 25 23:57:18 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 25 23:57:18 primo-plus volumio[1044]: info: Executing endpoint metavolumio Mar 25 23:57:18 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 25 23:57:19 primo-plus volumio[1044]: STREAMING PROXY: Upstream status code: 200 Mar 25 23:57:19 primo-plus volumio[1044]: STREAMING PROXY: Client closed connection Mar 25 23:57:19 primo-plus volumio[1044]: STREAMING PROXY: Upstream closed. Received 15648 bytes Mar 25 23:57:19 primo-plus volumio[1044]: STREAMING PROXY: Incomplete transfer. Expected 11276622, got 15648 Mar 25 23:57:19 primo-plus volumio[1044]: STREAMING PROXY: Retrying with range: bytes=15648- (attempt 1) Mar 25 23:57:19 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/76339122" Mar 25 23:57:19 primo-plus volumio[1044]: error: updateQueue error: null Mar 25 23:57:19 primo-plus volumio[1044]: error: updateQueue error: null Mar 25 23:57:19 primo-plus volumio[1044]: info: Mar 25 23:57:19 primo-plus volumio[1044]: ---------------------------- MPD announces system playlist update Mar 25 23:57:19 primo-plus volumio[1044]: info: Ignoring MPD Status Update Mar 25 23:57:19 primo-plus volumio[1044]: info: ------------------------------ 2373ms Mar 25 23:57:19 primo-plus volumio[1044]: info: ------------------------------ 2372ms Mar 25 23:57:19 primo-plus volumio[1044]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/76339122" took 2 milliseconds Mar 25 23:57:19 primo-plus volumio[1044]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 23:57:19 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand play Mar 25 23:57:19 primo-plus volumio[1044]: Upnp client error: Error: This socket has been ended by the other party Mar 25 23:57:19 primo-plus volumio[1044]: STREAMING PROXY: Handling url /?data=qobuz://song/76339122 Mar 25 23:57:19 primo-plus volumio[1044]: info: Mar 25 23:57:19 primo-plus volumio[1044]: ---------------------------- MPD announces system playlist update Mar 25 23:57:19 primo-plus volumio[1044]: info: Ignoring MPD Status Update Mar 25 23:57:19 primo-plus volumio[1044]: info: Mar 25 23:57:19 primo-plus volumio[1044]: ---------------------------- MPD announces system playlist update Mar 25 23:57:19 primo-plus volumio[1044]: info: Ignoring MPD Status Update Mar 25 23:57:19 primo-plus volumio[1044]: info: ------------------------------ 7ms Mar 25 23:57:19 primo-plus volumio[1044]: info: sendMpdCommand play took 6 milliseconds Mar 25 23:57:19 primo-plus volumio[1044]: info: ------------------------------ 3ms Mar 25 23:57:19 primo-plus volumio[1044]: info: ------------------------------ 2ms Mar 25 23:57:19 primo-plus volumio[1044]: info: Executing endpoint getStreamUrlqobuz Mar 25 23:57:19 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 25 23:57:19 primo-plus volumio[1044]: info: getStreamUrl took 513 milliseconds Mar 25 23:57:19 primo-plus volumio[1044]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=11307679&eid=76339122&fmt=7&profile=raw&range=20-30&app_id=539451548&etsp=1774537039&hmac=KmzIATUMCVlU5PQ3OO6Lthy4eF4 Mar 25 23:57:19 primo-plus volumio[1044]: STREAMING PROXY: Requesting upstream with range: none Mar 25 23:57:19 primo-plus volumio[1044]: STREAMING PROXY: Upstream status code: 200 Mar 25 23:57:19 primo-plus volumio[1044]: info: Mar 25 23:57:19 primo-plus volumio[1044]: ---------------------------- MPD announces state update: player Mar 25 23:57:19 primo-plus volumio[1044]: info: ControllerMpd::getState Mar 25 23:57:19 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:57:19 primo-plus volumio[1044]: info: Mar 25 23:57:19 primo-plus volumio[1044]: ---------------------------- MPD announces state update: player Mar 25 23:57:19 primo-plus volumio[1044]: info: ControllerMpd::getState Mar 25 23:57:19 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:57:19 primo-plus volumio[1044]: info: Mar 25 23:57:19 primo-plus volumio[1044]: ---------------------------- MPD announces state update: player Mar 25 23:57:19 primo-plus volumio[1044]: info: ControllerMpd::getState Mar 25 23:57:19 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:57:19 primo-plus volumio[1044]: info: sendMpdCommand status took 8 milliseconds Mar 25 23:57:19 primo-plus volumio[1044]: verbose: ControllerMpd::parseState Mar 25 23:57:19 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:57:19 primo-plus volumio[1044]: info: Mar 25 23:57:19 primo-plus volumio[1044]: ---------------------------- MPD announces state update: player Mar 25 23:57:19 primo-plus volumio[1044]: info: ControllerMpd::getState Mar 25 23:57:19 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:57:19 primo-plus volumio[1044]: info: sendMpdCommand status took 9 milliseconds Mar 25 23:57:19 primo-plus volumio[1044]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 25 23:57:19 primo-plus volumio[1044]: verbose: ControllerMpd::parseState Mar 25 23:57:19 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:57:19 primo-plus volumio[1044]: verbose: ControllerMpd::parseState Mar 25 23:57:19 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:57:19 primo-plus volumio[1044]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:57:19 primo-plus volumio[1044]: info: ControllerMpd::pushState Mar 25 23:57:19 primo-plus volumio[1044]: info: CoreCommandRouter::servicePushState Mar 25 23:57:19 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:19 primo-plus volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"76339122","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/76339122","trackType":"qobuz"} Mar 25 23:57:19 primo-plus volumio[1044]: verbose: CURRENT POSITION 14 Mar 25 23:57:19 primo-plus volumio[1044]: info: CoreStateMachine::syncState stateService play Mar 25 23:57:19 primo-plus volumio[1044]: info: CoreStateMachine::syncState currentStatus stop Mar 25 23:57:19 primo-plus volumio[1044]: info: ------------------------------ 16ms Mar 25 23:57:19 primo-plus volumio[1044]: info: Mar 25 23:57:19 primo-plus volumio[1044]: ---------------------------- MPD announces state update: player Mar 25 23:57:19 primo-plus volumio[1044]: info: ControllerMpd::getState Mar 25 23:57:19 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:57:19 primo-plus volumio[1044]: info: Mar 25 23:57:19 primo-plus volumio[1044]: ---------------------------- MPD announces state update: player Mar 25 23:57:19 primo-plus volumio[1044]: info: ControllerMpd::getState Mar 25 23:57:19 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:57:20 primo-plus volumio[1044]: info: sendMpdCommand status took 15 milliseconds Mar 25 23:57:20 primo-plus volumio[1044]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 25 23:57:20 primo-plus volumio[1044]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 25 23:57:20 primo-plus volumio[1044]: info: sendMpdCommand status took 11 milliseconds Mar 25 23:57:20 primo-plus volumio[1044]: info: sendMpdCommand status took 10 milliseconds Mar 25 23:57:20 primo-plus volumio[1044]: verbose: ControllerMpd::parseState Mar 25 23:57:20 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:57:20 primo-plus volumio[1044]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:57:20 primo-plus volumio[1044]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:57:20 primo-plus volumio[1044]: verbose: ControllerMpd::parseState Mar 25 23:57:20 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:57:20 primo-plus volumio[1044]: verbose: ControllerMpd::parseState Mar 25 23:57:20 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:57:20 primo-plus volumio[1044]: info: ControllerMpd::pushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::servicePushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:20 primo-plus volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"76339122","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/76339122","trackType":"qobuz"} Mar 25 23:57:20 primo-plus volumio[1044]: verbose: CURRENT POSITION 14 Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::syncState stateService play Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:57:20 primo-plus volumio[1044]: info: Received an update from plugin. extracting info from payload Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:20 primo-plus volumio[1044]: info: ControllerMpd::pushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::servicePushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:20 primo-plus volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"76339122","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/76339122","trackType":"qobuz"} Mar 25 23:57:20 primo-plus volumio[1044]: verbose: CURRENT POSITION 14 Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::syncState stateService play Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:57:20 primo-plus volumio[1044]: info: Received an update from plugin. extracting info from payload Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.023+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=0 volume=35 Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.024+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=0 volume=35 Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.024+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=0 volume=35 Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.025+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=0 volume=35 Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.026+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=0 volume=35 Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.026+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.026+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=0 volume=35 Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.026+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.026+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=0 volume=35 Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.026+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.026+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=0 volume=35 Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.027+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.027+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.027+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:20 primo-plus volumio[1044]: info: ------------------------------ 65ms Mar 25 23:57:20 primo-plus volumio[1044]: info: ------------------------------ 64ms Mar 25 23:57:20 primo-plus volumio[1044]: info: sendMpdCommand playlistinfo took 41 milliseconds Mar 25 23:57:20 primo-plus volumio[1044]: info: sendMpdCommand playlistinfo took 40 milliseconds Mar 25 23:57:20 primo-plus volumio[1044]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:57:20 primo-plus volumio[1044]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:57:20 primo-plus volumio[1044]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:57:20 primo-plus volumio[1044]: info: ControllerMpd::pushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::servicePushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:20 primo-plus volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"76339122","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/76339122","trackType":"qobuz"} Mar 25 23:57:20 primo-plus volumio[1044]: verbose: CURRENT POSITION 14 Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::syncState stateService play Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:57:20 primo-plus volumio[1044]: info: Received an update from plugin. extracting info from payload Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:20 primo-plus volumio[1044]: info: ControllerMpd::pushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::servicePushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:20 primo-plus volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"76339122","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/76339122","trackType":"qobuz"} Mar 25 23:57:20 primo-plus volumio[1044]: verbose: CURRENT POSITION 14 Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::syncState stateService play Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:57:20 primo-plus volumio[1044]: info: Received an update from plugin. extracting info from payload Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:20 primo-plus volumio[1044]: info: ControllerMpd::pushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::servicePushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:20 primo-plus volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"76339122","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/76339122","trackType":"qobuz"} Mar 25 23:57:20 primo-plus volumio[1044]: verbose: CURRENT POSITION 14 Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::syncState stateService play Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:57:20 primo-plus volumio[1044]: info: Received an update from plugin. extracting info from payload Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:20 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:20 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.080+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=101 volume=35 Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.080+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=101 volume=35 Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.081+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=101 volume=35 Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.085+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=101 volume=35 Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.085+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.085+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=101 volume=35 Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.085+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.086+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.086+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=101 volume=35 Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.088+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.088+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.089+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=101 volume=35 Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.090+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=101 volume=35 Mar 25 23:57:20 primo-plus volumio[1044]: info: ------------------------------ 106ms Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.090+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.090+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:20 primo-plus volumio[1044]: info: ------------------------------ 100ms Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.091+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=101 volume=35 Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.091+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=101 volume=35 Mar 25 23:57:20 primo-plus volumio[1044]: info: ------------------------------ 100ms Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.092+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.092+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.092+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=101 volume=35 Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.092+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=101 volume=35 Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.094+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:20 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:20.094+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:20 primo-plus volumio[1044]: info: Signalling Playback active due to playback status change Mar 25 23:57:20 primo-plus volumio[1044]: info: Signalling Playback active due to playback status change Mar 25 23:57:20 primo-plus volumio[1044]: info: Signalling Playback active due to playback status change Mar 25 23:57:20 primo-plus volumio[1044]: info: Signalling Playback active due to playback status change Mar 25 23:57:20 primo-plus volumio[1044]: info: Signalling Playback active due to playback status change Mar 25 23:57:20 primo-plus volumio[1044]: info: Signalling Playback active due to playback status change Mar 25 23:57:20 primo-plus volumio[1044]: info: Signalling Playback active due to playback status change Mar 25 23:57:20 primo-plus volumio[1044]: info: Signalling Playback active due to playback status change Mar 25 23:57:20 primo-plus volumio[1044]: info: Signalling Playback active due to playback status change Mar 25 23:57:20 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:20 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:20 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:20 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:20 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:20 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:20 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:20 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:20 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:20 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:20 primo-plus volumio[1044]: info: MCU Signalled Playback Active Mar 25 23:57:20 primo-plus volumio[1044]: STREAMING PROXY: Resuming with range: bytes=15648- Mar 25 23:57:20 primo-plus volumio[1044]: STREAMING PROXY: Resume upstream status code: 206 Mar 25 23:57:20 primo-plus volumio[1044]: BT PLUGIN MESSAGE: Duration is 30000 Mar 25 23:57:22 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:22.141+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:57:22 primo-plus volumio[1044]: STREAMING PROXY: Resume ended. Received 11260974 bytes Mar 25 23:57:22 primo-plus volumio[1044]: STREAMING PROXY: Upstream closed. Received 11276622 bytes Mar 25 23:57:22 primo-plus volumio[1044]: STREAMING PROXY: Client closed connection Mar 25 23:57:23 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:23.946+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:57:28 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:28.191+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:57:32 primo-plus volumio[1044]: Searching plugin music_service/webradio Mar 25 23:57:32 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 25 23:57:33 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:33.021+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:57:33 primo-plus volumio[1044]: info: All search sources collected, pushing search results Mar 25 23:57:33 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:33.923+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:57:34 primo-plus volumio[1044]: Searching plugin music_service/webradio Mar 25 23:57:34 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 25 23:57:34 primo-plus volumio[1044]: info: All search sources collected, pushing search results Mar 25 23:57:35 primo-plus volumio[1044]: Searching plugin music_service/webradio Mar 25 23:57:35 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 25 23:57:35 primo-plus volumio[1044]: info: All search sources collected, pushing search results Mar 25 23:57:37 primo-plus volumio[1044]: Searching plugin music_service/webradio Mar 25 23:57:37 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 25 23:57:37 primo-plus volumio[1044]: info: All search sources collected, pushing search results Mar 25 23:57:39 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:39.078+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:57:43 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:43.634+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:57:44 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:44.236+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:57:50 primo-plus volumio[1044]: info: Mar 25 23:57:50 primo-plus volumio[1044]: ---------------------------- MPD announces state update: player Mar 25 23:57:50 primo-plus volumio[1044]: info: ControllerMpd::getState Mar 25 23:57:50 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:57:50 primo-plus volumio[1044]: info: Mar 25 23:57:50 primo-plus volumio[1044]: ---------------------------- MPD announces state update: player Mar 25 23:57:50 primo-plus volumio[1044]: info: ControllerMpd::getState Mar 25 23:57:50 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:57:50 primo-plus volumio[1044]: info: Mar 25 23:57:50 primo-plus volumio[1044]: ---------------------------- MPD announces state update: player Mar 25 23:57:50 primo-plus volumio[1044]: info: ControllerMpd::getState Mar 25 23:57:50 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:57:50 primo-plus volumio[1044]: info: sendMpdCommand status took 3 milliseconds Mar 25 23:57:50 primo-plus volumio[1044]: info: sendMpdCommand status took 2 milliseconds Mar 25 23:57:50 primo-plus volumio[1044]: verbose: ControllerMpd::parseState Mar 25 23:57:50 primo-plus volumio[1044]: verbose: ControllerMpd::parseState Mar 25 23:57:50 primo-plus volumio[1044]: info: ControllerMpd::pushState Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreCommandRouter::servicePushState Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:50 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:50 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:50 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 14 Mar 25 23:57:50 primo-plus volumio[1044]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 23:57:50 primo-plus volumio[1044]: verbose: CURRENT POSITION 14 Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreStateMachine::syncState stateService stop Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreStateMachine::play index undefined Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:50 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 15 Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:50 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 15 Mar 25 23:57:50 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:50 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:50 primo-plus volumio[1044]: info: ControllerMpd::pushState Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreCommandRouter::servicePushState Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:50 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 15 Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:50 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 15 Mar 25 23:57:50 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:50 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:50 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 15 Mar 25 23:57:50 primo-plus volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Mar 25 23:57:50 primo-plus volumio[1044]: info: ControllerMpd::pushState Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreCommandRouter::servicePushState Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:50 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 15 Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:50 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 15 Mar 25 23:57:50 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:50 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:50 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 15 Mar 25 23:57:50 primo-plus volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.027+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=30036 volume=35 Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.028+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=30036 volume=35 Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.028+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.028+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/76339122 title=ジンジ Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.029+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_STOPPED positionMs= volume=35 Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.029+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_STOPPED positionMs= volume=35 Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.029+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_STOPPED positionMs= volume=35 Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.029+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.029+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_STOPPED positionMs= volume=35 Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.030+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.030+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_STOPPED positionMs= volume=35 Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.031+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.031+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.032+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_STOPPED positionMs= volume=35 Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.032+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.033+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:50 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 15 Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreStateMachine::startPlaybackTimer Mar 25 23:57:50 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 15 Mar 25 23:57:50 primo-plus volumio[1044]: info: [1774450670040] ControllerQobuz::clearAddPlayTrack Mar 25 23:57:50 primo-plus volumio[1044]: info: ------------------------------ 44ms Mar 25 23:57:50 primo-plus volumio[1044]: info: ------------------------------ 43ms Mar 25 23:57:50 primo-plus volumio[1044]: info: ------------------------------ 43ms Mar 25 23:57:50 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:50 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 15 Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:50 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 15 Mar 25 23:57:50 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:50 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.053+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_STOPPED positionMs=0 volume=35 Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.055+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_STOPPED positionMs=0 volume=35 Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.056+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:50 primo-plus volumio[1044]: info: sendMpdCommand stop took 10 milliseconds Mar 25 23:57:50 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 23:57:50 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:50.056+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:50 primo-plus volumio[1044]: info: Signalling Playback active due to playback status change Mar 25 23:57:50 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:50 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:50 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:50 primo-plus volumio[1044]: info: Mar 25 23:57:50 primo-plus volumio[1044]: ---------------------------- MPD announces system playlist update Mar 25 23:57:50 primo-plus volumio[1044]: info: Ignoring MPD Status Update Mar 25 23:57:50 primo-plus volumio[1044]: info: sendMpdCommand clear took 19 milliseconds Mar 25 23:57:50 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/361442494" Mar 25 23:57:50 primo-plus volumio[1044]: STREAMING PROXY: Handling url /?data=qobuz://song/361442494 Mar 25 23:57:50 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:50 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:50 primo-plus volumio[1044]: info: Mar 25 23:57:50 primo-plus volumio[1044]: ---------------------------- MPD announces system playlist update Mar 25 23:57:50 primo-plus volumio[1044]: info: Ignoring MPD Status Update Mar 25 23:57:50 primo-plus volumio[1044]: info: Mar 25 23:57:50 primo-plus volumio[1044]: ---------------------------- MPD announces system playlist update Mar 25 23:57:50 primo-plus volumio[1044]: info: Ignoring MPD Status Update Mar 25 23:57:50 primo-plus volumio[1044]: error: updateQueue error: null Mar 25 23:57:50 primo-plus volumio[1044]: info: ------------------------------ 15ms Mar 25 23:57:50 primo-plus volumio[1044]: info: Executing endpoint getStreamUrlqobuz Mar 25 23:57:50 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 25 23:57:50 primo-plus volumio[1044]: info: MCU Signalled Playback Inactive Mar 25 23:57:50 primo-plus volumio[1044]: info: getStreamUrl took 470 milliseconds Mar 25 23:57:50 primo-plus volumio[1044]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=11307679&eid=361442494&fmt=7&profile=raw&range=20-30&app_id=539451548&etsp=1774537070&hmac=8ciUXzfzNHbMt2gp691B_QBhypc Mar 25 23:57:50 primo-plus volumio[1044]: STREAMING PROXY: Requesting upstream with range: none Mar 25 23:57:50 primo-plus volumio[1044]: BT PLUGIN MESSAGE: Duration is 165000 Mar 25 23:57:52 primo-plus volumio[1044]: info: Executing endpoint metavolumio Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 25 23:57:52 primo-plus volumio[1044]: info: Executing endpoint metavolumio Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 25 23:57:52 primo-plus volumio[1044]: info: Executing endpoint metavolumio Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 25 23:57:52 primo-plus volumio[1044]: STREAMING PROXY: Upstream status code: 200 Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/361442494" Mar 25 23:57:52 primo-plus volumio[1044]: STREAMING PROXY: Client closed connection Mar 25 23:57:52 primo-plus volumio[1044]: error: updateQueue error: null Mar 25 23:57:52 primo-plus volumio[1044]: error: updateQueue error: null Mar 25 23:57:52 primo-plus volumio[1044]: STREAMING PROXY: Upstream closed. Received 32768 bytes Mar 25 23:57:52 primo-plus volumio[1044]: info: ------------------------------ 2097ms Mar 25 23:57:52 primo-plus volumio[1044]: STREAMING PROXY: Incomplete transfer. Expected 10491316, got 32768 Mar 25 23:57:52 primo-plus volumio[1044]: STREAMING PROXY: Retrying with range: bytes=32768- (attempt 1) Mar 25 23:57:52 primo-plus volumio[1044]: info: ------------------------------ 2095ms Mar 25 23:57:52 primo-plus volumio[1044]: Upnp client error: Error: This socket has been ended by the other party Mar 25 23:57:52 primo-plus volumio[1044]: info: Mar 25 23:57:52 primo-plus volumio[1044]: ---------------------------- MPD announces system playlist update Mar 25 23:57:52 primo-plus volumio[1044]: info: Ignoring MPD Status Update Mar 25 23:57:52 primo-plus volumio[1044]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/361442494" took 3 milliseconds Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand play Mar 25 23:57:52 primo-plus volumio[1044]: STREAMING PROXY: Handling url /?data=qobuz://song/361442494 Mar 25 23:57:52 primo-plus volumio[1044]: info: Mar 25 23:57:52 primo-plus volumio[1044]: ---------------------------- MPD announces system playlist update Mar 25 23:57:52 primo-plus volumio[1044]: info: Ignoring MPD Status Update Mar 25 23:57:52 primo-plus volumio[1044]: info: Mar 25 23:57:52 primo-plus volumio[1044]: ---------------------------- MPD announces system playlist update Mar 25 23:57:52 primo-plus volumio[1044]: info: Ignoring MPD Status Update Mar 25 23:57:52 primo-plus volumio[1044]: info: ------------------------------ 8ms Mar 25 23:57:52 primo-plus volumio[1044]: info: sendMpdCommand play took 7 milliseconds Mar 25 23:57:52 primo-plus volumio[1044]: info: ------------------------------ 3ms Mar 25 23:57:52 primo-plus volumio[1044]: info: ------------------------------ 2ms Mar 25 23:57:52 primo-plus volumio[1044]: info: Executing endpoint getStreamUrlqobuz Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 25 23:57:52 primo-plus volumio[1044]: info: getStreamUrl took 452 milliseconds Mar 25 23:57:52 primo-plus volumio[1044]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=11307679&eid=361442494&fmt=7&profile=raw&range=20-30&app_id=539451548&etsp=1774537072&hmac=2dn3dvgMd_uk3iX6e7NqswBdJOU Mar 25 23:57:52 primo-plus volumio[1044]: STREAMING PROXY: Requesting upstream with range: none Mar 25 23:57:52 primo-plus volumio[1044]: STREAMING PROXY: Upstream status code: 200 Mar 25 23:57:52 primo-plus volumio[1044]: info: Mar 25 23:57:52 primo-plus volumio[1044]: ---------------------------- MPD announces state update: player Mar 25 23:57:52 primo-plus volumio[1044]: info: ControllerMpd::getState Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:57:52 primo-plus volumio[1044]: info: Mar 25 23:57:52 primo-plus volumio[1044]: ---------------------------- MPD announces state update: player Mar 25 23:57:52 primo-plus volumio[1044]: info: ControllerMpd::getState Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:57:52 primo-plus volumio[1044]: info: Mar 25 23:57:52 primo-plus volumio[1044]: ---------------------------- MPD announces state update: player Mar 25 23:57:52 primo-plus volumio[1044]: info: ControllerMpd::getState Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:57:52 primo-plus volumio[1044]: info: Mar 25 23:57:52 primo-plus volumio[1044]: ---------------------------- MPD announces state update: player Mar 25 23:57:52 primo-plus volumio[1044]: info: sendMpdCommand status took 5 milliseconds Mar 25 23:57:52 primo-plus volumio[1044]: info: ControllerMpd::getState Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:57:52 primo-plus volumio[1044]: info: sendMpdCommand status took 4 milliseconds Mar 25 23:57:52 primo-plus volumio[1044]: info: sendMpdCommand status took 2 milliseconds Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::parseState Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::parseState Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::parseState Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:57:52 primo-plus volumio[1044]: info: Mar 25 23:57:52 primo-plus volumio[1044]: ---------------------------- MPD announces state update: player Mar 25 23:57:52 primo-plus volumio[1044]: info: ControllerMpd::getState Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:57:52 primo-plus volumio[1044]: info: Mar 25 23:57:52 primo-plus volumio[1044]: ---------------------------- MPD announces state update: player Mar 25 23:57:52 primo-plus volumio[1044]: info: ControllerMpd::getState Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Mar 25 23:57:52 primo-plus volumio[1044]: info: sendMpdCommand status took 12 milliseconds Mar 25 23:57:52 primo-plus volumio[1044]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 25 23:57:52 primo-plus volumio[1044]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 25 23:57:52 primo-plus volumio[1044]: info: sendMpdCommand playlistinfo took 10 milliseconds Mar 25 23:57:52 primo-plus volumio[1044]: info: sendMpdCommand status took 7 milliseconds Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::parseState Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::parseState Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:57:52 primo-plus volumio[1044]: info: ControllerMpd::pushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::servicePushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 15 Mar 25 23:57:52 primo-plus volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361442494","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/361442494","trackType":"qobuz"} Mar 25 23:57:52 primo-plus volumio[1044]: verbose: CURRENT POSITION 15 Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::syncState stateService play Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::syncState currentStatus stop Mar 25 23:57:52 primo-plus volumio[1044]: info: ControllerMpd::pushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::servicePushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 15 Mar 25 23:57:52 primo-plus volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361442494","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/361442494","trackType":"qobuz"} Mar 25 23:57:52 primo-plus volumio[1044]: verbose: CURRENT POSITION 15 Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::syncState stateService play Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:57:52 primo-plus volumio[1044]: info: Received an update from plugin. extracting info from payload Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:52 primo-plus volumio[1044]: info: ControllerMpd::pushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::servicePushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 15 Mar 25 23:57:52 primo-plus volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361442494","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/361442494","trackType":"qobuz"} Mar 25 23:57:52 primo-plus volumio[1044]: verbose: CURRENT POSITION 15 Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::syncState stateService play Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:57:52 primo-plus volumio[1044]: info: Received an update from plugin. extracting info from payload Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:52 primo-plus volumio[1044]: info: ------------------------------ 55ms Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.793+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=0 volume=35 Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.795+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=0 volume=35 Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.795+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=0 volume=35 Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.795+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.796+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.796+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=0 volume=35 Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.797+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=0 volume=35 Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.797+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.797+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=0 volume=35 Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.797+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.798+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.800+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.802+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=0 volume=35 Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.803+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=0 volume=35 Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.803+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.804+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:52 primo-plus volumio[1044]: info: ------------------------------ 70ms Mar 25 23:57:52 primo-plus volumio[1044]: info: ------------------------------ 68ms Mar 25 23:57:52 primo-plus volumio[1044]: info: sendMpdCommand status took 57 milliseconds Mar 25 23:57:52 primo-plus volumio[1044]: info: sendMpdCommand playlistinfo took 54 milliseconds Mar 25 23:57:52 primo-plus volumio[1044]: info: sendMpdCommand playlistinfo took 53 milliseconds Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::parseState Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:57:52 primo-plus volumio[1044]: info: ControllerMpd::pushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::servicePushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 15 Mar 25 23:57:52 primo-plus volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361442494","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/361442494","trackType":"qobuz"} Mar 25 23:57:52 primo-plus volumio[1044]: verbose: CURRENT POSITION 15 Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::syncState stateService play Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:57:52 primo-plus volumio[1044]: info: Received an update from plugin. extracting info from payload Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:52 primo-plus volumio[1044]: info: ControllerMpd::pushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::servicePushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 15 Mar 25 23:57:52 primo-plus volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361442494","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/361442494","trackType":"qobuz"} Mar 25 23:57:52 primo-plus volumio[1044]: verbose: CURRENT POSITION 15 Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::syncState stateService play Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:57:52 primo-plus volumio[1044]: info: Received an update from plugin. extracting info from payload Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.837+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=74 volume=35 Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.838+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=74 volume=35 Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.838+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.838+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=74 volume=35 Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.838+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.838+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.838+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=74 volume=35 Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.838+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.838+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.838+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=74 volume=35 Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.839+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.839+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.839+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:52 primo-plus volumio[1044]: info: ------------------------------ 110ms Mar 25 23:57:52 primo-plus volumio[1044]: info: ------------------------------ 107ms Mar 25 23:57:52 primo-plus volumio[1044]: info: Signalling Playback active due to playback status change Mar 25 23:57:52 primo-plus volumio[1044]: info: Signalling Playback active due to playback status change Mar 25 23:57:52 primo-plus volumio[1044]: info: Signalling Playback active due to playback status change Mar 25 23:57:52 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:52 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:52 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:52 primo-plus volumio[1044]: info: sendMpdCommand playlistinfo took 75 milliseconds Mar 25 23:57:52 primo-plus volumio[1044]: verbose: ControllerMpd::parseTrackInfo Mar 25 23:57:52 primo-plus volumio[1044]: info: ControllerMpd::pushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::servicePushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CorePlayQueue::getTrack 15 Mar 25 23:57:52 primo-plus volumio[1044]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"361442494","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/361442494","trackType":"qobuz"} Mar 25 23:57:52 primo-plus volumio[1044]: verbose: CURRENT POSITION 15 Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::syncState stateService play Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::syncState currentStatus play Mar 25 23:57:52 primo-plus volumio[1044]: info: Received an update from plugin. extracting info from payload Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreStateMachine::pushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioPushState Mar 25 23:57:52 primo-plus volumio[1044]: info: CoreCommandRouter::volumioGetState Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output update for this device Mar 25 23:57:52 primo-plus volumio[1044]: info: MRS: Pushing multiroomSync output Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.892+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=150 volume=35 Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.893+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" state=STATUS_PLAYING positionMs=150 volume=35 Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.893+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" state=STATUS_PLAYING positionMs=150 volume=35 Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.893+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58733,192.168.11.10:58842,00:00:00:00:00:00%53,00:00:00:00:00:00%54 @ 0x2c98120" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:52 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:52.893+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.11.10:58842 @ 0x2c020c0" id=qobuz://song/361442494 title=ディサフィナード Mar 25 23:57:52 primo-plus volumio[1044]: info: ------------------------------ 149ms Mar 25 23:57:52 primo-plus volumio[1044]: info: Signalling Playback active due to playback status change Mar 25 23:57:52 primo-plus volumio[1044]: info: Signalling Playback active due to playback status change Mar 25 23:57:52 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:52 primo-plus volumio[1044]: info: Updating RAAT Signal Path Mar 25 23:57:53 primo-plus volumio[1044]: info: MCU Signalled Playback Active Mar 25 23:57:53 primo-plus volumio[1044]: STREAMING PROXY: Resuming with range: bytes=32768- Mar 25 23:57:53 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:53.255+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:57:53 primo-plus volumio[1044]: STREAMING PROXY: Resume upstream status code: 206 Mar 25 23:57:53 primo-plus volumio[1044]: BT PLUGIN MESSAGE: Duration is 30000 Mar 25 23:57:53 primo-plus volumio[1044]: STREAMING PROXY: Upstream closed. Received 10491316 bytes Mar 25 23:57:53 primo-plus volumio[1044]: STREAMING PROXY: Client closed connection Mar 25 23:57:54 primo-plus volumio[1044]: STREAMING PROXY: Resume ended. Received 10458548 bytes Mar 25 23:57:56 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:57:56.563+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:57:57 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Mar 25 23:57:57 primo-plus volumio[1044]: info: handleBrowseUri took 383 milliseconds Mar 25 23:57:57 primo-plus volumio[1044]: info: Preload queue cleared Mar 25 23:57:57 primo-plus volumio[1044]: info: Preload queue cleared Mar 25 23:58:00 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:58:00.172+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:58:03 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:58:03.777+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:58:04 primo-plus volumio[1044]: Searching plugin music_service/qobuz Mar 25 23:58:04 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 25 23:58:05 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:58:05.273+09:00 level=INFO msg="new address was allocated" component=ble/conn old=85 new=86 Mar 25 23:58:06 primo-plus volumio[1044]: info: searchQOBUZUri took 1376 milliseconds Mar 25 23:58:06 primo-plus volumio[1044]: info: All search sources collected, pushing search results Mar 25 23:58:06 primo-plus volumio[1044]: Searching plugin music_service/qobuz Mar 25 23:58:06 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 25 23:58:08 primo-plus volumio[1044]: info: searchQOBUZUri took 1567 milliseconds Mar 25 23:58:08 primo-plus volumio[1044]: info: All search sources collected, pushing search results Mar 25 23:58:08 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:58:08.944+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:58:13 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:58:13.175+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:58:13 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Mar 25 23:58:14 primo-plus volumio[1044]: info: handleBrowseUri took 1565 milliseconds Mar 25 23:58:14 primo-plus volumio[1044]: info: Preload queue cleared Mar 25 23:58:15 primo-plus volumio[1044]: info: Executing endpoint getSimilarArtists Mar 25 23:58:15 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Mar 25 23:58:15 primo-plus volumio[1044]: info: Executing endpoint metavolumio Mar 25 23:58:15 primo-plus volumio[1044]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 25 23:58:16 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:58:16.784+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:58:17 primo-plus volumio[1044]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 23:58:17 primo-plus volumio[1044]: TypeError: Cannot read property 'slice' of null Mar 25 23:58:17 primo-plus volumio[1044]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 25 23:58:17 primo-plus volumio[1044]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 25 23:58:17 primo-plus volumio[1044]: at Object.onceWrapper (events.js:422:26) Mar 25 23:58:17 primo-plus volumio[1044]: at ClientRequest.emit (events.js:315:20) Mar 25 23:58:17 primo-plus volumio[1044]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Mar 25 23:58:17 primo-plus volumio[1044]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Mar 25 23:58:17 primo-plus volumio[1044]: at TLSSocket.socketOnData (_http_client.js:509:22) Mar 25 23:58:17 primo-plus volumio[1044]: at TLSSocket.emit (events.js:315:20) Mar 25 23:58:17 primo-plus volumio[1044]: at addChunk (internal/streams/readable.js:309:12) Mar 25 23:58:17 primo-plus volumio[1044]: at readableAddChunk (internal/streams/readable.js:284:9) Mar 25 23:58:17 primo-plus volumio[1044]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Mar 25 23:58:17 primo-plus volumio[1044]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Mar 25 23:58:17 primo-plus volumio[1044]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 23:58:17 primo-plus volumio5-onboarding[1486]: time=2026-03-25T23:58:17.987+09:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 23:58:18 primo-plus sudo[26206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-25 23:57 Mar 25 23:58:18 primo-plus sudo[26206]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="primoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 01:08:28 PM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Primo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo Plus" VOLUMIO_HASH="2aa40c31709b83c0e790f52b483672b5"