Oct 11 19:31:00 rivoplus volumio[3517]: info: Executing endpoint metavolumio Oct 11 19:31:00 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 11 19:31:00 rivoplus volumio[3517]: info: Executing endpoint metavolumio Oct 11 19:31:00 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 11 19:31:00 rivoplus volumio[3517]: info: Executing endpoint metavolumio Oct 11 19:31:00 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreCommandRouter::volumioSeek Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreStateMachine::seek Oct 11 19:31:07 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 0 Oct 11 19:31:07 rivoplus volumio[3517]: info: TRACKBLOCK {"service":"bandcamp","uri":"bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fin-the-pocket-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22In%20the%20Pocket%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fin-the-pocket-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D","albumart":"https://f4.bcbits.com/img/a0336167888_16.jpg","artist":"Alex Haas","album":"STRING OF PIECES","name":"In the Pocket","title":"In the Pocket","duration":245.581,"samplerate":"128 kbps"} Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreStateMachine::startPlaybackTimer Oct 11 19:31:07 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 0 Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 19:31:07 rivoplus volumio[3517]: info: ControllerMpd::seek Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:07 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:07 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:07 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:07.064Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=72000 volume=100 Oct 11 19:31:07 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:07.065Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fin-the-pocket-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22In%20the%20Pocket%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fin-the-pocket-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="In the Pocket" Oct 11 19:31:07 rivoplus volumio[3517]: error: null Oct 11 19:31:07 rivoplus volumio[3517]: info: Oct 11 19:31:07 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:07 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:07 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:07 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:07 rivoplus volumio[3517]: info: Oct 11 19:31:07 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:07 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:07 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:07 rivoplus volumio[3517]: info: Oct 11 19:31:07 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:07 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:07 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:07 rivoplus volumio[3517]: info: sendMpdCommand status took 17 milliseconds Oct 11 19:31:07 rivoplus volumio[3517]: info: sendMpdCommand status took 3 milliseconds Oct 11 19:31:07 rivoplus volumio[3517]: info: sendMpdCommand status took 2 milliseconds Oct 11 19:31:07 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:07 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:07 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:07 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:07 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:07 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:07 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 19:31:07 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 19:31:07 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 19:31:07 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:07 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:07 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:07 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:07 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 0 Oct 11 19:31:07 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":72000,"duration":246,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"In the Pocket","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/5481e259c602f0ec771e51afebd83621/mp3-128/1052860338?p=0&ts=1791833451&t=a90b83b8bba47796f3e48ca1b191824803d3b844&token=1791833451_5aa3d072e402683de91ea520277fd93fe5f38804&t.mp3","trackType":"mp3"} Oct 11 19:31:07 rivoplus volumio[3517]: verbose: CURRENT POSITION 0 Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:07 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:07 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:07 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:07 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:07 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:07 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:07 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 0 Oct 11 19:31:07 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":73236,"duration":246,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"In the Pocket","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/5481e259c602f0ec771e51afebd83621/mp3-128/1052860338?p=0&ts=1791833451&t=a90b83b8bba47796f3e48ca1b191824803d3b844&token=1791833451_5aa3d072e402683de91ea520277fd93fe5f38804&t.mp3","trackType":"mp3"} Oct 11 19:31:07 rivoplus volumio[3517]: verbose: CURRENT POSITION 0 Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:07 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:07 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:07 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:07 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:07 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:07 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:07 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 0 Oct 11 19:31:07 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":73236,"duration":246,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"In the Pocket","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/5481e259c602f0ec771e51afebd83621/mp3-128/1052860338?p=0&ts=1791833451&t=a90b83b8bba47796f3e48ca1b191824803d3b844&token=1791833451_5aa3d072e402683de91ea520277fd93fe5f38804&t.mp3","trackType":"mp3"} Oct 11 19:31:07 rivoplus volumio[3517]: verbose: CURRENT POSITION 0 Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:07 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:07 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:07 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:07 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:07 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:07 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:07 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:07.136Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=72047 volume=100 Oct 11 19:31:07 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:07.136Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fin-the-pocket-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22In%20the%20Pocket%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fin-the-pocket-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="In the Pocket" Oct 11 19:31:07 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:07.151Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=72047 volume=100 Oct 11 19:31:07 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:07.152Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=72047 volume=100 Oct 11 19:31:07 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:07.152Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fin-the-pocket-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22In%20the%20Pocket%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fin-the-pocket-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="In the Pocket" Oct 11 19:31:07 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:07.152Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fin-the-pocket-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22In%20the%20Pocket%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fin-the-pocket-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="In the Pocket" Oct 11 19:31:07 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:07.154Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=72047 volume=100 Oct 11 19:31:07 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:07.154Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=72047 volume=100 Oct 11 19:31:07 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:07.157Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fin-the-pocket-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22In%20the%20Pocket%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fin-the-pocket-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="In the Pocket" Oct 11 19:31:07 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:07.156Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=72047 volume=100 Oct 11 19:31:07 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:07.157Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fin-the-pocket-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22In%20the%20Pocket%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fin-the-pocket-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="In the Pocket" Oct 11 19:31:07 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:07.161Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fin-the-pocket-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22In%20the%20Pocket%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fin-the-pocket-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="In the Pocket" Oct 11 19:31:07 rivoplus volumio[3517]: info: ------------------------------ 107ms Oct 11 19:31:07 rivoplus volumio[3517]: info: ------------------------------ 94ms Oct 11 19:31:07 rivoplus volumio[3517]: info: ------------------------------ 98ms Oct 11 19:31:07 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:07 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:07 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:07 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:07 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:07 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreCommandRouter::volumioNext Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreStateMachine::next Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 19:31:14 rivoplus volumio[3517]: info: ControllerMpd::next Oct 11 19:31:14 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand next Oct 11 19:31:14 rivoplus volumio[3517]: info: Oct 11 19:31:14 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:14 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:14 rivoplus volumio[3517]: info: Oct 11 19:31:14 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:14 rivoplus volumio[3517]: info: sendMpdCommand next took 37 milliseconds Oct 11 19:31:14 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:14 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:14 rivoplus volumio[3517]: info: Oct 11 19:31:14 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:14 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:14 rivoplus volumio[3517]: info: Oct 11 19:31:14 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:14 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:14 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:14 rivoplus volumio[3517]: info: Oct 11 19:31:14 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:14 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:14 rivoplus volumio[3517]: info: Oct 11 19:31:14 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:14 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:14 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:14 rivoplus volumio[3517]: error: updateQueue error: null Oct 11 19:31:14 rivoplus volumio[3517]: error: updateQueue error: null Oct 11 19:31:14 rivoplus volumio[3517]: error: updateQueue error: null Oct 11 19:31:14 rivoplus volumio[3517]: info: ------------------------------ 8ms Oct 11 19:31:14 rivoplus volumio[3517]: info: sendMpdCommand status took 7 milliseconds Oct 11 19:31:14 rivoplus volumio[3517]: info: ------------------------------ 6ms Oct 11 19:31:14 rivoplus volumio[3517]: info: sendMpdCommand status took 5 milliseconds Oct 11 19:31:14 rivoplus volumio[3517]: info: ------------------------------ 4ms Oct 11 19:31:14 rivoplus volumio[3517]: info: sendMpdCommand status took 3 milliseconds Oct 11 19:31:14 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:14 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:14 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:14 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:14 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:14 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:14 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 0 Oct 11 19:31:14 rivoplus volumio[3517]: 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} Oct 11 19:31:14 rivoplus volumio[3517]: verbose: CURRENT POSITION 0 Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService stop Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreStateMachine::play index undefined Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:14 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:14 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:14 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:14 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:14 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:14 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:14 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:14 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:14 rivoplus volumio[3517]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current bandcamp Received mpd Oct 11 19:31:14 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:14 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:14 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:14 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:14 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:14 rivoplus volumio[3517]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current bandcamp Received mpd Oct 11 19:31:14 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:14.695Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=79548 volume=100 Oct 11 19:31:14 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:14.696Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fin-the-pocket-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22In%20the%20Pocket%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fin-the-pocket-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="In the Pocket" Oct 11 19:31:14 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:14.696Z level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 227.848 into Go struct field State.duration of type int" Oct 11 19:31:14 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:14.698Z level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 227.848 into Go struct field State.duration of type int" Oct 11 19:31:14 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:14.699Z level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 227.848 into Go struct field State.duration of type int" Oct 11 19:31:14 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreStateMachine::startPlaybackTimer Oct 11 19:31:14 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:14 rivoplus volumio[3517]: info: [bandcamp-play] clearAddPlayTrack: bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D Oct 11 19:31:14 rivoplus volumio[3517]: info: ------------------------------ 66ms Oct 11 19:31:14 rivoplus volumio[3517]: info: ------------------------------ 64ms Oct 11 19:31:14 rivoplus volumio[3517]: info: ------------------------------ 62ms Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:14 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:14 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:14 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:14 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:14 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:14.729Z level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 227.848 into Go struct field State.duration of type int" Oct 11 19:31:14 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:14 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:14 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:14 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:14 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:15 rivoplus volumio[3517]: info: MCU Signalled Playback Inactive Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 19:31:15 rivoplus volumio[3517]: info: sendMpdCommand stop took 2 milliseconds Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 19:31:15 rivoplus volumio[3517]: info: Oct 11 19:31:15 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:15 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:15 rivoplus volumio[3517]: info: sendMpdCommand clear took 1 milliseconds Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand addid "https://t4.bcbits.com/stream/ab1c3b86a11febb2ea993e8ec9726d21/mp3-128/2701377319?p=0&ts=1791833451&t=fa03737da4b45be055fa733d7c9e738f2087d96a&token=1791833451_4b2d54b9a26c9e29741e1657bb7b8d1dc16bc4c5&t.mp3" Oct 11 19:31:15 rivoplus volumio[3517]: info: Oct 11 19:31:15 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:15 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:15 rivoplus volumio[3517]: info: Oct 11 19:31:15 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:15 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:15 rivoplus volumio[3517]: error: updateQueue error: null Oct 11 19:31:15 rivoplus volumio[3517]: info: Oct 11 19:31:15 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:15 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:15 rivoplus volumio[3517]: info: ------------------------------ 4ms Oct 11 19:31:15 rivoplus volumio[3517]: info: sendMpdCommand addid "https://t4.bcbits.com/stream/ab1c3b86a11febb2ea993e8ec9726d21/mp3-128/2701377319?p=0&ts=1791833451&t=fa03737da4b45be055fa733d7c9e738f2087d96a&token=1791833451_4b2d54b9a26c9e29741e1657bb7b8d1dc16bc4c5&t.mp3" took 4 milliseconds Oct 11 19:31:15 rivoplus volumio[3517]: info: ------------------------------ 3ms Oct 11 19:31:15 rivoplus volumio[3517]: info: ------------------------------ 3ms Oct 11 19:31:15 rivoplus volumio[3517]: verbose: MPD COMMAND [object Object] Oct 11 19:31:15 rivoplus volumio[3517]: verbose: MPD COMMAND [object Object] Oct 11 19:31:15 rivoplus volumio[3517]: verbose: MPD COMMAND [object Object] Oct 11 19:31:15 rivoplus volumio[3517]: info: Oct 11 19:31:15 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:15 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:15 rivoplus volumio[3517]: info: Oct 11 19:31:15 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:15 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:15 rivoplus volumio[3517]: info: Oct 11 19:31:15 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:15 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:15 rivoplus volumio[3517]: info: ------------------------------ 6ms Oct 11 19:31:15 rivoplus volumio[3517]: info: ------------------------------ 3ms Oct 11 19:31:15 rivoplus volumio[3517]: info: ------------------------------ 2ms Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand play Oct 11 19:31:15 rivoplus volumio[3517]: info: Oct 11 19:31:15 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:15 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:15 rivoplus volumio[3517]: info: Oct 11 19:31:15 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:15 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:15 rivoplus volumio[3517]: info: ------------------------------ 5ms Oct 11 19:31:15 rivoplus volumio[3517]: info: sendMpdCommand play took 4 milliseconds Oct 11 19:31:15 rivoplus volumio[3517]: info: ------------------------------ 3ms Oct 11 19:31:15 rivoplus volumio[3517]: info: ------------------------------ 2ms Oct 11 19:31:15 rivoplus volumio[3517]: info: Oct 11 19:31:15 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:15 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:15 rivoplus volumio[3517]: info: Oct 11 19:31:15 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:15 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:15 rivoplus volumio[3517]: info: Oct 11 19:31:15 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:15 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:15 rivoplus volumio[3517]: info: sendMpdCommand status took 21 milliseconds Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:15 rivoplus volumio[3517]: info: Oct 11 19:31:15 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:15 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:15 rivoplus volumio[3517]: info: Oct 11 19:31:15 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:15 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:15 rivoplus volumio[3517]: info: sendMpdCommand status took 25 milliseconds Oct 11 19:31:15 rivoplus volumio[3517]: info: sendMpdCommand status took 23 milliseconds Oct 11 19:31:15 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 11 19:31:15 rivoplus volumio[3517]: info: sendMpdCommand status took 3 milliseconds Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:15 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:15 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Blue in Red","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/ab1c3b86a11febb2ea993e8ec9726d21/mp3-128/2701377319?p=0&ts=1791833451&t=fa03737da4b45be055fa733d7c9e738f2087d96a&token=1791833451_4b2d54b9a26c9e29741e1657bb7b8d1dc16bc4c5&t.mp3","trackType":"mp3"} Oct 11 19:31:15 rivoplus volumio[3517]: verbose: CURRENT POSITION 1 Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus stop Oct 11 19:31:15 rivoplus volumio[3517]: info: ------------------------------ 32ms Oct 11 19:31:15 rivoplus volumio[3517]: info: Oct 11 19:31:15 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:15 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:15 rivoplus volumio[3517]: info: sendMpdCommand status took 8 milliseconds Oct 11 19:31:15 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 11 19:31:15 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 11 19:31:15 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:15 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:15 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Blue in Red","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/ab1c3b86a11febb2ea993e8ec9726d21/mp3-128/2701377319?p=0&ts=1791833451&t=fa03737da4b45be055fa733d7c9e738f2087d96a&token=1791833451_4b2d54b9a26c9e29741e1657bb7b8d1dc16bc4c5&t.mp3","trackType":"mp3"} Oct 11 19:31:15 rivoplus volumio[3517]: verbose: CURRENT POSITION 1 Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:15 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:15 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:15 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blue in Red","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/ab1c3b86a11febb2ea993e8ec9726d21/mp3-128/2701377319?p=0&ts=1791833451&t=fa03737da4b45be055fa733d7c9e738f2087d96a&token=1791833451_4b2d54b9a26c9e29741e1657bb7b8d1dc16bc4c5&t.mp3","trackType":"mp3"} Oct 11 19:31:15 rivoplus volumio[3517]: verbose: CURRENT POSITION 1 Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:15 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:15 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:15 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1133,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blue in Red","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/ab1c3b86a11febb2ea993e8ec9726d21/mp3-128/2701377319?p=0&ts=1791833451&t=fa03737da4b45be055fa733d7c9e738f2087d96a&token=1791833451_4b2d54b9a26c9e29741e1657bb7b8d1dc16bc4c5&t.mp3","trackType":"mp3"} Oct 11 19:31:15 rivoplus volumio[3517]: verbose: CURRENT POSITION 1 Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:15 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.408Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.408Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Blue in Red" Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.411Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.411Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Blue in Red" Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.413Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.414Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Blue in Red" Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.416Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.417Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Blue in Red" Oct 11 19:31:15 rivoplus volumio[3517]: info: ------------------------------ 121ms Oct 11 19:31:15 rivoplus volumio[3517]: info: ------------------------------ 121ms Oct 11 19:31:15 rivoplus volumio[3517]: info: ------------------------------ 102ms Oct 11 19:31:15 rivoplus volumio[3517]: info: sendMpdCommand status took 94 milliseconds Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.417Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.418Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Blue in Red" Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.419Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 19:31:15 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 92 milliseconds Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:15 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:15 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blue in Red","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/ab1c3b86a11febb2ea993e8ec9726d21/mp3-128/2701377319?p=0&ts=1791833451&t=fa03737da4b45be055fa733d7c9e738f2087d96a&token=1791833451_4b2d54b9a26c9e29741e1657bb7b8d1dc16bc4c5&t.mp3","trackType":"mp3"} Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.420Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Blue in Red" Oct 11 19:31:15 rivoplus volumio[3517]: verbose: CURRENT POSITION 1 Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:15 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.487Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.488Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Blue in Red" Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.489Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.489Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Blue in Red" Oct 11 19:31:15 rivoplus volumio[3517]: info: ------------------------------ 144ms Oct 11 19:31:15 rivoplus volumio[3517]: info: Signalling Playback active due to playback status change Oct 11 19:31:15 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:15 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:15 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:15 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:15 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:15 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:15 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:15 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:15 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 168 milliseconds Oct 11 19:31:15 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:15 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:15 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blue in Red","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/ab1c3b86a11febb2ea993e8ec9726d21/mp3-128/2701377319?p=0&ts=1791833451&t=fa03737da4b45be055fa733d7c9e738f2087d96a&token=1791833451_4b2d54b9a26c9e29741e1657bb7b8d1dc16bc4c5&t.mp3","trackType":"mp3"} Oct 11 19:31:15 rivoplus volumio[3517]: verbose: CURRENT POSITION 1 Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:15 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:15 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:15 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.656Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=275 volume=100 Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.657Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Blue in Red" Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.661Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=275 volume=100 Oct 11 19:31:15 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:15.661Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Blue in Red" Oct 11 19:31:15 rivoplus volumio[3517]: info: ------------------------------ 310ms Oct 11 19:31:15 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:15 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:15 rivoplus volumio[3517]: info: MCU Signalled Playback Active Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreCommandRouter::volumioSeek Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreStateMachine::seek Oct 11 19:31:16 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:16 rivoplus volumio[3517]: info: TRACKBLOCK {"service":"bandcamp","uri":"bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D","albumart":"https://f4.bcbits.com/img/a0336167888_16.jpg","artist":"Alex Haas","album":"STRING OF PIECES","name":"Blue in Red","title":"Blue in Red","duration":227.848,"samplerate":"128 kbps"} Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreStateMachine::startPlaybackTimer Oct 11 19:31:16 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 19:31:16 rivoplus volumio[3517]: info: ControllerMpd::seek Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:16 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:16 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:16 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:16.757Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=65000 volume=100 Oct 11 19:31:16 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:16.757Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Blue in Red" Oct 11 19:31:16 rivoplus volumio[3517]: error: null Oct 11 19:31:16 rivoplus volumio[3517]: info: Oct 11 19:31:16 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:16 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:16 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:16 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:16 rivoplus volumio[3517]: info: Oct 11 19:31:16 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:16 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:16 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:16 rivoplus volumio[3517]: info: Oct 11 19:31:16 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:16 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:16 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:16 rivoplus volumio[3517]: info: sendMpdCommand status took 13 milliseconds Oct 11 19:31:16 rivoplus volumio[3517]: info: sendMpdCommand status took 4 milliseconds Oct 11 19:31:16 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:16 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:16 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:16 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:16 rivoplus volumio[3517]: info: sendMpdCommand status took 10 milliseconds Oct 11 19:31:16 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 11 19:31:16 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 11 19:31:16 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:16 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:16 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:16 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:16 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:16 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:16 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":65000,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blue in Red","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/ab1c3b86a11febb2ea993e8ec9726d21/mp3-128/2701377319?p=0&ts=1791833451&t=fa03737da4b45be055fa733d7c9e738f2087d96a&token=1791833451_4b2d54b9a26c9e29741e1657bb7b8d1dc16bc4c5&t.mp3","trackType":"mp3"} Oct 11 19:31:16 rivoplus volumio[3517]: verbose: CURRENT POSITION 1 Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:16 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:16 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:16 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:16 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:16 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:16 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:16 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:16 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":65000,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blue in Red","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/ab1c3b86a11febb2ea993e8ec9726d21/mp3-128/2701377319?p=0&ts=1791833451&t=fa03737da4b45be055fa733d7c9e738f2087d96a&token=1791833451_4b2d54b9a26c9e29741e1657bb7b8d1dc16bc4c5&t.mp3","trackType":"mp3"} Oct 11 19:31:16 rivoplus volumio[3517]: verbose: CURRENT POSITION 1 Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:16 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:16 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:16 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:16 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:16 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:16 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:16.832Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=65000 volume=100 Oct 11 19:31:16 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:16.833Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Blue in Red" Oct 11 19:31:16 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:16.834Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=65000 volume=100 Oct 11 19:31:16 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:16.834Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Blue in Red" Oct 11 19:31:16 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:16.835Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=65000 volume=100 Oct 11 19:31:16 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:16.835Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Blue in Red" Oct 11 19:31:16 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:16.837Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=65000 volume=100 Oct 11 19:31:16 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:16.837Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Blue in Red" Oct 11 19:31:16 rivoplus volumio[3517]: info: ------------------------------ 91ms Oct 11 19:31:16 rivoplus volumio[3517]: info: ------------------------------ 82ms Oct 11 19:31:16 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 69 milliseconds Oct 11 19:31:16 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:16 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:16 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:16 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":65000,"duration":228,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Blue in Red","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/ab1c3b86a11febb2ea993e8ec9726d21/mp3-128/2701377319?p=0&ts=1791833451&t=fa03737da4b45be055fa733d7c9e738f2087d96a&token=1791833451_4b2d54b9a26c9e29741e1657bb7b8d1dc16bc4c5&t.mp3","trackType":"mp3"} Oct 11 19:31:16 rivoplus volumio[3517]: verbose: CURRENT POSITION 1 Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:16 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:16 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:16 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:16 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:16 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:16 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:16 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:16.877Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=65000 volume=100 Oct 11 19:31:16 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:16.882Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Blue in Red" Oct 11 19:31:16 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:16.883Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=65000 volume=100 Oct 11 19:31:16 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:16.884Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Blue in Red" Oct 11 19:31:16 rivoplus volumio[3517]: info: ------------------------------ 115ms Oct 11 19:31:16 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:16 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:16 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:16 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:16 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:16 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::volumioNext Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::next Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::next Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand next Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:18 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand next took 44 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:18 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:18 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:18 rivoplus volumio[3517]: error: updateQueue error: null Oct 11 19:31:18 rivoplus volumio[3517]: error: updateQueue error: null Oct 11 19:31:18 rivoplus volumio[3517]: error: updateQueue error: null Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 13ms Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand status took 10 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 9ms Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand status took 8 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 7ms Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand status took 3 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:18 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 1 Oct 11 19:31:18 rivoplus volumio[3517]: 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} Oct 11 19:31:18 rivoplus volumio[3517]: verbose: CURRENT POSITION 1 Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService stop Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::play index undefined Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:18 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:18 rivoplus volumio[3517]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current bandcamp Received mpd Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:18 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:18 rivoplus volumio[3517]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current bandcamp Received mpd Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.153Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=66251 volume=100 Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.154Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fblue-in-red-2@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Blue%20in%20Red%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fblue-in-red-2%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Blue in Red" Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.155Z level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 190.588 into Go struct field State.duration of type int" Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.156Z level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 190.588 into Go struct field State.duration of type int" Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.157Z level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 190.588 into Go struct field State.duration of type int" Oct 11 19:31:18 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::startPlaybackTimer Oct 11 19:31:18 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:18 rivoplus volumio[3517]: info: [bandcamp-play] clearAddPlayTrack: bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 68ms Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 63ms Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 61ms Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.184Z level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 190.588 into Go struct field State.duration of type int" Oct 11 19:31:18 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:18 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:18 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:18 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:18 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:18 rivoplus volumio[3517]: info: MCU Signalled Playback Inactive Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand stop took 2 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:18 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand clear took 3 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand addid "https://t4.bcbits.com/stream/8195f4e08171aada7c4599422c9c05be/mp3-128/307355626?p=0&ts=1791833451&t=7eafd9bfedf582f7ae06145950805ea21082eb46&token=1791833451_9027a443433f53692a9390e2178dbe9e04f5f7b7&t.mp3" Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:18 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:18 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:18 rivoplus volumio[3517]: error: updateQueue error: null Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:18 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 10ms Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand addid "https://t4.bcbits.com/stream/8195f4e08171aada7c4599422c9c05be/mp3-128/307355626?p=0&ts=1791833451&t=7eafd9bfedf582f7ae06145950805ea21082eb46&token=1791833451_9027a443433f53692a9390e2178dbe9e04f5f7b7&t.mp3" took 8 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 8ms Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 6ms Oct 11 19:31:18 rivoplus volumio[3517]: verbose: MPD COMMAND [object Object] Oct 11 19:31:18 rivoplus volumio[3517]: verbose: MPD COMMAND [object Object] Oct 11 19:31:18 rivoplus volumio[3517]: verbose: MPD COMMAND [object Object] Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:18 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:18 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:18 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 7ms Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 3ms Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 3ms Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand play Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:18 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces system playlist update Oct 11 19:31:18 rivoplus volumio[3517]: info: Ignoring MPD Status Update Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 5ms Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand play took 3 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 2ms Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 2ms Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand status took 20 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand status took 20 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand status took 18 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand status took 4 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand status took 2 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:18 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Just Us","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/8195f4e08171aada7c4599422c9c05be/mp3-128/307355626?p=0&ts=1791833451&t=7eafd9bfedf582f7ae06145950805ea21082eb46&token=1791833451_9027a443433f53692a9390e2178dbe9e04f5f7b7&t.mp3","trackType":"mp3"} Oct 11 19:31:18 rivoplus volumio[3517]: verbose: CURRENT POSITION 2 Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus stop Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:18 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":423,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Just Us","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/8195f4e08171aada7c4599422c9c05be/mp3-128/307355626?p=0&ts=1791833451&t=7eafd9bfedf582f7ae06145950805ea21082eb46&token=1791833451_9027a443433f53692a9390e2178dbe9e04f5f7b7&t.mp3","trackType":"mp3"} Oct 11 19:31:18 rivoplus volumio[3517]: verbose: CURRENT POSITION 2 Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:18 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:18 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Just Us","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/8195f4e08171aada7c4599422c9c05be/mp3-128/307355626?p=0&ts=1791833451&t=7eafd9bfedf582f7ae06145950805ea21082eb46&token=1791833451_9027a443433f53692a9390e2178dbe9e04f5f7b7&t.mp3","trackType":"mp3"} Oct 11 19:31:18 rivoplus volumio[3517]: verbose: CURRENT POSITION 2 Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:18 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 54ms Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.754Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.755Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Just Us" Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.756Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.757Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Just Us" Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.759Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.760Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Just Us" Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.764Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.765Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Just Us" Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 89ms Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 89ms Oct 11 19:31:18 rivoplus volumio[3517]: info: Oct 11 19:31:18 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 69 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 68 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand status took 4 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:18 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Just Us","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/8195f4e08171aada7c4599422c9c05be/mp3-128/307355626?p=0&ts=1791833451&t=7eafd9bfedf582f7ae06145950805ea21082eb46&token=1791833451_9027a443433f53692a9390e2178dbe9e04f5f7b7&t.mp3","trackType":"mp3"} Oct 11 19:31:18 rivoplus volumio[3517]: verbose: CURRENT POSITION 2 Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:18 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:18 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Just Us","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/8195f4e08171aada7c4599422c9c05be/mp3-128/307355626?p=0&ts=1791833451&t=7eafd9bfedf582f7ae06145950805ea21082eb46&token=1791833451_9027a443433f53692a9390e2178dbe9e04f5f7b7&t.mp3","trackType":"mp3"} Oct 11 19:31:18 rivoplus volumio[3517]: verbose: CURRENT POSITION 2 Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:18 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.844Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=89 volume=100 Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.844Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Just Us" Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.857Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=89 volume=100 Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.857Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Just Us" Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.860Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=89 volume=100 Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.860Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Just Us" Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.864Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=89 volume=100 Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.869Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Just Us" Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 162ms Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 159ms Oct 11 19:31:18 rivoplus volumio[3517]: info: Signalling Playback active due to playback status change Oct 11 19:31:18 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:18 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:18 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:18 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:18 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:18 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:18 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:18 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:18 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 163 milliseconds Oct 11 19:31:18 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:18 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:18 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1362,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Just Us","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/8195f4e08171aada7c4599422c9c05be/mp3-128/307355626?p=0&ts=1791833451&t=7eafd9bfedf582f7ae06145950805ea21082eb46&token=1791833451_9027a443433f53692a9390e2178dbe9e04f5f7b7&t.mp3","trackType":"mp3"} Oct 11 19:31:18 rivoplus volumio[3517]: verbose: CURRENT POSITION 2 Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:18 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:18 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:18 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.977Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=256 volume=100 Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.978Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=256 volume=100 Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.979Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Just Us" Oct 11 19:31:18 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:18.979Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Just Us" Oct 11 19:31:18 rivoplus volumio[3517]: info: ------------------------------ 199ms Oct 11 19:31:19 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:19 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:19 rivoplus volumio[3517]: info: MCU Signalled Playback Active Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreCommandRouter::volumioSeek Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreStateMachine::seek Oct 11 19:31:19 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:19 rivoplus volumio[3517]: info: TRACKBLOCK {"service":"bandcamp","uri":"bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D","albumart":"https://f4.bcbits.com/img/a0336167888_16.jpg","artist":"Alex Haas","album":"STRING OF PIECES","name":"Just Us","title":"Just Us","duration":190.588,"samplerate":"128 kbps"} Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreStateMachine::startPlaybackTimer Oct 11 19:31:19 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 19:31:19 rivoplus volumio[3517]: info: ControllerMpd::seek Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:19 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:19 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:19 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:19.892Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=55000 volume=100 Oct 11 19:31:19 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:19.893Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Just Us" Oct 11 19:31:19 rivoplus volumio[3517]: error: null Oct 11 19:31:19 rivoplus volumio[3517]: info: Oct 11 19:31:19 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:19 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:19 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:19 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:19 rivoplus volumio[3517]: info: Oct 11 19:31:19 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:19 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:19 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:19 rivoplus volumio[3517]: info: Oct 11 19:31:19 rivoplus volumio[3517]: ---------------------------- MPD announces state update: player Oct 11 19:31:19 rivoplus volumio[3517]: info: ControllerMpd::getState Oct 11 19:31:19 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand status Oct 11 19:31:19 rivoplus volumio[3517]: info: sendMpdCommand status took 13 milliseconds Oct 11 19:31:19 rivoplus volumio[3517]: info: sendMpdCommand status took 3 milliseconds Oct 11 19:31:19 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:19 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:19 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:19 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:19 rivoplus volumio[3517]: info: sendMpdCommand status took 6 milliseconds Oct 11 19:31:19 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 11 19:31:19 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 11 19:31:19 rivoplus volumio[3517]: verbose: ControllerMpd::parseState Oct 11 19:31:19 rivoplus volumio[3517]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 19:31:19 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:19 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:19 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:19 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:19 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":55000,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Just Us","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/8195f4e08171aada7c4599422c9c05be/mp3-128/307355626?p=0&ts=1791833451&t=7eafd9bfedf582f7ae06145950805ea21082eb46&token=1791833451_9027a443433f53692a9390e2178dbe9e04f5f7b7&t.mp3","trackType":"mp3"} Oct 11 19:31:19 rivoplus volumio[3517]: verbose: CURRENT POSITION 2 Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:19 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:19 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:19 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:19 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:19 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:19 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:19 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:19 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":55000,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Just Us","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/8195f4e08171aada7c4599422c9c05be/mp3-128/307355626?p=0&ts=1791833451&t=7eafd9bfedf582f7ae06145950805ea21082eb46&token=1791833451_9027a443433f53692a9390e2178dbe9e04f5f7b7&t.mp3","trackType":"mp3"} Oct 11 19:31:19 rivoplus volumio[3517]: verbose: CURRENT POSITION 2 Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:19 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:19 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:19 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:19 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:19 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:19 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:19.950Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=55000 volume=100 Oct 11 19:31:19 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:19.950Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=55000 volume=100 Oct 11 19:31:19 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:19.951Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Just Us" Oct 11 19:31:19 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:19.951Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Just Us" Oct 11 19:31:19 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:19.955Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=55000 volume=100 Oct 11 19:31:19 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:19.955Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=55000 volume=100 Oct 11 19:31:19 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:19.956Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Just Us" Oct 11 19:31:19 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:19.956Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Just Us" Oct 11 19:31:19 rivoplus volumio[3517]: info: ------------------------------ 67ms Oct 11 19:31:19 rivoplus volumio[3517]: info: ------------------------------ 58ms Oct 11 19:31:19 rivoplus volumio[3517]: info: sendMpdCommand playlistinfo took 51 milliseconds Oct 11 19:31:19 rivoplus volumio[3517]: verbose: ControllerMpd::parseTrackInfo Oct 11 19:31:19 rivoplus volumio[3517]: info: ControllerMpd::pushState Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreCommandRouter::servicePushState Oct 11 19:31:19 rivoplus volumio[3517]: info: CorePlayQueue::getTrack 2 Oct 11 19:31:19 rivoplus volumio[3517]: verbose: STATE SERVICE {"status":"play","position":0,"seek":55000,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Just Us","artist":"Alex Haas","album":"STRING OF PIECES","uri":"https://t4.bcbits.com/stream/8195f4e08171aada7c4599422c9c05be/mp3-128/307355626?p=0&ts=1791833451&t=7eafd9bfedf582f7ae06145950805ea21082eb46&token=1791833451_9027a443433f53692a9390e2178dbe9e04f5f7b7&t.mp3","trackType":"mp3"} Oct 11 19:31:19 rivoplus volumio[3517]: verbose: CURRENT POSITION 2 Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreStateMachine::syncState stateService play Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreStateMachine::syncState currentStatus play Oct 11 19:31:19 rivoplus volumio[3517]: info: Received an update from plugin. extracting info from payload Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:19 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:19 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreStateMachine::pushState Oct 11 19:31:19 rivoplus volumio[3517]: info: CoreCommandRouter::volumioPushState Oct 11 19:31:19 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output update for this device Oct 11 19:31:19 rivoplus volumio[3517]: info: MRS: Pushing multiroomSync output Oct 11 19:31:19 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:19.988Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=55104 volume=100 Oct 11 19:31:19 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:19.990Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" state=STATUS_PLAYING positionMs=55104 volume=100 Oct 11 19:31:19 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:19.991Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Just Us" Oct 11 19:31:19 rivoplus volumio5-onboarding[4629]: time=2026-10-11T19:31:19.992Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.139:49270 @ 0x2e0e1b0" id="bandcamp/track@startIndex=0@trackUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Ftrack%2Fjust-us@albumUrl=https%3A%2F%2Falexhaas.bandcamp.com%2Falbum%2Fstring-of-pieces@artistUrl=https%3A%2F%2Falexhaas.bandcamp.com@explode:o=%7B%22title%22%3A%22Just%20Us%22%2C%22artist%22%3A%22Alex%20Haas%22%2C%22album%22%3A%22STRING%20OF%20PIECES%22%2C%22albumart%22%3A%22https%3A%2F%2Ff4.bcbits.com%2Fimg%2Fa0336167888_16.jpg%22%2C%22uri%22%3A%22bandcamp%2Ftrack%40trackUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Ftrack%252Fjust-us%40albumUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%252Falbum%252Fstring-of-pieces%40artistUrl%3Dhttps%253A%252F%252Falexhaas.bandcamp.com%22%7D" title="Just Us" Oct 11 19:31:19 rivoplus volumio[3517]: info: ------------------------------ 90ms Oct 11 19:31:20 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:20 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:20 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:20 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:20 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:20 rivoplus volumio[3517]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.M8bfvcOHKRaJFwZgbcIDxqo8O7w1.21da243ce21f46462a3feb201ada946a.state.mute' Oct 11 19:31:34 rivoplus bluealsa[3418]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_6D_1B_7F_7E_94_B0, ...) Oct 11 19:31:39 rivoplus bluealsa[3418]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_6D_1B_7F_7E_94_B0, ...) Oct 11 19:31:43 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: bandcamp , handleBrowseUri Oct 11 19:31:43 rivoplus volumio[3517]: info: [bandcamp-browse] browseUri: bandcamp/fan@username=JvdH/fan@username=JvdH@view=followingArtistsAndLabels/band@bandUrl=https%3A%2F%2Fvariousartists-act.bandcamp.com Oct 11 19:31:44 rivoplus volumio[3517]: info: Preload queue cleared Oct 11 19:31:44 rivoplus volumio[3517]: info: Preload queue cleared Oct 11 19:32:14 rivoplus volumio[3517]: info: CoreCommandRouter::executeOnPlugin: bandcamp , handleBrowseUri Oct 11 19:32:14 rivoplus volumio[3517]: info: [bandcamp-browse] browseUri: bandcamp/fan@username=JvdH/fan@username=JvdH@view=followingArtistsAndLabels/band@bandUrl=https%3A%2F%2Fvariousartists-act.bandcamp.com/album@albumUrl=https%3A%2F%2Fbandcamp.com%2Falbum%2Fdoldingers-best Oct 11 19:32:15 rivoplus volumio[3517]: error: Failed to execute browseSource: Error: Failed to parse album info: data missing 'ld_json' or 'tralbum' fields. Oct 11 19:32:15 rivoplus volumio[3517]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 19:32:15 rivoplus volumio[3517]: [UnhandledPromiseRejection: This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). The promise rejected with the reason "Error: Failed to parse album info: data missing 'ld_json' or 'tralbum' fields.".] { Oct 11 19:32:15 rivoplus volumio[3517]: code: 'ERR_UNHANDLED_REJECTION' Oct 11 19:32:15 rivoplus volumio[3517]: } Oct 11 19:32:15 rivoplus volumio[3517]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 19:32:16 rivoplus sudo[469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-11 19:31' Oct 11 19:32:16 rivoplus sudo[469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="8ed05bf20f96eb8557ed5652c420f84f39d66325" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 16 16:52:40 UTC 2026" VOLUMIO_VERSION="4.208" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="ba37532a86808e19439a6278b2d45f47"