-- Logs begin at Wed 2026-02-11 04:26:49 GMT, end at Tue 2026-02-17 17:38:42 GMT. -- Feb 17 17:37:18 rivoplus volumio[3108]: verbose: New Socket.io Connection to 192.168.1.244 from 192.168.1.129 UA: Mozilla/5.0 (Linux; Android 16; SM-A366B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 17 17:37:18 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 17 17:37:18 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetVisibleSources Feb 17 17:37:18 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 17 17:37:18 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Feb 17 17:37:18 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 5 Feb 17 17:37:18 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 17 17:37:18 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:37:18 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:37:18 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:37:18 rivoplus volumio[3108]: info: Listing playlists Feb 17 17:37:18 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 17 17:37:18 rivoplus volumio[3108]: info: Received Get System Info Feb 17 17:37:18 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 17:37:18 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 17:37:18 rivoplus volumio[3108]: info: Discovery: Getting this device information Feb 17 17:37:18 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Feb 17 17:37:18 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 5 Feb 17 17:37:18 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 17:37:18 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Feb 17 17:37:18 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 5 Feb 17 17:37:18 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 17 17:37:22 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 17:37:22 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 17:37:22 rivoplus volumio[3108]: info: Discovery: Getting this device information Feb 17 17:37:22 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetState Feb 17 17:37:22 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 5 Feb 17 17:37:22 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 17:37:22 rivoplus volumio[3108]: verbose: New Socket.io Connection to 192.168.1.244:3000 from 192.168.1.129 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Feb 17 17:37:22 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 17 17:37:22 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 17 17:37:32 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Feb 17 17:37:32 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand update Feb 17 17:37:36 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 5 Feb 17 17:37:36 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 6 Feb 17 17:37:36 rivoplus volumio[3108]: info: Prefetching next song Feb 17 17:37:36 rivoplus volumio[3108]: info: Doing Prefetch in UPNP Feb 17 17:37:36 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I95877900.flac" Feb 17 17:37:36 rivoplus volumio[3108]: info: Feb 17 17:37:36 rivoplus volumio[3108]: ---------------------------- MPD announces system playlist update Feb 17 17:37:36 rivoplus volumio[3108]: info: Ignoring MPD Status Update Feb 17 17:37:36 rivoplus volumio[3108]: info: sendMpdCommand add "http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I95877900.flac" took 2 milliseconds Feb 17 17:37:36 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 17 17:37:36 rivoplus volumio[3108]: info: ------------------------------ 4ms Feb 17 17:37:36 rivoplus volumio[3108]: info: sendMpdCommand consume 1 took 2 milliseconds Feb 17 17:37:36 rivoplus volumio[3108]: info: Feb 17 17:37:36 rivoplus volumio[3108]: ---------------------------- MPD announces system playlist update Feb 17 17:37:36 rivoplus volumio[3108]: info: Ignoring MPD Status Update Feb 17 17:37:36 rivoplus volumio[3108]: info: Feb 17 17:37:36 rivoplus volumio[3108]: ---------------------------- MPD announces system playlist update Feb 17 17:37:36 rivoplus volumio[3108]: info: Ignoring MPD Status Update Feb 17 17:37:36 rivoplus volumio[3108]: info: ------------------------------ 4ms Feb 17 17:37:36 rivoplus volumio[3108]: info: ------------------------------ 3ms Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::startPlaybackTimer Feb 17 17:37:41 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 6 Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:37:41 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 6 Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:37:41 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:37:41.408Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=251 volume=100 Feb 17 17:37:41 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:37:41.408Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I95877900.flac title="Blue Bayou (2015 Remaster)" Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:37:41 rivoplus volumio[3108]: info: Feb 17 17:37:41 rivoplus volumio[3108]: ---------------------------- MPD announces system playlist update Feb 17 17:37:41 rivoplus volumio[3108]: info: Ignoring MPD Status Update Feb 17 17:37:41 rivoplus volumio[3108]: info: Feb 17 17:37:41 rivoplus volumio[3108]: ---------------------------- MPD announces state update: player Feb 17 17:37:41 rivoplus volumio[3108]: info: ControllerMpd::getState Feb 17 17:37:41 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Feb 17 17:37:41 rivoplus volumio[3108]: info: Feb 17 17:37:41 rivoplus volumio[3108]: ---------------------------- MPD announces system playlist update Feb 17 17:37:41 rivoplus volumio[3108]: info: Ignoring MPD Status Update Feb 17 17:37:41 rivoplus volumio[3108]: info: Feb 17 17:37:41 rivoplus volumio[3108]: ---------------------------- MPD announces state update: player Feb 17 17:37:41 rivoplus volumio[3108]: info: ControllerMpd::getState Feb 17 17:37:41 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Feb 17 17:37:41 rivoplus volumio[3108]: info: Feb 17 17:37:41 rivoplus volumio[3108]: ---------------------------- MPD announces system playlist update Feb 17 17:37:41 rivoplus volumio[3108]: info: Ignoring MPD Status Update Feb 17 17:37:41 rivoplus volumio[3108]: info: Feb 17 17:37:41 rivoplus volumio[3108]: ---------------------------- MPD announces state update: player Feb 17 17:37:41 rivoplus volumio[3108]: info: ControllerMpd::getState Feb 17 17:37:41 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Feb 17 17:37:41 rivoplus volumio[3108]: info: ------------------------------ 10ms Feb 17 17:37:41 rivoplus volumio[3108]: info: sendMpdCommand status took 8 milliseconds Feb 17 17:37:41 rivoplus volumio[3108]: info: ------------------------------ 8ms Feb 17 17:37:41 rivoplus volumio[3108]: info: sendMpdCommand status took 6 milliseconds Feb 17 17:37:41 rivoplus volumio[3108]: info: ------------------------------ 5ms Feb 17 17:37:41 rivoplus volumio[3108]: info: sendMpdCommand status took 4 milliseconds Feb 17 17:37:41 rivoplus volumio[3108]: verbose: ControllerMpd::parseState Feb 17 17:37:41 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 17:37:41 rivoplus volumio[3108]: verbose: ControllerMpd::parseState Feb 17 17:37:41 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 17:37:41 rivoplus volumio[3108]: verbose: ControllerMpd::parseState Feb 17 17:37:41 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 17:37:41 rivoplus volumio[3108]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 17 17:37:41 rivoplus volumio[3108]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 17 17:37:41 rivoplus volumio[3108]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 17 17:37:41 rivoplus volumio[3108]: verbose: ControllerMpd::parseTrackInfo Feb 17 17:37:41 rivoplus volumio[3108]: verbose: ControllerMpd::parseTrackInfo Feb 17 17:37:41 rivoplus volumio[3108]: verbose: ControllerMpd::parseTrackInfo Feb 17 17:37:41 rivoplus volumio[3108]: info: ControllerMpd::pushState Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::servicePushState Feb 17 17:37:41 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 6 Feb 17 17:37:41 rivoplus volumio[3108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84,"duration":234,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2741 Kbps","isStreaming":false,"title":"Blue Bayou (2015 Remaster)","artist":"Linda Ronstadt","album":"Linda Ronstadt - Greatest Hits","uri":"http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I95877900.flac","trackType":"flac"} Feb 17 17:37:41 rivoplus volumio[3108]: verbose: CURRENT POSITION 6 Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::syncState stateService play Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::syncState currentStatus play Feb 17 17:37:41 rivoplus volumio[3108]: info: Received an update from plugin. extracting info from payload Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:37:41 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 6 Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:37:41 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 6 Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:37:41 rivoplus volumio[3108]: info: ControllerMpd::pushState Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::servicePushState Feb 17 17:37:41 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 6 Feb 17 17:37:41 rivoplus volumio[3108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84,"duration":234,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2741 Kbps","isStreaming":false,"title":"Blue Bayou (2015 Remaster)","artist":"Linda Ronstadt","album":"Linda Ronstadt - Greatest Hits","uri":"http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I95877900.flac","trackType":"flac"} Feb 17 17:37:41 rivoplus volumio[3108]: verbose: CURRENT POSITION 6 Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::syncState stateService play Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::syncState currentStatus play Feb 17 17:37:41 rivoplus volumio[3108]: info: Received an update from plugin. extracting info from payload Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:37:41 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 6 Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:37:41 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 6 Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:37:41 rivoplus volumio[3108]: info: ControllerMpd::pushState Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::servicePushState Feb 17 17:37:41 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 6 Feb 17 17:37:41 rivoplus volumio[3108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84,"duration":234,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2741 Kbps","isStreaming":false,"title":"Blue Bayou (2015 Remaster)","artist":"Linda Ronstadt","album":"Linda Ronstadt - Greatest Hits","uri":"http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I95877900.flac","trackType":"flac"} Feb 17 17:37:41 rivoplus volumio[3108]: verbose: CURRENT POSITION 6 Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::syncState stateService play Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::syncState currentStatus play Feb 17 17:37:41 rivoplus volumio[3108]: info: Received an update from plugin. extracting info from payload Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:37:41 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 6 Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:37:41 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 6 Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:37:41 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:37:41.542Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=84 volume=100 Feb 17 17:37:41 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:37:41.543Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I95877900.flac title="Blue Bayou (2015 Remaster)" Feb 17 17:37:41 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:37:41.544Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=84 volume=100 Feb 17 17:37:41 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:37:41.545Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I95877900.flac title="Blue Bayou (2015 Remaster)" Feb 17 17:37:41 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:37:41.546Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=84 volume=100 Feb 17 17:37:41 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:37:41.547Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I95877900.flac title="Blue Bayou (2015 Remaster)" Feb 17 17:37:41 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:37:41.549Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=84 volume=100 Feb 17 17:37:41 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:37:41.549Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I95877900.flac title="Blue Bayou (2015 Remaster)" Feb 17 17:37:41 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:37:41.551Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=84 volume=100 Feb 17 17:37:41 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:37:41.551Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I95877900.flac title="Blue Bayou (2015 Remaster)" Feb 17 17:37:41 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:37:41.555Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=84 volume=100 Feb 17 17:37:41 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:37:41.556Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I95877900.flac title="Blue Bayou (2015 Remaster)" Feb 17 17:37:41 rivoplus volumio[3108]: info: ------------------------------ 100ms Feb 17 17:37:41 rivoplus volumio[3108]: info: ------------------------------ 98ms Feb 17 17:37:41 rivoplus volumio[3108]: info: ------------------------------ 97ms Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:37:41 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:37:41 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:37:41 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:37:41 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:37:41 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:37:41 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:37:44 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 17:37:44 rivoplus volumio[3108]: info: Preload queue cleared Feb 17 17:37:47 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 17:37:47 rivoplus volumio[3108]: info: Preload queue cleared Feb 17 17:37:51 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Feb 17 17:37:51 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand update Feb 17 17:38:01 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 17:38:01 rivoplus volumio[3108]: info: Preload queue cleared Feb 17 17:38:03 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 17:38:04 rivoplus volumio[3108]: info: Preload queue cleared Feb 17 17:38:13 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 17:38:13 rivoplus volumio[3108]: info: Preload queue cleared Feb 17 17:38:17 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 17:38:17 rivoplus volumio[3108]: info: Preload queue cleared Feb 17 17:38:17 rivoplus volumio[3108]: info: Preloading song: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97038348 Feb 17 17:38:17 rivoplus volumio[3108]: info: Preloading song: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97038860 Feb 17 17:38:17 rivoplus volumio[3108]: info: Preloading song: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97039116 Feb 17 17:38:17 rivoplus volumio[3108]: info: Preloading song: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97039372 Feb 17 17:38:17 rivoplus volumio[3108]: info: Preloading song: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97039628 Feb 17 17:38:17 rivoplus volumio[3108]: info: Preloading song: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97039884 Feb 17 17:38:17 rivoplus volumio[3108]: info: Preloading song: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97040140 Feb 17 17:38:17 rivoplus volumio[3108]: info: Preloading song: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97040396 Feb 17 17:38:17 rivoplus volumio[3108]: info: Preloading song: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97040908 Feb 17 17:38:17 rivoplus volumio[3108]: info: Preloading song: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97041164 Feb 17 17:38:17 rivoplus volumio[3108]: info: Exploding uri upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97038348 in service upnp_browser Feb 17 17:38:17 rivoplus volumio[3108]: info: Exploding uri upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97038860 in service upnp_browser Feb 17 17:38:17 rivoplus volumio[3108]: info: Exploding uri upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97039116 in service upnp_browser Feb 17 17:38:17 rivoplus volumio[3108]: info: Exploding uri upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97039372 in service upnp_browser Feb 17 17:38:17 rivoplus volumio[3108]: info: Exploding uri upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97039628 in service upnp_browser Feb 17 17:38:17 rivoplus volumio[3108]: info: Exploding uri upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97039884 in service upnp_browser Feb 17 17:38:17 rivoplus volumio[3108]: info: Exploding uri upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97040140 in service upnp_browser Feb 17 17:38:17 rivoplus volumio[3108]: info: Exploding uri upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97040396 in service upnp_browser Feb 17 17:38:17 rivoplus volumio[3108]: info: Exploding uri upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97040908 in service upnp_browser Feb 17 17:38:17 rivoplus volumio[3108]: info: Exploding uri upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97041164 in service upnp_browser Feb 17 17:38:26 rivoplus volumio[3108]: info: Executing endpoint getSimilarAlbums Feb 17 17:38:26 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Feb 17 17:38:30 rivoplus volumio[3108]: info: Preload queue cleared Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::ClearQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::stop Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::stPlaybackTimer Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::updateTrackBlock Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getTrackBlock Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 6 Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::serviceStop Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 6 Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::serviceStop Feb 17 17:38:30 rivoplus volumio[3108]: info: [1771349910074] ControllerUPNPBrowser::stop Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::clearPlayQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::saveQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::addQueueItems Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::addQueueItems Feb 17 17:38:30 rivoplus volumio[3108]: info: Preload queue cleared Feb 17 17:38:30 rivoplus volumio[3108]: info: Adding Item to queue: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97038348 Feb 17 17:38:30 rivoplus volumio[3108]: info: Using cached record of: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97038348 Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.088Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_STOPPED positionMs=0 volume=100 Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.089Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I95877900.flac title="Blue Bayou (2015 Remaster)" Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::saveQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::updateTrackBlock Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getTrackBlock Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPlay Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::play index 0 Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::addQueueItems Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::addQueueItems Feb 17 17:38:30 rivoplus volumio[3108]: info: Preload queue cleared Feb 17 17:38:30 rivoplus volumio[3108]: info: Adding Item to queue: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97038860 Feb 17 17:38:30 rivoplus volumio[3108]: info: Using cached record of: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97038860 Feb 17 17:38:30 rivoplus volumio[3108]: info: Adding Item to queue: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97039116 Feb 17 17:38:30 rivoplus volumio[3108]: info: Using cached record of: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97039116 Feb 17 17:38:30 rivoplus volumio[3108]: info: Adding Item to queue: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97039372 Feb 17 17:38:30 rivoplus volumio[3108]: info: Using cached record of: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97039372 Feb 17 17:38:30 rivoplus volumio[3108]: info: Adding Item to queue: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97039628 Feb 17 17:38:30 rivoplus volumio[3108]: info: Using cached record of: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97039628 Feb 17 17:38:30 rivoplus volumio[3108]: info: Adding Item to queue: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97039884 Feb 17 17:38:30 rivoplus volumio[3108]: info: Using cached record of: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97039884 Feb 17 17:38:30 rivoplus volumio[3108]: info: Adding Item to queue: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97040140 Feb 17 17:38:30 rivoplus volumio[3108]: info: Using cached record of: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97040140 Feb 17 17:38:30 rivoplus volumio[3108]: info: Adding Item to queue: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97040396 Feb 17 17:38:30 rivoplus volumio[3108]: info: Using cached record of: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97040396 Feb 17 17:38:30 rivoplus volumio[3108]: info: Adding Item to queue: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97040908 Feb 17 17:38:30 rivoplus volumio[3108]: info: Using cached record of: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97040908 Feb 17 17:38:30 rivoplus volumio[3108]: info: Adding Item to queue: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97041164 Feb 17 17:38:30 rivoplus volumio[3108]: info: Using cached record of: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10205R97041164 Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::stop Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::saveQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::play index undefined Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::updateTrackBlock Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getTrackBlock Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 0 Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::startPlaybackTimer Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 0 Feb 17 17:38:30 rivoplus volumio[3108]: info: [1771349910133] ControllerUPNPBrowser::clearAddPlayTrack Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 17:38:30 rivoplus volumio[3108]: info: Feb 17 17:38:30 rivoplus volumio[3108]: ---------------------------- MPD announces state update: player Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand stop took 73 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: info: ControllerMpd::getState Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand stop took 14 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand clear Feb 17 17:38:30 rivoplus volumio[3108]: info: Feb 17 17:38:30 rivoplus volumio[3108]: ---------------------------- MPD announces system playlist update Feb 17 17:38:30 rivoplus volumio[3108]: info: Ignoring MPD Status Update Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand status took 6 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand clear took 4 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::parseState Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac" Feb 17 17:38:30 rivoplus volumio[3108]: info: Feb 17 17:38:30 rivoplus volumio[3108]: ---------------------------- MPD announces state update: player Feb 17 17:38:30 rivoplus volumio[3108]: info: ControllerMpd::getState Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Feb 17 17:38:30 rivoplus volumio[3108]: info: Feb 17 17:38:30 rivoplus volumio[3108]: ---------------------------- MPD announces state update: player Feb 17 17:38:30 rivoplus volumio[3108]: info: ControllerMpd::getState Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Feb 17 17:38:30 rivoplus volumio[3108]: error: updateQueue error: null Feb 17 17:38:30 rivoplus volumio[3108]: info: ------------------------------ 9ms Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::parseTrackInfo Feb 17 17:38:30 rivoplus volumio[3108]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 17 17:38:30 rivoplus volumio[3108]: info: ------------------------------ 17ms Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac" Feb 17 17:38:30 rivoplus volumio[3108]: info: Feb 17 17:38:30 rivoplus volumio[3108]: ---------------------------- MPD announces system playlist update Feb 17 17:38:30 rivoplus volumio[3108]: info: Ignoring MPD Status Update Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand status took 22 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand status took 22 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand add "http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac" took 3 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::parseState Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::parseState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand play Feb 17 17:38:30 rivoplus volumio[3108]: info: ControllerMpd::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::servicePushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 0 Feb 17 17:38:30 rivoplus volumio[3108]: 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} Feb 17 17:38:30 rivoplus volumio[3108]: verbose: CURRENT POSITION 0 Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::syncState stateService stop Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:38:30 rivoplus volumio[3108]: info: No code Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:38:30 rivoplus volumio[3108]: info: ControllerMpd::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::servicePushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 0 Feb 17 17:38:30 rivoplus volumio[3108]: 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} Feb 17 17:38:30 rivoplus volumio[3108]: verbose: CURRENT POSITION 0 Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::syncState stateService stop Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:38:30 rivoplus volumio[3108]: info: No code Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.241Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=0 volume=100 Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.241Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I95876620.flac title="Love Has No Pride (2015 Remaster)" Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.242Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=0 volume=100 Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.243Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I95876620.flac title="Love Has No Pride (2015 Remaster)" Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.244Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=0 volume=100 Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.245Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=0 volume=100 Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.246Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=0 volume=100 Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.247Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=0 volume=100 Feb 17 17:38:30 rivoplus volumio[3108]: info: ------------------------------ 91ms Feb 17 17:38:30 rivoplus volumio[3108]: info: ------------------------------ 92ms Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.251Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I95876620.flac title="Love Has No Pride (2015 Remaster)" Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.252Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I95876620.flac title="Love Has No Pride (2015 Remaster)" Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.255Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I95876620.flac title="Love Has No Pride (2015 Remaster)" Feb 17 17:38:30 rivoplus volumio[3108]: info: Feb 17 17:38:30 rivoplus volumio[3108]: ---------------------------- MPD announces system playlist update Feb 17 17:38:30 rivoplus volumio[3108]: info: Ignoring MPD Status Update Feb 17 17:38:30 rivoplus volumio[3108]: info: Feb 17 17:38:30 rivoplus volumio[3108]: ---------------------------- MPD announces system playlist update Feb 17 17:38:30 rivoplus volumio[3108]: info: Ignoring MPD Status Update Feb 17 17:38:30 rivoplus volumio[3108]: info: Feb 17 17:38:30 rivoplus volumio[3108]: ---------------------------- MPD announces state update: player Feb 17 17:38:30 rivoplus volumio[3108]: info: ------------------------------ 80ms Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand play took 76 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: info: ControllerMpd::getState Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Feb 17 17:38:30 rivoplus volumio[3108]: info: ------------------------------ 8ms Feb 17 17:38:30 rivoplus volumio[3108]: info: ------------------------------ 7ms Feb 17 17:38:30 rivoplus volumio[3108]: info: Feb 17 17:38:30 rivoplus volumio[3108]: ---------------------------- MPD announces system playlist update Feb 17 17:38:30 rivoplus volumio[3108]: info: Ignoring MPD Status Update Feb 17 17:38:30 rivoplus volumio[3108]: info: Feb 17 17:38:30 rivoplus volumio[3108]: ---------------------------- MPD announces state update: player Feb 17 17:38:30 rivoplus volumio[3108]: info: ControllerMpd::getState Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Feb 17 17:38:30 rivoplus volumio[3108]: info: Feb 17 17:38:30 rivoplus volumio[3108]: ---------------------------- MPD announces system playlist update Feb 17 17:38:30 rivoplus volumio[3108]: info: Ignoring MPD Status Update Feb 17 17:38:30 rivoplus volumio[3108]: info: Feb 17 17:38:30 rivoplus volumio[3108]: ---------------------------- MPD announces state update: player Feb 17 17:38:30 rivoplus volumio[3108]: info: ControllerMpd::getState Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:38:30 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:38:30 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:38:30 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:38:30 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:38:30 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:38:30 rivoplus volumio[3108]: info: Feb 17 17:38:30 rivoplus volumio[3108]: ---------------------------- MPD announces system playlist update Feb 17 17:38:30 rivoplus volumio[3108]: info: Ignoring MPD Status Update Feb 17 17:38:30 rivoplus volumio[3108]: info: Feb 17 17:38:30 rivoplus volumio[3108]: ---------------------------- MPD announces state update: player Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand status took 112 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: info: ControllerMpd::getState Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Feb 17 17:38:30 rivoplus volumio[3108]: info: ------------------------------ 112ms Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand status took 110 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: info: ------------------------------ 109ms Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand status took 108 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::parseState Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::parseState Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::parseState Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 17:38:30 rivoplus volumio[3108]: info: Feb 17 17:38:30 rivoplus volumio[3108]: ---------------------------- MPD announces system playlist update Feb 17 17:38:30 rivoplus volumio[3108]: info: Ignoring MPD Status Update Feb 17 17:38:30 rivoplus volumio[3108]: info: Feb 17 17:38:30 rivoplus volumio[3108]: ---------------------------- MPD announces state update: player Feb 17 17:38:30 rivoplus volumio[3108]: info: ControllerMpd::getState Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Feb 17 17:38:30 rivoplus volumio[3108]: info: Feb 17 17:38:30 rivoplus volumio[3108]: ---------------------------- MPD announces system playlist update Feb 17 17:38:30 rivoplus volumio[3108]: info: Ignoring MPD Status Update Feb 17 17:38:30 rivoplus volumio[3108]: info: Feb 17 17:38:30 rivoplus volumio[3108]: ---------------------------- MPD announces state update: player Feb 17 17:38:30 rivoplus volumio[3108]: info: ControllerMpd::getState Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Feb 17 17:38:30 rivoplus volumio[3108]: info: MCU Signalled Playback Inactive Feb 17 17:38:30 rivoplus volumio[3108]: info: ------------------------------ 32ms Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand status took 28 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand playlistinfo took 25 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand playlistinfo took 23 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: info: ------------------------------ 22ms Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand status took 20 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: info: ------------------------------ 18ms Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand status took 16 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::parseState Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::parseTrackInfo Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::parseTrackInfo Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::parseState Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::parseState Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 17:38:30 rivoplus volumio[3108]: info: ControllerMpd::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::servicePushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 0 Feb 17 17:38:30 rivoplus volumio[3108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Spancil Hill","artist":"The Brothers Gillespie","album":"Songs from the Outlands","uri":"http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac","trackType":"flac"} Feb 17 17:38:30 rivoplus volumio[3108]: verbose: CURRENT POSITION 0 Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::syncState stateService play Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::syncState currentStatus stop Feb 17 17:38:30 rivoplus volumio[3108]: info: ControllerMpd::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::servicePushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 0 Feb 17 17:38:30 rivoplus volumio[3108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Spancil Hill","artist":"The Brothers Gillespie","album":"Songs from the Outlands","uri":"http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac","trackType":"flac"} Feb 17 17:38:30 rivoplus volumio[3108]: verbose: CURRENT POSITION 0 Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::syncState stateService play Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::syncState currentStatus play Feb 17 17:38:30 rivoplus volumio[3108]: info: Received an update from plugin. extracting info from payload Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:38:30 rivoplus volumio[3108]: info: ControllerMpd::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::servicePushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 0 Feb 17 17:38:30 rivoplus volumio[3108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":411,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"639 Kbps","isStreaming":false,"title":"Spancil Hill","artist":"The Brothers Gillespie","album":"Songs from the Outlands","uri":"http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac","trackType":"flac"} Feb 17 17:38:30 rivoplus volumio[3108]: verbose: CURRENT POSITION 0 Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::syncState stateService play Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::syncState currentStatus play Feb 17 17:38:30 rivoplus volumio[3108]: info: Received an update from plugin. extracting info from payload Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:38:30 rivoplus volumio[3108]: info: ------------------------------ 193ms Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.466Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=0 volume=100 Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.466Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac title="Spancil Hill" Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.467Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=0 volume=100 Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.468Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac title="Spancil Hill" Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.469Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=0 volume=100 Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.469Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac title="Spancil Hill" Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.471Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=0 volume=100 Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.471Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac title="Spancil Hill" Feb 17 17:38:30 rivoplus volumio[3108]: info: ------------------------------ 209ms Feb 17 17:38:30 rivoplus volumio[3108]: info: ------------------------------ 207ms Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand playlistinfo took 69 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand playlistinfo took 67 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: info: sendMpdCommand playlistinfo took 63 milliseconds Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::parseTrackInfo Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::parseTrackInfo Feb 17 17:38:30 rivoplus volumio[3108]: verbose: ControllerMpd::parseTrackInfo Feb 17 17:38:30 rivoplus volumio[3108]: info: ControllerMpd::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::servicePushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 0 Feb 17 17:38:30 rivoplus volumio[3108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"698 Kbps","isStreaming":false,"title":"Spancil Hill","artist":"The Brothers Gillespie","album":"Songs from the Outlands","uri":"http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac","trackType":"flac"} Feb 17 17:38:30 rivoplus volumio[3108]: verbose: CURRENT POSITION 0 Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::syncState stateService play Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::syncState currentStatus play Feb 17 17:38:30 rivoplus volumio[3108]: info: Received an update from plugin. extracting info from payload Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:38:30 rivoplus volumio[3108]: info: ControllerMpd::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::servicePushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 0 Feb 17 17:38:30 rivoplus volumio[3108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"698 Kbps","isStreaming":false,"title":"Spancil Hill","artist":"The Brothers Gillespie","album":"Songs from the Outlands","uri":"http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac","trackType":"flac"} Feb 17 17:38:30 rivoplus volumio[3108]: verbose: CURRENT POSITION 0 Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::syncState stateService play Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::syncState currentStatus play Feb 17 17:38:30 rivoplus volumio[3108]: info: Received an update from plugin. extracting info from payload Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:38:30 rivoplus volumio[3108]: info: ControllerMpd::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::servicePushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 0 Feb 17 17:38:30 rivoplus volumio[3108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"698 Kbps","isStreaming":false,"title":"Spancil Hill","artist":"The Brothers Gillespie","album":"Songs from the Outlands","uri":"http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac","trackType":"flac"} Feb 17 17:38:30 rivoplus volumio[3108]: verbose: CURRENT POSITION 0 Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::syncState stateService play Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::syncState currentStatus play Feb 17 17:38:30 rivoplus volumio[3108]: info: Received an update from plugin. extracting info from payload Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::pushState Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.527Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=0 volume=100 Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.527Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=0 volume=100 Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.528Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac title="Spancil Hill" Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.528Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac title="Spancil Hill" Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.528Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=0 volume=100 Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.529Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac title="Spancil Hill" Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.529Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=0 volume=100 Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.529Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac title="Spancil Hill" Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.530Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=0 volume=100 Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.530Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac title="Spancil Hill" Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.531Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" state=STATUS_PLAYING positionMs=0 volume=100 Feb 17 17:38:30 rivoplus volumio5-onboarding[3880]: time=2026-02-17T17:38:30.531Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.129:60530 @ 0x2c01080" id=http://192.168.1.110:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I97038348.flac title="Spancil Hill" Feb 17 17:38:30 rivoplus volumio[3108]: info: ------------------------------ 164ms Feb 17 17:38:30 rivoplus volumio[3108]: info: ------------------------------ 151ms Feb 17 17:38:30 rivoplus volumio[3108]: info: ------------------------------ 147ms Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CoreStateMachine::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: CorePlayQueue::getQueue Feb 17 17:38:30 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:38:30 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:38:30 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:38:30 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:38:30 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:38:30 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:38:30 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:38:30 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:38:30 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:38:30 rivoplus volumio[3108]: info: Signalling Playback active due to playback status change Feb 17 17:38:30 rivoplus volumio[3108]: info: MCU Signalled Playback Active Feb 17 17:38:31 rivoplus nmbd[3048]: [2026/02/17 17:38:31.460981, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 17 17:38:31 rivoplus nmbd[3048]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.244 for name WORKGROUP<1d>. Feb 17 17:38:31 rivoplus nmbd[3048]: This response was from IP 192.168.1.110, reporting an IP address of 192.168.1.110. Feb 17 17:38:40 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 17 17:38:40 rivoplus volumio[3108]: info: Preload queue cleared Feb 17 17:38:40 rivoplus volumio[3108]: info: Preloading song: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10217R97040652 Feb 17 17:38:40 rivoplus volumio[3108]: info: Preloading song: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10217R97041420 Feb 17 17:38:40 rivoplus volumio[3108]: info: Preloading song: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10217R97041676 Feb 17 17:38:40 rivoplus volumio[3108]: info: Preloading song: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10217R97041932 Feb 17 17:38:40 rivoplus volumio[3108]: info: Preloading song: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10217R97042188 Feb 17 17:38:40 rivoplus volumio[3108]: info: Preloading song: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10217R97042700 Feb 17 17:38:40 rivoplus volumio[3108]: info: Preloading song: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10217R97042956 Feb 17 17:38:40 rivoplus volumio[3108]: info: Preloading song: upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10217R97043212 Feb 17 17:38:40 rivoplus volumio[3108]: info: Exploding uri upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10217R97040652 in service upnp_browser Feb 17 17:38:40 rivoplus volumio[3108]: info: Exploding uri upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10217R97041420 in service upnp_browser Feb 17 17:38:40 rivoplus volumio[3108]: info: Exploding uri upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10217R97041676 in service upnp_browser Feb 17 17:38:40 rivoplus volumio[3108]: info: Exploding uri upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10217R97041932 in service upnp_browser Feb 17 17:38:40 rivoplus volumio[3108]: info: Exploding uri upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10217R97042188 in service upnp_browser Feb 17 17:38:40 rivoplus volumio[3108]: info: Exploding uri upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10217R97042700 in service upnp_browser Feb 17 17:38:40 rivoplus volumio[3108]: info: Exploding uri upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10217R97042956 in service upnp_browser Feb 17 17:38:40 rivoplus volumio[3108]: info: Exploding uri upnp/http://192.168.1.110:9000/dev0/srv1/control@0$1$15$245$10203$10217R97043212 in service upnp_browser Feb 17 17:38:41 rivoplus volumio[3108]: info: Executing endpoint getSimilarAlbums Feb 17 17:38:41 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Feb 17 17:38:42 rivoplus volumio[3108]: info: Executing endpoint metavolumio Feb 17 17:38:42 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 17 17:38:42 rivoplus volumio[3108]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 17:38:42 rivoplus volumio[3108]: TypeError: Cannot read property 'slice' of null Feb 17 17:38:42 rivoplus volumio[3108]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Feb 17 17:38:42 rivoplus volumio[3108]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Feb 17 17:38:42 rivoplus volumio[3108]: at Object.onceWrapper (events.js:520:26) Feb 17 17:38:42 rivoplus volumio[3108]: at ClientRequest.emit (events.js:400:28) Feb 17 17:38:42 rivoplus volumio[3108]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Feb 17 17:38:42 rivoplus volumio[3108]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Feb 17 17:38:42 rivoplus volumio[3108]: at HTTPParser.execute () Feb 17 17:38:42 rivoplus volumio[3108]: at TLSSocket.socketOnData (_http_client.js:515:22) Feb 17 17:38:42 rivoplus volumio[3108]: at TLSSocket.emit (events.js:400:28) Feb 17 17:38:42 rivoplus volumio[3108]: at addChunk (internal/streams/readable.js:293:12) Feb 17 17:38:42 rivoplus volumio[3108]: at readableAddChunk (internal/streams/readable.js:267:9) Feb 17 17:38:42 rivoplus volumio[3108]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Feb 17 17:38:42 rivoplus volumio[3108]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Feb 17 17:38:42 rivoplus volumio[3108]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 17:38:42 rivoplus sudo[32142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-17 17:37 Feb 17 17:38:42 rivoplus sudo[32142]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 02:06:11 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="6fcef4b36db553cbf88901935dbe83e3"