-- Logs begin at Wed 2026-03-04 13:19:25 CET, end at Wed 2026-03-04 13:53:08 CET. -- Mar 04 13:52:00 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 2 Mar 04 13:52:00 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:00 rivoplus volumio[3299]: info: Prefetching next song Mar 04 13:52:00 rivoplus volumio[3299]: info: [1772628720499] ControllerQobuz::prefetch Mar 04 13:52:00 rivoplus volumio[3299]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/971991" Mar 04 13:52:00 rivoplus volumio[3299]: STREAMING PROXY: Handling url /?data=qobuz://song/971991 Mar 04 13:52:00 rivoplus volumio[3299]: info: Executing endpoint getStreamUrlqobuz Mar 04 13:52:00 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 04 13:52:00 rivoplus dbus-daemon[2835]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 13:52:00 rivoplus volumio[3299]: info: Discovery: Restarting Browsing Mar 04 13:52:01 rivoplus volumio[3299]: info: getStreamUrl took 546 milliseconds Mar 04 13:52:01 rivoplus volumio[3299]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1294363&eid=971991&fmt=6&profile=raw&app_id=539451548&cid=3958002&etsp=1772632320&hmac=czxumPy0gZQlKFxg0qza1VzMisg Mar 04 13:52:01 rivoplus volumio[3299]: STREAMING PROXY: Requesting upstream with range: none Mar 04 13:52:01 rivoplus volumio[3299]: STREAMING PROXY: Upstream status code: 200 Mar 04 13:52:01 rivoplus volumio[3299]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/971991" Mar 04 13:52:01 rivoplus volumio[3299]: STREAMING PROXY: Client closed connection Mar 04 13:52:01 rivoplus volumio[3299]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/971991" took 2 milliseconds Mar 04 13:52:01 rivoplus volumio[3299]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 13:52:01 rivoplus volumio[3299]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 04 13:52:01 rivoplus volumio[3299]: info: Mar 04 13:52:01 rivoplus volumio[3299]: ---------------------------- MPD announces system playlist update Mar 04 13:52:01 rivoplus volumio[3299]: info: Ignoring MPD Status Update Mar 04 13:52:01 rivoplus volumio[3299]: info: sendMpdCommand consume 1 took 2 milliseconds Mar 04 13:52:01 rivoplus volumio[3299]: info: Mar 04 13:52:01 rivoplus volumio[3299]: ---------------------------- MPD announces system playlist update Mar 04 13:52:01 rivoplus volumio[3299]: info: Ignoring MPD Status Update Mar 04 13:52:01 rivoplus volumio[3299]: info: Mar 04 13:52:01 rivoplus volumio[3299]: ---------------------------- MPD announces system playlist update Mar 04 13:52:01 rivoplus volumio[3299]: info: Ignoring MPD Status Update Mar 04 13:52:01 rivoplus volumio[3299]: STREAMING PROXY: Upstream closed. Received 15715 bytes Mar 04 13:52:01 rivoplus volumio[3299]: STREAMING PROXY: Incomplete transfer. Expected 4133262, got 15715 Mar 04 13:52:01 rivoplus volumio[3299]: STREAMING PROXY: Retrying with range: bytes=15715- (attempt 1) Mar 04 13:52:01 rivoplus volumio[3299]: STREAMING PROXY: Handling url /?data=qobuz://song/971991 Mar 04 13:52:01 rivoplus volumio[3299]: info: ------------------------------ 12ms Mar 04 13:52:01 rivoplus volumio[3299]: info: ------------------------------ 10ms Mar 04 13:52:01 rivoplus volumio[3299]: info: ------------------------------ 9ms Mar 04 13:52:01 rivoplus volumio[3299]: info: Executing endpoint getStreamUrlqobuz Mar 04 13:52:01 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 04 13:52:01 rivoplus volumio[3299]: info: getStreamUrl took 365 milliseconds Mar 04 13:52:01 rivoplus volumio[3299]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1294363&eid=971991&fmt=6&profile=raw&app_id=539451548&cid=3958002&etsp=1772632321&hmac=WBX-rxGBt2oj-G1Zydsdp905zc0 Mar 04 13:52:01 rivoplus volumio[3299]: STREAMING PROXY: Requesting upstream with range: none Mar 04 13:52:01 rivoplus volumio[3299]: STREAMING PROXY: Upstream status code: 200 Mar 04 13:52:01 rivoplus volumio[3299]: STREAMING PROXY: Upstream closed. Received 4133262 bytes Mar 04 13:52:01 rivoplus volumio[3299]: STREAMING PROXY: Client closed connection Mar 04 13:52:02 rivoplus volumio[3299]: STREAMING PROXY: Resuming with range: bytes=15715- Mar 04 13:52:02 rivoplus volumio[3299]: STREAMING PROXY: Resume upstream status code: 206 Mar 04 13:52:02 rivoplus volumio[3299]: STREAMING PROXY: Resume ended. Received 4117547 bytes Mar 04 13:52:05 rivoplus volumio[3299]: info: CoreStateMachine::startPlaybackTimer Mar 04 13:52:05 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:05 rivoplus volumio[3299]: info: CoreStateMachine::pushState Mar 04 13:52:05 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:05 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 13:52:05 rivoplus volumio[3299]: info: CoreCommandRouter::volumioPushState Mar 04 13:52:05 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:05 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:05 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:05.419+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" state=STATUS_PLAYING positionMs=251 volume=100 Mar 04 13:52:05 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:05.420+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" id=qobuz://song/971991 title="Concerto in G Minor \"la Notte\", Rv439 : IV. Presto" Mar 04 13:52:05 rivoplus volumio[3299]: info: Signalling Playback active due to playback status change Mar 04 13:52:05 rivoplus volumio[3299]: info: Updating RAAT Signal Path Mar 04 13:52:05 rivoplus dbus-daemon[2835]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 13:52:05 rivoplus volumio[3299]: info: Discovery: Restarting Browsing Mar 04 13:52:06 rivoplus volumio[3299]: info: Mar 04 13:52:06 rivoplus volumio[3299]: ---------------------------- MPD announces system playlist update Mar 04 13:52:06 rivoplus volumio[3299]: info: Ignoring MPD Status Update Mar 04 13:52:06 rivoplus volumio[3299]: info: Mar 04 13:52:06 rivoplus volumio[3299]: ---------------------------- MPD announces state update: player Mar 04 13:52:06 rivoplus volumio[3299]: info: ControllerMpd::getState Mar 04 13:52:06 rivoplus volumio[3299]: verbose: ControllerMpd::sendMpdCommand status Mar 04 13:52:06 rivoplus volumio[3299]: info: ------------------------------ 2ms Mar 04 13:52:06 rivoplus volumio[3299]: info: sendMpdCommand status took 2 milliseconds Mar 04 13:52:06 rivoplus volumio[3299]: verbose: ControllerMpd::parseState Mar 04 13:52:06 rivoplus volumio[3299]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 13:52:06 rivoplus volumio[3299]: info: Mar 04 13:52:06 rivoplus volumio[3299]: ---------------------------- MPD announces system playlist update Mar 04 13:52:06 rivoplus volumio[3299]: info: Ignoring MPD Status Update Mar 04 13:52:06 rivoplus volumio[3299]: info: Mar 04 13:52:06 rivoplus volumio[3299]: ---------------------------- MPD announces state update: player Mar 04 13:52:06 rivoplus volumio[3299]: info: ControllerMpd::getState Mar 04 13:52:06 rivoplus volumio[3299]: verbose: ControllerMpd::sendMpdCommand status Mar 04 13:52:06 rivoplus volumio[3299]: info: Mar 04 13:52:06 rivoplus volumio[3299]: ---------------------------- MPD announces system playlist update Mar 04 13:52:06 rivoplus volumio[3299]: info: Ignoring MPD Status Update Mar 04 13:52:06 rivoplus volumio[3299]: info: Mar 04 13:52:06 rivoplus volumio[3299]: ---------------------------- MPD announces state update: player Mar 04 13:52:06 rivoplus volumio[3299]: info: ControllerMpd::getState Mar 04 13:52:06 rivoplus volumio[3299]: verbose: ControllerMpd::sendMpdCommand status Mar 04 13:52:06 rivoplus volumio[3299]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 04 13:52:06 rivoplus volumio[3299]: info: ------------------------------ 12ms Mar 04 13:52:06 rivoplus volumio[3299]: info: sendMpdCommand status took 12 milliseconds Mar 04 13:52:06 rivoplus volumio[3299]: info: ------------------------------ 11ms Mar 04 13:52:06 rivoplus volumio[3299]: info: sendMpdCommand status took 10 milliseconds Mar 04 13:52:06 rivoplus volumio[3299]: verbose: ControllerMpd::parseTrackInfo Mar 04 13:52:06 rivoplus volumio[3299]: verbose: ControllerMpd::parseState Mar 04 13:52:06 rivoplus volumio[3299]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 13:52:06 rivoplus volumio[3299]: verbose: ControllerMpd::parseState Mar 04 13:52:06 rivoplus volumio[3299]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 13:52:06 rivoplus volumio[3299]: info: ControllerMpd::pushState Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreCommandRouter::servicePushState Mar 04 13:52:06 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:06 rivoplus volumio[3299]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":52,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"762 Kbps","isStreaming":false,"title":"971991","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/971991","trackType":"qobuz"} Mar 04 13:52:06 rivoplus volumio[3299]: verbose: CURRENT POSITION 3 Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreStateMachine::syncState stateService play Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreStateMachine::syncState currentStatus play Mar 04 13:52:06 rivoplus volumio[3299]: info: Received an update from plugin. extracting info from payload Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreStateMachine::pushState Mar 04 13:52:06 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreCommandRouter::volumioPushState Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:06 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreStateMachine::pushState Mar 04 13:52:06 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreCommandRouter::volumioPushState Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:06 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:06 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:06.090+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" state=STATUS_PLAYING positionMs=68 volume=100 Mar 04 13:52:06 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:06.091+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" state=STATUS_PLAYING positionMs=68 volume=100 Mar 04 13:52:06 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:06.091+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" id=qobuz://song/971991 title="Concerto in G Minor \"la Notte\", Rv439 : IV. Presto" Mar 04 13:52:06 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:06.092+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" id=qobuz://song/971991 title="Concerto in G Minor \"la Notte\", Rv439 : IV. Presto" Mar 04 13:52:06 rivoplus volumio[3299]: info: ------------------------------ 41ms Mar 04 13:52:06 rivoplus volumio[3299]: info: sendMpdCommand playlistinfo took 26 milliseconds Mar 04 13:52:06 rivoplus volumio[3299]: info: sendMpdCommand playlistinfo took 27 milliseconds Mar 04 13:52:06 rivoplus volumio[3299]: verbose: ControllerMpd::parseTrackInfo Mar 04 13:52:06 rivoplus volumio[3299]: verbose: ControllerMpd::parseTrackInfo Mar 04 13:52:06 rivoplus volumio[3299]: info: ControllerMpd::pushState Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreCommandRouter::servicePushState Mar 04 13:52:06 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:06 rivoplus volumio[3299]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":52,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"762 Kbps","isStreaming":false,"title":"971991","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/971991","trackType":"qobuz"} Mar 04 13:52:06 rivoplus volumio[3299]: verbose: CURRENT POSITION 3 Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreStateMachine::syncState stateService play Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreStateMachine::syncState currentStatus play Mar 04 13:52:06 rivoplus volumio[3299]: info: Received an update from plugin. extracting info from payload Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreStateMachine::pushState Mar 04 13:52:06 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreCommandRouter::volumioPushState Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:06 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreStateMachine::pushState Mar 04 13:52:06 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreCommandRouter::volumioPushState Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:06 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:06 rivoplus volumio[3299]: info: ControllerMpd::pushState Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreCommandRouter::servicePushState Mar 04 13:52:06 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:06 rivoplus volumio[3299]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":52,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"762 Kbps","isStreaming":false,"title":"971991","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/971991","trackType":"qobuz"} Mar 04 13:52:06 rivoplus volumio[3299]: verbose: CURRENT POSITION 3 Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreStateMachine::syncState stateService play Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreStateMachine::syncState currentStatus play Mar 04 13:52:06 rivoplus volumio[3299]: info: Received an update from plugin. extracting info from payload Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreStateMachine::pushState Mar 04 13:52:06 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreCommandRouter::volumioPushState Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:06 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreStateMachine::pushState Mar 04 13:52:06 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreCommandRouter::volumioPushState Mar 04 13:52:06 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:06 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:06 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:06.134+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" state=STATUS_PLAYING positionMs=68 volume=100 Mar 04 13:52:06 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:06.135+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" id=qobuz://song/971991 title="Concerto in G Minor \"la Notte\", Rv439 : IV. Presto" Mar 04 13:52:06 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:06.136+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" state=STATUS_PLAYING positionMs=68 volume=100 Mar 04 13:52:06 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:06.136+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" id=qobuz://song/971991 title="Concerto in G Minor \"la Notte\", Rv439 : IV. Presto" Mar 04 13:52:06 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:06.138+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" state=STATUS_PLAYING positionMs=68 volume=100 Mar 04 13:52:06 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:06.138+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" id=qobuz://song/971991 title="Concerto in G Minor \"la Notte\", Rv439 : IV. Presto" Mar 04 13:52:06 rivoplus volumio[3299]: info: ------------------------------ 89ms Mar 04 13:52:06 rivoplus volumio[3299]: info: ------------------------------ 89ms Mar 04 13:52:06 rivoplus volumio[3299]: info: Signalling Playback active due to playback status change Mar 04 13:52:06 rivoplus volumio[3299]: info: Signalling Playback active due to playback status change Mar 04 13:52:06 rivoplus volumio[3299]: info: Signalling Playback active due to playback status change Mar 04 13:52:06 rivoplus volumio[3299]: info: Signalling Playback active due to playback status change Mar 04 13:52:06 rivoplus volumio[3299]: info: Signalling Playback active due to playback status change Mar 04 13:52:06 rivoplus volumio[3299]: info: Updating RAAT Signal Path Mar 04 13:52:06 rivoplus volumio[3299]: info: Updating RAAT Signal Path Mar 04 13:52:06 rivoplus volumio[3299]: info: Updating RAAT Signal Path Mar 04 13:52:06 rivoplus volumio[3299]: info: Updating RAAT Signal Path Mar 04 13:52:06 rivoplus volumio[3299]: info: Updating RAAT Signal Path Mar 04 13:52:06 rivoplus volumio[3299]: info: Updating RAAT Signal Path Mar 04 13:52:10 rivoplus dbus-daemon[2835]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 13:52:10 rivoplus volumio[3299]: info: Discovery: Restarting Browsing Mar 04 13:52:13 rivoplus volumio[3299]: verbose: New Socket.io Connection to 192.168.1.37 from 192.168.1.47 UA: Mozilla/5.0 (Linux; Android 13; REA-NX9 Build/HONORREA-N39; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 04 13:52:13 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 04 13:52:13 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetVisibleSources Mar 04 13:52:13 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 13:52:13 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:13 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:13 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 04 13:52:13 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetQueue Mar 04 13:52:13 rivoplus volumio[3299]: info: CoreStateMachine::getQueue Mar 04 13:52:13 rivoplus volumio[3299]: info: CorePlayQueue::getQueue Mar 04 13:52:13 rivoplus volumio[3299]: info: Listing playlists Mar 04 13:52:13 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 04 13:52:13 rivoplus volumio[3299]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 04 13:52:13 rivoplus volumio[3299]: info: Received Get System Info Mar 04 13:52:13 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 13:52:13 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 13:52:13 rivoplus volumio[3299]: info: Discovery: Getting this device information Mar 04 13:52:13 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:13 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:13 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 13:52:13 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:13 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:13 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 04 13:52:15 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:15.410+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.47:42876 @ 0x35200f0" latency=-712.336922ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 04 13:52:15 rivoplus dbus-daemon[2835]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 13:52:15 rivoplus volumio[3299]: info: Discovery: Restarting Browsing Mar 04 13:52:16 rivoplus sudo[11937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 13:52:16 rivoplus sudo[11937]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 13:52:16 rivoplus sudo[11940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 13:52:16 rivoplus sudo[11940]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 13:52:16 rivoplus sudo[11937]: pam_unix(sudo:session): session closed for user root Mar 04 13:52:16 rivoplus sudo[11940]: pam_unix(sudo:session): session closed for user root Mar 04 13:52:16 rivoplus volumio[3299]: verbose: New Socket.io Connection to 192.168.1.37 from 192.168.1.47 UA: Mozilla/5.0 (Linux; Android 13; REA-NX9 Build/HONORREA-N39; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 04 13:52:16 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 04 13:52:16 rivoplus sudo[11947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 13:52:16 rivoplus sudo[11947]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 13:52:16 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 04 13:52:16 rivoplus sudo[11950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 13:52:16 rivoplus sudo[11950]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 13:52:16 rivoplus sudo[11947]: pam_unix(sudo:session): session closed for user root Mar 04 13:52:16 rivoplus sudo[11950]: pam_unix(sudo:session): session closed for user root Mar 04 13:52:16 rivoplus volumio[3299]: verbose: New Socket.io Connection to 192.168.1.37 from 192.168.1.47 UA: Mozilla/5.0 (Linux; Android 13; REA-NX9 Build/HONORREA-N39; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Mar 04 13:52:17 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 04 13:52:17 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetVisibleSources Mar 04 13:52:17 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 13:52:17 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:17 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:17 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 04 13:52:17 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetQueue Mar 04 13:52:17 rivoplus volumio[3299]: info: CoreStateMachine::getQueue Mar 04 13:52:17 rivoplus volumio[3299]: info: CorePlayQueue::getQueue Mar 04 13:52:17 rivoplus volumio[3299]: info: Listing playlists Mar 04 13:52:17 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 04 13:52:17 rivoplus volumio[3299]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 04 13:52:17 rivoplus volumio[3299]: info: Received Get System Info Mar 04 13:52:17 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 13:52:17 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 13:52:17 rivoplus volumio[3299]: info: Discovery: Getting this device information Mar 04 13:52:17 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:17 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:17 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 13:52:17 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:17 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:17 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 04 13:52:17 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 04 13:52:17 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 04 13:52:18 rivoplus go-librespot[3928]: time="2026-03-04T13:52:18+01:00" level=trace msg="received accesspoint ping" Mar 04 13:52:18 rivoplus go-librespot[3928]: time="2026-03-04T13:52:18+01:00" level=trace msg="received accesspoint pong ack" Mar 04 13:52:18 rivoplus go-librespot[3928]: time="2026-03-04T13:52:18+01:00" level=trace msg="sent dealer ping" Mar 04 13:52:18 rivoplus go-librespot[3928]: time="2026-03-04T13:52:18+01:00" level=trace msg="received dealer pong" Mar 04 13:52:18 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 04 13:52:18 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 13:52:18 rivoplus volumio[3299]: info: Received Get System Info Mar 04 13:52:18 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 13:52:18 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 13:52:18 rivoplus volumio[3299]: info: Discovery: Getting this device information Mar 04 13:52:18 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:18 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:18 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 13:52:19 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 13:52:19 rivoplus volumio[3299]: info: Received Get System Info Mar 04 13:52:19 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 13:52:19 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 13:52:19 rivoplus volumio[3299]: info: Discovery: Getting this device information Mar 04 13:52:19 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:19 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:19 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 13:52:20 rivoplus dbus-daemon[2835]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 13:52:20 rivoplus volumio[3299]: info: Discovery: Restarting Browsing Mar 04 13:52:25 rivoplus dbus-daemon[2835]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 13:52:25 rivoplus volumio[3299]: info: Discovery: Restarting Browsing Mar 04 13:52:26 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 04 13:52:30 rivoplus dbus-daemon[2835]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 13:52:30 rivoplus volumio[3299]: info: Discovery: Restarting Browsing Mar 04 13:52:32 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Mar 04 13:52:33 rivoplus volumio[3299]: info: handleBrowseUri took 620 milliseconds Mar 04 13:52:33 rivoplus volumio[3299]: info: Preload queue cleared Mar 04 13:52:33 rivoplus volumio[3299]: info: Preloading song: qobuz://song/530658 Mar 04 13:52:33 rivoplus volumio[3299]: info: Preloading song: qobuz://song/530659 Mar 04 13:52:33 rivoplus volumio[3299]: info: Preloading song: qobuz://song/530660 Mar 04 13:52:33 rivoplus volumio[3299]: info: Preloading song: qobuz://song/530661 Mar 04 13:52:33 rivoplus volumio[3299]: info: Preloading song: qobuz://song/530662 Mar 04 13:52:33 rivoplus volumio[3299]: info: Preloading song: qobuz://song/530663 Mar 04 13:52:33 rivoplus volumio[3299]: info: Preloading song: qobuz://song/530664 Mar 04 13:52:33 rivoplus volumio[3299]: info: Exploding uri qobuz://song/530658 in service qobuz Mar 04 13:52:33 rivoplus volumio[3299]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 04 13:52:33 rivoplus volumio[3299]: info: Exploding uri qobuz://song/530659 in service qobuz Mar 04 13:52:33 rivoplus volumio[3299]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 04 13:52:33 rivoplus volumio[3299]: info: No valid Plugin REST Endpoint: getSimilarAlbums Mar 04 13:52:33 rivoplus volumio[3299]: info: Exploding uri qobuz://song/530660 in service qobuz Mar 04 13:52:33 rivoplus volumio[3299]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 04 13:52:33 rivoplus volumio[3299]: info: Exploding uri qobuz://song/530661 in service qobuz Mar 04 13:52:33 rivoplus volumio[3299]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 04 13:52:33 rivoplus volumio[3299]: info: Exploding uri qobuz://song/530662 in service qobuz Mar 04 13:52:33 rivoplus volumio[3299]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 04 13:52:33 rivoplus volumio[3299]: info: Exploding uri qobuz://song/530663 in service qobuz Mar 04 13:52:33 rivoplus volumio[3299]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 04 13:52:33 rivoplus volumio[3299]: info: Exploding uri qobuz://song/530664 in service qobuz Mar 04 13:52:33 rivoplus volumio[3299]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 04 13:52:33 rivoplus volumio[3299]: info: explodeUri took 397 milliseconds Mar 04 13:52:34 rivoplus volumio[3299]: info: explodeUri took 433 milliseconds Mar 04 13:52:34 rivoplus volumio[3299]: info: explodeUri took 430 milliseconds Mar 04 13:52:34 rivoplus volumio[3299]: info: explodeUri took 414 milliseconds Mar 04 13:52:34 rivoplus volumio[3299]: info: explodeUri took 389 milliseconds Mar 04 13:52:34 rivoplus volumio[3299]: info: explodeUri took 421 milliseconds Mar 04 13:52:34 rivoplus volumio[3299]: info: explodeUri took 381 milliseconds Mar 04 13:52:34 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 04 13:52:34 rivoplus volumio[3299]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 04 13:52:34 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 04 13:52:34 rivoplus volumio[3299]: info: Received Get System Version Mar 04 13:52:34 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 13:52:34 rivoplus volumio[3299]: info: Received Get System Info Mar 04 13:52:34 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 13:52:34 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 13:52:34 rivoplus volumio[3299]: info: Discovery: Getting this device information Mar 04 13:52:34 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:34 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:34 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 13:52:35 rivoplus dbus-daemon[2835]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 13:52:35 rivoplus volumio[3299]: info: Discovery: Restarting Browsing Mar 04 13:52:40 rivoplus dbus-daemon[2835]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 13:52:40 rivoplus volumio[3299]: info: Discovery: Restarting Browsing Mar 04 13:52:45 rivoplus dbus-daemon[2835]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 13:52:45 rivoplus volumio[3299]: info: Discovery: Restarting Browsing Mar 04 13:52:48 rivoplus go-librespot[3928]: time="2026-03-04T13:52:48+01:00" level=trace msg="sent dealer ping" Mar 04 13:52:48 rivoplus go-librespot[3928]: time="2026-03-04T13:52:48+01:00" level=trace msg="received dealer pong" Mar 04 13:52:50 rivoplus dbus-daemon[2835]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 13:52:50 rivoplus volumio[3299]: info: Discovery: Restarting Browsing Mar 04 13:52:52 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 3 Mar 04 13:52:52 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:52 rivoplus volumio[3299]: info: Prefetching next song Mar 04 13:52:52 rivoplus volumio[3299]: info: [1772628772183] ControllerQobuz::prefetch Mar 04 13:52:52 rivoplus volumio[3299]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/971992" Mar 04 13:52:52 rivoplus volumio[3299]: STREAMING PROXY: Handling url /?data=qobuz://song/971992 Mar 04 13:52:52 rivoplus volumio[3299]: info: Executing endpoint getStreamUrlqobuz Mar 04 13:52:52 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 04 13:52:52 rivoplus volumio[3299]: info: getStreamUrl took 472 milliseconds Mar 04 13:52:52 rivoplus volumio[3299]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1294363&eid=971992&fmt=6&profile=raw&app_id=539451548&cid=3958002&etsp=1772632372&hmac=z2A4We92eK7A1L6CY8TINHyZQ24 Mar 04 13:52:52 rivoplus volumio[3299]: STREAMING PROXY: Requesting upstream with range: none Mar 04 13:52:52 rivoplus volumio[3299]: STREAMING PROXY: Upstream status code: 200 Mar 04 13:52:52 rivoplus volumio[3299]: STREAMING PROXY: Client closed connection Mar 04 13:52:52 rivoplus volumio[3299]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/971992" Mar 04 13:52:52 rivoplus volumio[3299]: STREAMING PROXY: Upstream closed. Received 15716 bytes Mar 04 13:52:52 rivoplus volumio[3299]: STREAMING PROXY: Incomplete transfer. Expected 9448540, got 15716 Mar 04 13:52:52 rivoplus volumio[3299]: STREAMING PROXY: Retrying with range: bytes=15716- (attempt 1) Mar 04 13:52:52 rivoplus volumio[3299]: info: Mar 04 13:52:52 rivoplus volumio[3299]: ---------------------------- MPD announces system playlist update Mar 04 13:52:52 rivoplus volumio[3299]: info: Ignoring MPD Status Update Mar 04 13:52:52 rivoplus volumio[3299]: STREAMING PROXY: Handling url /?data=qobuz://song/971992 Mar 04 13:52:52 rivoplus volumio[3299]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/971992" took 5 milliseconds Mar 04 13:52:52 rivoplus volumio[3299]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 13:52:52 rivoplus volumio[3299]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 04 13:52:52 rivoplus volumio[3299]: info: Mar 04 13:52:52 rivoplus volumio[3299]: ---------------------------- MPD announces system playlist update Mar 04 13:52:52 rivoplus volumio[3299]: info: Ignoring MPD Status Update Mar 04 13:52:52 rivoplus volumio[3299]: info: Mar 04 13:52:52 rivoplus volumio[3299]: ---------------------------- MPD announces system playlist update Mar 04 13:52:52 rivoplus volumio[3299]: info: Ignoring MPD Status Update Mar 04 13:52:52 rivoplus volumio[3299]: info: ------------------------------ 11ms Mar 04 13:52:52 rivoplus volumio[3299]: info: sendMpdCommand consume 1 took 7 milliseconds Mar 04 13:52:52 rivoplus volumio[3299]: info: ------------------------------ 6ms Mar 04 13:52:52 rivoplus volumio[3299]: info: ------------------------------ 5ms Mar 04 13:52:52 rivoplus volumio[3299]: info: Executing endpoint getStreamUrlqobuz Mar 04 13:52:52 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 04 13:52:53 rivoplus volumio[3299]: info: getStreamUrl took 376 milliseconds Mar 04 13:52:53 rivoplus volumio[3299]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1294363&eid=971992&fmt=6&profile=raw&app_id=539451548&cid=3958002&etsp=1772632373&hmac=fAyX-crJI9aWT9YjbkWf7yrmnHY Mar 04 13:52:53 rivoplus volumio[3299]: STREAMING PROXY: Requesting upstream with range: none Mar 04 13:52:53 rivoplus volumio[3299]: STREAMING PROXY: Upstream status code: 200 Mar 04 13:52:53 rivoplus volumio[3299]: STREAMING PROXY: Upstream closed. Received 9448540 bytes Mar 04 13:52:53 rivoplus volumio[3299]: STREAMING PROXY: Client closed connection Mar 04 13:52:53 rivoplus volumio[3299]: STREAMING PROXY: Resuming with range: bytes=15716- Mar 04 13:52:53 rivoplus volumio[3299]: STREAMING PROXY: Resume upstream status code: 206 Mar 04 13:52:54 rivoplus volumio[3299]: STREAMING PROXY: Resume ended. Received 9432824 bytes Mar 04 13:52:54 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 13:52:54 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 13:52:54 rivoplus volumio[3299]: info: Discovery: Getting this device information Mar 04 13:52:54 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:54 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 13:52:54 rivoplus volumio[3299]: verbose: New Socket.io Connection to 192.168.1.37:3000 from 192.168.1.47 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Mar 04 13:52:54 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 04 13:52:54 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 04 13:52:55 rivoplus dbus-daemon[2835]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 13:52:55 rivoplus volumio[3299]: info: Discovery: Restarting Browsing Mar 04 13:52:56 rivoplus volumio[3299]: info: CoreStateMachine::startPlaybackTimer Mar 04 13:52:56 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:56 rivoplus volumio[3299]: info: CoreStateMachine::pushState Mar 04 13:52:56 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:56 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreCommandRouter::volumioPushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:57 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:57 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:57.008+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" state=STATUS_PLAYING positionMs=251 volume=100 Mar 04 13:52:57 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:57.008+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" id=qobuz://song/971992 title="Concerto in G Minor \"la Notte\", Rv439 : V. Largo" Mar 04 13:52:57 rivoplus volumio[3299]: info: Signalling Playback active due to playback status change Mar 04 13:52:57 rivoplus volumio[3299]: info: Updating RAAT Signal Path Mar 04 13:52:57 rivoplus volumio[3299]: info: Mar 04 13:52:57 rivoplus volumio[3299]: ---------------------------- MPD announces system playlist update Mar 04 13:52:57 rivoplus volumio[3299]: info: Ignoring MPD Status Update Mar 04 13:52:57 rivoplus volumio[3299]: info: Mar 04 13:52:57 rivoplus volumio[3299]: ---------------------------- MPD announces state update: player Mar 04 13:52:57 rivoplus volumio[3299]: info: ControllerMpd::getState Mar 04 13:52:57 rivoplus volumio[3299]: verbose: ControllerMpd::sendMpdCommand status Mar 04 13:52:57 rivoplus volumio[3299]: info: ------------------------------ 3ms Mar 04 13:52:57 rivoplus volumio[3299]: info: sendMpdCommand status took 2 milliseconds Mar 04 13:52:57 rivoplus volumio[3299]: verbose: ControllerMpd::parseState Mar 04 13:52:57 rivoplus volumio[3299]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 13:52:57 rivoplus volumio[3299]: info: Mar 04 13:52:57 rivoplus volumio[3299]: ---------------------------- MPD announces system playlist update Mar 04 13:52:57 rivoplus volumio[3299]: info: Ignoring MPD Status Update Mar 04 13:52:57 rivoplus volumio[3299]: info: Mar 04 13:52:57 rivoplus volumio[3299]: ---------------------------- MPD announces state update: player Mar 04 13:52:57 rivoplus volumio[3299]: info: ControllerMpd::getState Mar 04 13:52:57 rivoplus volumio[3299]: verbose: ControllerMpd::sendMpdCommand status Mar 04 13:52:57 rivoplus volumio[3299]: info: Mar 04 13:52:57 rivoplus volumio[3299]: ---------------------------- MPD announces system playlist update Mar 04 13:52:57 rivoplus volumio[3299]: info: Ignoring MPD Status Update Mar 04 13:52:57 rivoplus volumio[3299]: info: Mar 04 13:52:57 rivoplus volumio[3299]: ---------------------------- MPD announces state update: player Mar 04 13:52:57 rivoplus volumio[3299]: info: ControllerMpd::getState Mar 04 13:52:57 rivoplus volumio[3299]: verbose: ControllerMpd::sendMpdCommand status Mar 04 13:52:57 rivoplus volumio[3299]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 04 13:52:57 rivoplus volumio[3299]: info: ------------------------------ 6ms Mar 04 13:52:57 rivoplus volumio[3299]: info: sendMpdCommand status took 5 milliseconds Mar 04 13:52:57 rivoplus volumio[3299]: info: ------------------------------ 4ms Mar 04 13:52:57 rivoplus volumio[3299]: info: sendMpdCommand status took 3 milliseconds Mar 04 13:52:57 rivoplus volumio[3299]: verbose: ControllerMpd::parseTrackInfo Mar 04 13:52:57 rivoplus volumio[3299]: verbose: ControllerMpd::parseState Mar 04 13:52:57 rivoplus volumio[3299]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 13:52:57 rivoplus volumio[3299]: verbose: ControllerMpd::parseState Mar 04 13:52:57 rivoplus volumio[3299]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 13:52:57 rivoplus volumio[3299]: info: ControllerMpd::pushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreCommandRouter::servicePushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:57 rivoplus volumio[3299]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":165,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"337 Kbps","isStreaming":false,"title":"971992","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/971992","trackType":"qobuz"} Mar 04 13:52:57 rivoplus volumio[3299]: verbose: CURRENT POSITION 4 Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreStateMachine::syncState stateService play Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreStateMachine::syncState currentStatus play Mar 04 13:52:57 rivoplus volumio[3299]: info: Received an update from plugin. extracting info from payload Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreStateMachine::pushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreCommandRouter::volumioPushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:57 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreStateMachine::pushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreCommandRouter::volumioPushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:57 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:57 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:57.954+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 04 13:52:57 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:57.955+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" id=qobuz://song/971992 title="Concerto in G Minor \"la Notte\", Rv439 : V. Largo" Mar 04 13:52:57 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:57.955+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 04 13:52:57 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:57.956+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" id=qobuz://song/971992 title="Concerto in G Minor \"la Notte\", Rv439 : V. Largo" Mar 04 13:52:57 rivoplus volumio[3299]: info: ------------------------------ 34ms Mar 04 13:52:57 rivoplus volumio[3299]: info: sendMpdCommand playlistinfo took 24 milliseconds Mar 04 13:52:57 rivoplus volumio[3299]: info: sendMpdCommand playlistinfo took 25 milliseconds Mar 04 13:52:57 rivoplus volumio[3299]: verbose: ControllerMpd::parseTrackInfo Mar 04 13:52:57 rivoplus volumio[3299]: verbose: ControllerMpd::parseTrackInfo Mar 04 13:52:57 rivoplus volumio[3299]: info: ControllerMpd::pushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreCommandRouter::servicePushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:57 rivoplus volumio[3299]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":165,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"337 Kbps","isStreaming":false,"title":"971992","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/971992","trackType":"qobuz"} Mar 04 13:52:57 rivoplus volumio[3299]: verbose: CURRENT POSITION 4 Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreStateMachine::syncState stateService play Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreStateMachine::syncState currentStatus play Mar 04 13:52:57 rivoplus volumio[3299]: info: Received an update from plugin. extracting info from payload Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreStateMachine::pushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreCommandRouter::volumioPushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:57 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreStateMachine::pushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreCommandRouter::volumioPushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:57 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:57 rivoplus volumio[3299]: info: ControllerMpd::pushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreCommandRouter::servicePushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:57 rivoplus volumio[3299]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":165,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"337 Kbps","isStreaming":false,"title":"971992","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/971992","trackType":"qobuz"} Mar 04 13:52:57 rivoplus volumio[3299]: verbose: CURRENT POSITION 4 Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreStateMachine::syncState stateService play Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreStateMachine::syncState currentStatus play Mar 04 13:52:57 rivoplus volumio[3299]: info: Received an update from plugin. extracting info from payload Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreStateMachine::pushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreCommandRouter::volumioPushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:57 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreStateMachine::pushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreCommandRouter::volumioPushState Mar 04 13:52:57 rivoplus volumio[3299]: info: CoreCommandRouter::volumioGetState Mar 04 13:52:57 rivoplus volumio[3299]: info: CorePlayQueue::getTrack 4 Mar 04 13:52:57 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:57.990+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 04 13:52:57 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:57.991+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" id=qobuz://song/971992 title="Concerto in G Minor \"la Notte\", Rv439 : V. Largo" Mar 04 13:52:57 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:57.992+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 04 13:52:57 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:57.993+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" id=qobuz://song/971992 title="Concerto in G Minor \"la Notte\", Rv439 : V. Largo" Mar 04 13:52:57 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:57.995+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 04 13:52:57 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:57.996+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" id=qobuz://song/971992 title="Concerto in G Minor \"la Notte\", Rv439 : V. Largo" Mar 04 13:52:57 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:57.999+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 04 13:52:57 rivoplus volumio5-onboarding[3809]: time=2026-03-04T13:52:57.999+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.47:42876 @ 0x35200f0" id=qobuz://song/971992 title="Concerto in G Minor \"la Notte\", Rv439 : V. Largo" Mar 04 13:52:58 rivoplus volumio[3299]: info: ------------------------------ 80ms Mar 04 13:52:58 rivoplus volumio[3299]: info: ------------------------------ 79ms Mar 04 13:52:58 rivoplus volumio[3299]: info: Signalling Playback active due to playback status change Mar 04 13:52:58 rivoplus volumio[3299]: info: Signalling Playback active due to playback status change Mar 04 13:52:58 rivoplus volumio[3299]: info: Signalling Playback active due to playback status change Mar 04 13:52:58 rivoplus volumio[3299]: info: Signalling Playback active due to playback status change Mar 04 13:52:58 rivoplus volumio[3299]: info: Signalling Playback active due to playback status change Mar 04 13:52:58 rivoplus volumio[3299]: info: Signalling Playback active due to playback status change Mar 04 13:52:58 rivoplus volumio[3299]: info: Updating RAAT Signal Path Mar 04 13:52:58 rivoplus volumio[3299]: info: Updating RAAT Signal Path Mar 04 13:52:58 rivoplus volumio[3299]: info: Updating RAAT Signal Path Mar 04 13:52:58 rivoplus volumio[3299]: info: Updating RAAT Signal Path Mar 04 13:52:58 rivoplus volumio[3299]: info: Updating RAAT Signal Path Mar 04 13:52:58 rivoplus volumio[3299]: info: Updating RAAT Signal Path Mar 04 13:53:00 rivoplus dbus-daemon[2835]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 13:53:00 rivoplus volumio[3299]: info: Discovery: Restarting Browsing Mar 04 13:53:05 rivoplus dbus-daemon[2835]: [system] The maximum number of active connections for UID 1000 has been reached (max_connections_per_user=256) Mar 04 13:53:05 rivoplus volumio[3299]: info: Discovery: Restarting Browsing Mar 04 13:53:07 rivoplus volumio[3299]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Mar 04 13:53:07 rivoplus volumio[3299]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 13:53:07 rivoplus volumio[3299]: TypeError: Cannot read property 'then' of undefined Mar 04 13:53:07 rivoplus volumio[3299]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Mar 04 13:53:07 rivoplus volumio[3299]: at Socket.emit (events.js:400:28) Mar 04 13:53:07 rivoplus volumio[3299]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 04 13:53:07 rivoplus volumio[3299]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Mar 04 13:53:07 rivoplus volumio[3299]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 13:53:08 rivoplus sudo[12206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-04 13:52 Mar 04 13:53:08 rivoplus sudo[12206]: 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"