-- Logs begin at Mon 2026-08-24 20:20:12 JST, end at Wed 2026-08-26 00:58:51 JST. -- Aug 26 00:57:23 rivo-waldemio volumiologrotate[2836]: ls: cannot access '/var/log/samba/log.wb-RIVO': No such file or directory Aug 26 00:57:23 rivo-waldemio volumiologrotate[2836]: ls: cannot access 'WALDEMIO': No such file or directory Aug 26 00:57:28 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 16 Aug 26 00:57:28 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:28 rivo-waldemio volumio[3100]: info: Prefetching next song Aug 26 00:57:28 rivo-waldemio volumio[3100]: info: [1787673448644] ControllerQobuz::prefetch Aug 26 00:57:28 rivo-waldemio volumio[3100]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/45390407" Aug 26 00:57:28 rivo-waldemio volumio[3100]: STREAMING PROXY: Handling url /?data=qobuz://song/45390407 Aug 26 00:57:28 rivo-waldemio volumio[3100]: info: Executing endpoint getStreamUrlqobuz Aug 26 00:57:28 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Aug 26 00:57:29 rivo-waldemio volumio[3100]: info: getStreamUrl took 526 milliseconds Aug 26 00:57:29 rivo-waldemio volumio[3100]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=13140433&eid=45390407&fmt=7&profile=raw&app_id=539451548&cid=4502419&etsp=1787677049&hmac=Mrq7S-Y-AsGMWRu7h6nXu_27EAM Aug 26 00:57:29 rivo-waldemio volumio[3100]: STREAMING PROXY: Requesting upstream with range: none Aug 26 00:57:30 rivo-waldemio volumio[3100]: STREAMING PROXY: Upstream status code: 200 Aug 26 00:57:30 rivo-waldemio volumio[3100]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/45390407" Aug 26 00:57:30 rivo-waldemio volumio[3100]: STREAMING PROXY: Client closed connection Aug 26 00:57:30 rivo-waldemio volumio[3100]: STREAMING PROXY: Upstream closed. Received 15410 bytes Aug 26 00:57:30 rivo-waldemio volumio[3100]: STREAMING PROXY: Incomplete transfer. Expected 95915934, got 15410 Aug 26 00:57:30 rivo-waldemio volumio[3100]: STREAMING PROXY: Retrying with range: bytes=15410- (attempt 1) Aug 26 00:57:30 rivo-waldemio volumio[3100]: info: Aug 26 00:57:30 rivo-waldemio volumio[3100]: ---------------------------- MPD announces system playlist update Aug 26 00:57:30 rivo-waldemio volumio[3100]: info: Ignoring MPD Status Update Aug 26 00:57:30 rivo-waldemio volumio[3100]: STREAMING PROXY: Handling url /?data=qobuz://song/45390407 Aug 26 00:57:30 rivo-waldemio volumio[3100]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/45390407" took 6 milliseconds Aug 26 00:57:30 rivo-waldemio volumio[3100]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 00:57:30 rivo-waldemio volumio[3100]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 26 00:57:30 rivo-waldemio volumio[3100]: info: Aug 26 00:57:30 rivo-waldemio volumio[3100]: ---------------------------- MPD announces system playlist update Aug 26 00:57:30 rivo-waldemio volumio[3100]: info: Ignoring MPD Status Update Aug 26 00:57:30 rivo-waldemio volumio[3100]: info: Aug 26 00:57:30 rivo-waldemio volumio[3100]: ---------------------------- MPD announces system playlist update Aug 26 00:57:30 rivo-waldemio volumio[3100]: info: Ignoring MPD Status Update Aug 26 00:57:30 rivo-waldemio volumio[3100]: info: ------------------------------ 8ms Aug 26 00:57:30 rivo-waldemio volumio[3100]: info: sendMpdCommand consume 1 took 6 milliseconds Aug 26 00:57:30 rivo-waldemio volumio[3100]: info: ------------------------------ 5ms Aug 26 00:57:30 rivo-waldemio volumio[3100]: info: ------------------------------ 3ms Aug 26 00:57:30 rivo-waldemio volumio[3100]: info: Executing endpoint getStreamUrlqobuz Aug 26 00:57:30 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Aug 26 00:57:30 rivo-waldemio volumio[3100]: info: getStreamUrl took 454 milliseconds Aug 26 00:57:30 rivo-waldemio volumio[3100]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=13140433&eid=45390407&fmt=7&profile=raw&app_id=539451548&cid=4502419&etsp=1787677050&hmac=aC9VFRz5pIh6ZwWQRhZFDkR91eI Aug 26 00:57:30 rivo-waldemio volumio[3100]: STREAMING PROXY: Requesting upstream with range: none Aug 26 00:57:30 rivo-waldemio volumio[3100]: STREAMING PROXY: Upstream status code: 200 Aug 26 00:57:31 rivo-waldemio volumio[3100]: STREAMING PROXY: Resuming with range: bytes=15410- Aug 26 00:57:31 rivo-waldemio volumio[3100]: STREAMING PROXY: Resume upstream status code: 206 Aug 26 00:57:33 rivo-waldemio volumio[3100]: info: CoreStateMachine::startPlaybackTimer Aug 26 00:57:33 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:33 rivo-waldemio volumio[3100]: info: CoreStateMachine::pushState Aug 26 00:57:33 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:33 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 00:57:33 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioPushState Aug 26 00:57:33 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:57:33 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:33 rivo-waldemio volumio[3100]: info: MRS: Pushing multiroomSync output update for this device Aug 26 00:57:33 rivo-waldemio volumio[3100]: info: MRS: Pushing multiroomSync output Aug 26 00:57:33 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:57:33.597+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.235:44946 @ 0x14009f0" state=STATUS_PLAYING positionMs=251 volume=90 Aug 26 00:57:33 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:57:33.597+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.235:44946 @ 0x14009f0" id=qobuz://song/45390407 title="Nothing Left but Their Names" Aug 26 00:57:33 rivo-waldemio volumio[3100]: info: [LastFM] Current track has sufficient metadata: title (Nothing Left but Their Names) and artist (クロノス・クァルテット) passed on explicitly Aug 26 00:57:33 rivo-waldemio volumio[3100]: info: Updating RAAT Signal Path Aug 26 00:57:33 rivo-waldemio volumio[3100]: info: Signalling Playback active due to playback status change Aug 26 00:57:33 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 00:57:33 rivo-waldemio volumio[3100]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"Nothing Left but Their Names","artist":"クロノス・クァルテット","album":"Landfall","duration":"578","uri":"qobuz://song/45390407","service":""} Aug 26 00:57:33 rivo-waldemio volumio[3100]: info: [now-playing] LRCLIB getLyrics(): song: "Nothing Left but Their Names" album: "Landfall" artist: "クロノス・クァルテット" duration: "578" Aug 26 00:57:33 rivo-waldemio volumio[3100]: info: [now-playing] LRCLIB getLyrics() API URL: https://lrclib.net/api/get?track_name=Nothing+Left+but+Their+Names&artist_name=%E3%82%AF%E3%83%AD%E3%83%8E%E3%82%B9%E3%83%BB%E3%82%AF%E3%82%A1%E3%83%AB%E3%83%86%E3%83%83%E3%83%88&album_name=Landfall&duration=578 Aug 26 00:57:33 rivo-waldemio volumio[3100]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Nothing Left but Their Names","url":"https://www.last.fm/music/%E3%82%AF%E3%83%AD%E3%83%8E%E3%82%B9%E3%83%BB%E3%82%AF%E3%82%A1%E3%83%AB%E3%83%86%E3%83%83%E3%83%88/_/Nothing+Left+but+Their+Names","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"1","playcount":"3","artist":{"name":"クロノス・クァルテット","url":"https://www.last.fm/music/%E3%82%AF%E3%83%AD%E3%83%8E%E3%82%B9%E3%83%BB%E3%82%AF%E3%82%A1%E3%83%AB%E3%83%86%E3%83%83%E3%83%88"},"userplaycount":"3","userloved":"0","toptags":""}} Aug 26 00:57:34 rivo-waldemio volumio[3100]: error: [now-playing] Error fetching lyrics from LRCLIB (URL: https://lrclib.net/api/get?track_name=Nothing+Left+but+Their+Names&artist_name=%E3%82%AF%E3%83%AD%E3%83%8E%E3%82%B9%E3%83%BB%E3%82%AF%E3%82%A1%E3%83%AB%E3%83%86%E3%83%83%E3%83%88&album_name=Landfall&duration=578): Error: Response error: 404 - Not Found Aug 26 00:57:34 rivo-waldemio volumio[3100]: at apiGet (/data/plugins/user_interface/now_playing/dist/lib/api/lrclib/index.js:21:15) Aug 26 00:57:34 rivo-waldemio volumio[3100]: at runMicrotasks () Aug 26 00:57:34 rivo-waldemio volumio[3100]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Aug 26 00:57:34 rivo-waldemio volumio[3100]: at async Function.getLyrics (/data/plugins/user_interface/now_playing/dist/lib/api/lrclib/index.js:34:24) Aug 26 00:57:34 rivo-waldemio volumio[3100]: at async DefaultMetadataProvider.getSongInfo (/data/plugins/user_interface/now_playing/dist/lib/api/DefaultMetadataProvider.js:62:37) Aug 26 00:57:34 rivo-waldemio volumio[3100]: at async /data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:131:40 Aug 26 00:57:34 rivo-waldemio volumio[3100]: error: [now-playing] API endpoint metadata/fetchInfo returned error: Error: Could not fetch info: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Aug 26 00:57:34 rivo-waldemio volumio[3100]: at MetadataAPI._MetadataAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:176:15) Aug 26 00:57:34 rivo-waldemio volumio[3100]: at runMicrotasks () Aug 26 00:57:34 rivo-waldemio volumio[3100]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Aug 26 00:57:34 rivo-waldemio volumio[3100]: at async MetadataAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:52:36) Aug 26 00:57:34 rivo-waldemio volumio[3100]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:115:28) Aug 26 00:57:34 rivo-waldemio volumio[3100]: (node:3100) UnhandledPromiseRejectionWarning: Error: Token missing - Please provide a Genius Access Token in the Now Playing plugin settings. Aug 26 00:57:34 rivo-waldemio volumio[3100]: at DefaultMetadataProvider.getSongInfo (/data/plugins/user_interface/now_playing/dist/lib/api/DefaultMetadataProvider.js:68:19) Aug 26 00:57:34 rivo-waldemio volumio[3100]: at runMicrotasks () Aug 26 00:57:34 rivo-waldemio volumio[3100]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Aug 26 00:57:34 rivo-waldemio volumio[3100]: at async /data/plugins/user_interface/now_playing/dist/lib/api/MetadataAPI.js:131:40 Aug 26 00:57:34 rivo-waldemio volumio[3100]: (node:3100) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 451) Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Aug 26 00:57:34 rivo-waldemio volumio[3100]: ---------------------------- MPD announces system playlist update Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Ignoring MPD Status Update Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Aug 26 00:57:34 rivo-waldemio volumio[3100]: ---------------------------- MPD announces state update: player Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: ControllerMpd::getState Aug 26 00:57:34 rivo-waldemio volumio[3100]: verbose: ControllerMpd::sendMpdCommand status Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Aug 26 00:57:34 rivo-waldemio volumio[3100]: ---------------------------- MPD announces system playlist update Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Ignoring MPD Status Update Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Aug 26 00:57:34 rivo-waldemio volumio[3100]: ---------------------------- MPD announces state update: player Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: ControllerMpd::getState Aug 26 00:57:34 rivo-waldemio volumio[3100]: verbose: ControllerMpd::sendMpdCommand status Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Aug 26 00:57:34 rivo-waldemio volumio[3100]: ---------------------------- MPD announces system playlist update Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Ignoring MPD Status Update Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Aug 26 00:57:34 rivo-waldemio volumio[3100]: ---------------------------- MPD announces state update: player Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: ControllerMpd::getState Aug 26 00:57:34 rivo-waldemio volumio[3100]: verbose: ControllerMpd::sendMpdCommand status Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: ------------------------------ 12ms Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: sendMpdCommand status took 10 milliseconds Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: ------------------------------ 10ms Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: sendMpdCommand status took 8 milliseconds Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: ------------------------------ 7ms Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: sendMpdCommand status took 6 milliseconds Aug 26 00:57:34 rivo-waldemio volumio[3100]: verbose: ControllerMpd::parseState Aug 26 00:57:34 rivo-waldemio volumio[3100]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 00:57:34 rivo-waldemio volumio[3100]: verbose: ControllerMpd::parseState Aug 26 00:57:34 rivo-waldemio volumio[3100]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 00:57:34 rivo-waldemio volumio[3100]: verbose: ControllerMpd::parseState Aug 26 00:57:34 rivo-waldemio volumio[3100]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 26 00:57:34 rivo-waldemio volumio[3100]: verbose: ControllerMpd::parseTrackInfo Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: ControllerMpd::pushState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreCommandRouter::servicePushState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:34 rivo-waldemio volumio[3100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102,"duration":578,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1187 Kbps","isStreaming":false,"title":"45390407","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/45390407","trackType":"qobuz"} Aug 26 00:57:34 rivo-waldemio volumio[3100]: verbose: CURRENT POSITION 17 Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreStateMachine::syncState stateService play Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreStateMachine::syncState currentStatus play Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Received an update from plugin. extracting info from payload Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreStateMachine::pushState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioPushState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: MRS: Pushing multiroomSync output update for this device Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: MRS: Pushing multiroomSync output Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreStateMachine::pushState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioPushState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: MRS: Pushing multiroomSync output update for this device Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: MRS: Pushing multiroomSync output Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: ControllerMpd::pushState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreCommandRouter::servicePushState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:34 rivo-waldemio volumio[3100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102,"duration":578,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1187 Kbps","isStreaming":false,"title":"45390407","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/45390407","trackType":"qobuz"} Aug 26 00:57:34 rivo-waldemio volumio[3100]: verbose: CURRENT POSITION 17 Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreStateMachine::syncState stateService play Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreStateMachine::syncState currentStatus play Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Received an update from plugin. extracting info from payload Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreStateMachine::pushState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioPushState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: MRS: Pushing multiroomSync output update for this device Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: MRS: Pushing multiroomSync output Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreStateMachine::pushState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioPushState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: MRS: Pushing multiroomSync output update for this device Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: MRS: Pushing multiroomSync output Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: ControllerMpd::pushState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreCommandRouter::servicePushState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:34 rivo-waldemio volumio[3100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":102,"duration":578,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1187 Kbps","isStreaming":false,"title":"45390407","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/45390407","trackType":"qobuz"} Aug 26 00:57:34 rivo-waldemio volumio[3100]: verbose: CURRENT POSITION 17 Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreStateMachine::syncState stateService play Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreStateMachine::syncState currentStatus play Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Received an update from plugin. extracting info from payload Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreStateMachine::pushState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioPushState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: MRS: Pushing multiroomSync output update for this device Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: MRS: Pushing multiroomSync output Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreStateMachine::pushState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioPushState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: MRS: Pushing multiroomSync output update for this device Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: MRS: Pushing multiroomSync output Aug 26 00:57:34 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:57:34.439+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.235:44946 @ 0x14009f0" state=STATUS_PLAYING positionMs=102 volume=90 Aug 26 00:57:34 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:57:34.439+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.235:44946 @ 0x14009f0" id=qobuz://song/45390407 title="Nothing Left but Their Names" Aug 26 00:57:34 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:57:34.441+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.235:44946 @ 0x14009f0" state=STATUS_PLAYING positionMs=102 volume=90 Aug 26 00:57:34 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:57:34.441+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.235:44946 @ 0x14009f0" id=qobuz://song/45390407 title="Nothing Left but Their Names" Aug 26 00:57:34 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:57:34.442+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.235:44946 @ 0x14009f0" state=STATUS_PLAYING positionMs=102 volume=90 Aug 26 00:57:34 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:57:34.443+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.235:44946 @ 0x14009f0" id=qobuz://song/45390407 title="Nothing Left but Their Names" Aug 26 00:57:34 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:57:34.443+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.235:44946 @ 0x14009f0" state=STATUS_PLAYING positionMs=102 volume=90 Aug 26 00:57:34 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:57:34.444+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.235:44946 @ 0x14009f0" id=qobuz://song/45390407 title="Nothing Left but Their Names" Aug 26 00:57:34 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:57:34.447+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.235:44946 @ 0x14009f0" state=STATUS_PLAYING positionMs=102 volume=90 Aug 26 00:57:34 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:57:34.448+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.235:44946 @ 0x14009f0" id=qobuz://song/45390407 title="Nothing Left but Their Names" Aug 26 00:57:34 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:57:34.453+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.235:44946 @ 0x14009f0" state=STATUS_PLAYING positionMs=102 volume=90 Aug 26 00:57:34 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:57:34.453+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.235:44946 @ 0x14009f0" id=qobuz://song/45390407 title="Nothing Left but Their Names" Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: ------------------------------ 154ms Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: ------------------------------ 153ms Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: ------------------------------ 151ms Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Updating RAAT Signal Path Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Updating RAAT Signal Path Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Updating RAAT Signal Path Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Updating RAAT Signal Path Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Updating RAAT Signal Path Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Updating RAAT Signal Path Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Signalling Playback active due to playback status change Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Signalling Playback active due to playback status change Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Signalling Playback active due to playback status change Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Signalling Playback active due to playback status change Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Signalling Playback active due to playback status change Aug 26 00:57:34 rivo-waldemio volumio[3100]: info: Signalling Playback active due to playback status change Aug 26 00:57:51 rivo-waldemio volumio[3100]: STREAMING PROXY: Resume ended. Received 95900524 bytes Aug 26 00:57:51 rivo-waldemio volumio[3100]: STREAMING PROXY: Upstream closed. Received 95915934 bytes Aug 26 00:57:51 rivo-waldemio volumio[3100]: STREAMING PROXY: Client closed connection Aug 26 00:58:02 rivo-waldemio volumio[3100]: verbose: New Socket.io Connection to 192.168.50.53 from 192.168.50.235 UA: Mozilla/5.0 (Linux; Android 15; ASUS_AI2302 Build/AQ3A.240812.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.85 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: Received Get System Info Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: Discovery: Getting this device information Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: Listing playlists Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetQueue Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: CoreStateMachine::getQueue Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: CorePlayQueue::getQueue Aug 26 00:58:02 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 26 00:58:06 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:58:06.012+09:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.235:44946 @ 0x14009f0" latency=617.108039ms timeout=10s Aug 26 00:58:06 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:58:06.263+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.235:44946 @ 0x14009f0" latency=617.108039ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=250.711345ms Aug 26 00:58:06 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:58:06.303+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.235:44946 @ 0x14009f0" latency=617.108039ms timeout=10s endpoint=https://www.googleapis.com duration=287.935583ms Aug 26 00:58:06 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:58:06.385+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.235:44946 @ 0x14009f0" latency=617.108039ms timeout=10s endpoint=https://securetoken.googleapis.com duration=371.008085ms Aug 26 00:58:06 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 00:58:06 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 00:58:06 rivo-waldemio volumio[3100]: info: Discovery: Getting this device information Aug 26 00:58:06 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:58:06 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:58:06 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 00:58:06 rivo-waldemio volumio[3100]: verbose: New Socket.io Connection to 192.168.50.53:3000 from 192.168.50.235 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Aug 26 00:58:06 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 26 00:58:06 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 26 00:58:06 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:58:06.488+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.235:44946 @ 0x14009f0" latency=617.108039ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=473.346187ms Aug 26 00:58:06 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:58:06.529+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.235:44946 @ 0x14009f0" latency=617.108039ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=516.476276ms Aug 26 00:58:06 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:58:06.537+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.235:44946 @ 0x14009f0" latency=617.108039ms timeout=10s endpoint=https://functions.volumio.cloud duration=521.335708ms Aug 26 00:58:06 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:58:06.545+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.235:44946 @ 0x14009f0" latency=617.108039ms timeout=10s endpoint=https://functions.volumio.cloud duration=529.6709ms Aug 26 00:58:06 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:58:06.546+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.235:44946 @ 0x14009f0" latency=617.108039ms timeout=10s endpoint=http://pushupdates.volumio.org duration=532.81741ms Aug 26 00:58:06 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:58:06.597+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.235:44946 @ 0x14009f0" latency=617.108039ms timeout=10s endpoint=https://google.com duration=582.896978ms Aug 26 00:58:06 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:58:06.615+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.235:44946 @ 0x14009f0" latency=617.108039ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=600.878574ms Aug 26 00:58:06 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:58:06.701+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.235:44946 @ 0x14009f0" latency=617.108039ms timeout=10s endpoint=https://database.volumio.cloud duration=687.09817ms Aug 26 00:58:06 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:58:06.715+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.235:44946 @ 0x14009f0" latency=617.108039ms timeout=10s endpoint=http://plugins.volumio.org duration=701.174129ms Aug 26 00:58:06 rivo-waldemio volumio5-onboarding[4009]: time=2026-08-26T00:58:06.945+09:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.50.235:44946 @ 0x14009f0" latency=617.108039ms timeout=10s endpoint=http://cddb.volumio.org duration=929.391364ms Aug 26 00:58:08 rivo-waldemio sudo[12143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 00:58:08 rivo-waldemio sudo[12143]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 00:58:08 rivo-waldemio sudo[12143]: pam_unix(sudo:session): session closed for user root Aug 26 00:58:08 rivo-waldemio sudo[12147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 00:58:08 rivo-waldemio sudo[12147]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 00:58:08 rivo-waldemio sudo[12147]: pam_unix(sudo:session): session closed for user root Aug 26 00:58:08 rivo-waldemio volumio[3100]: verbose: New Socket.io Connection to 192.168.50.53 from 192.168.50.235 UA: Mozilla/5.0 (Linux; Android 15; ASUS_AI2302 Build/AQ3A.240812.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.85 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: Received Get System Info Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: Discovery: Getting this device information Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: Received Get System Info Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: Discovery: Getting this device information Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: Listing playlists Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetQueue Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreStateMachine::getQueue Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CorePlayQueue::getQueue Aug 26 00:58:08 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 26 00:58:09 rivo-waldemio sudo[12155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 00:58:09 rivo-waldemio sudo[12155]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 00:58:09 rivo-waldemio sudo[12155]: pam_unix(sudo:session): session closed for user root Aug 26 00:58:10 rivo-waldemio sudo[12159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 00:58:10 rivo-waldemio sudo[12159]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 00:58:10 rivo-waldemio sudo[12159]: pam_unix(sudo:session): session closed for user root Aug 26 00:58:10 rivo-waldemio volumio[3100]: verbose: New Socket.io Connection to 192.168.50.53 from 192.168.50.235 UA: Mozilla/5.0 (Linux; Android 15; ASUS_AI2302 Build/AQ3A.240812.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.85 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: Received Get System Info Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: Discovery: Getting this device information Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: Received Get System Info Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: Discovery: Getting this device information Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: Listing playlists Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetQueue Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreStateMachine::getQueue Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CorePlayQueue::getQueue Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: Received Get System Info Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: Discovery: Getting this device information Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: Received Get System Info Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: Discovery: Getting this device information Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::volumioGetState Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CorePlayQueue::getTrack 17 Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 00:58:10 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 26 00:58:12 rivo-waldemio volumio[3100]: info: Executing endpoint metavolumio Aug 26 00:58:12 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 00:58:12 rivo-waldemio volumio[3100]: info: Executing endpoint metavolumio Aug 26 00:58:12 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 00:58:15 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Aug 26 00:58:15 rivo-waldemio volumio[3100]: info: handleBrowseUri took 304 milliseconds Aug 26 00:58:15 rivo-waldemio volumio[3100]: info: Preload queue cleared Aug 26 00:58:15 rivo-waldemio volumio[3100]: info: Preload queue cleared Aug 26 00:58:18 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 26 00:58:19 rivo-waldemio volumio[3100]: Searching plugin music_service/qobuz Aug 26 00:58:19 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Aug 26 00:58:20 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 26 00:58:21 rivo-waldemio volumio[3100]: info: searchQOBUZUri took 1450 milliseconds Aug 26 00:58:21 rivo-waldemio volumio[3100]: info: All search sources collected, pushing search results Aug 26 00:58:24 rivo-waldemio volumiologrotate[2836]: ls: cannot access '/var/log/samba/log.wb-RIVO': No such file or directory Aug 26 00:58:24 rivo-waldemio volumiologrotate[2836]: ls: cannot access 'WALDEMIO': No such file or directory Aug 26 00:58:29 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Aug 26 00:58:30 rivo-waldemio volumio[3100]: info: handleBrowseUri took 848 milliseconds Aug 26 00:58:30 rivo-waldemio volumio[3100]: info: Preload queue cleared Aug 26 00:58:30 rivo-waldemio volumio[3100]: info: Executing endpoint getSimilarArtists Aug 26 00:58:30 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 26 00:58:30 rivo-waldemio volumio[3100]: info: Executing endpoint metavolumio Aug 26 00:58:30 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 00:58:35 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Aug 26 00:58:36 rivo-waldemio volumio[3100]: info: handleBrowseUri took 440 milliseconds Aug 26 00:58:36 rivo-waldemio volumio[3100]: info: Preload queue cleared Aug 26 00:58:36 rivo-waldemio volumio[3100]: info: Preloading song: qobuz://song/148630983 Aug 26 00:58:36 rivo-waldemio volumio[3100]: info: Exploding uri qobuz://song/148630983 in service qobuz Aug 26 00:58:36 rivo-waldemio volumio[3100]: https://prod.vlmapi.io/v2/qobuz/explodeUri Aug 26 00:58:36 rivo-waldemio volumio[3100]: info: Executing endpoint getSimilarAlbums Aug 26 00:58:36 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 26 00:58:36 rivo-waldemio volumio[3100]: info: Executing endpoint metavolumio Aug 26 00:58:36 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 00:58:36 rivo-waldemio volumio[3100]: info: Executing endpoint metavolumio Aug 26 00:58:36 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 00:58:36 rivo-waldemio volumio[3100]: info: explodeUri took 596 milliseconds Aug 26 00:58:42 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 26 00:58:42 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Aug 26 00:58:43 rivo-waldemio volumio[3100]: info: searchQOBUZUri took 1450 milliseconds Aug 26 00:58:43 rivo-waldemio volumio[3100]: info: All search sources collected, pushing search results Aug 26 00:58:43 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Aug 26 00:58:46 rivo-waldemio volumio[3100]: info: handleBrowseUri took 2467 milliseconds Aug 26 00:58:46 rivo-waldemio volumio[3100]: info: Preload queue cleared Aug 26 00:58:46 rivo-waldemio volumio[3100]: info: Executing endpoint getSimilarArtists Aug 26 00:58:46 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 26 00:58:46 rivo-waldemio volumio[3100]: info: Executing endpoint metavolumio Aug 26 00:58:46 rivo-waldemio volumio[3100]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 00:58:50 rivo-waldemio volumio[3100]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 00:58:50 rivo-waldemio volumio[3100]: TypeError: Cannot read property 'slice' of null Aug 26 00:58:50 rivo-waldemio volumio[3100]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Aug 26 00:58:50 rivo-waldemio volumio[3100]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Aug 26 00:58:50 rivo-waldemio volumio[3100]: at Object.onceWrapper (events.js:520:26) Aug 26 00:58:50 rivo-waldemio volumio[3100]: at ClientRequest.emit (events.js:400:28) Aug 26 00:58:50 rivo-waldemio volumio[3100]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Aug 26 00:58:50 rivo-waldemio volumio[3100]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Aug 26 00:58:50 rivo-waldemio volumio[3100]: at TLSSocket.socketOnData (_http_client.js:515:22) Aug 26 00:58:50 rivo-waldemio volumio[3100]: at TLSSocket.emit (events.js:400:28) Aug 26 00:58:50 rivo-waldemio volumio[3100]: at addChunk (internal/streams/readable.js:293:12) Aug 26 00:58:50 rivo-waldemio volumio[3100]: at readableAddChunk (internal/streams/readable.js:267:9) Aug 26 00:58:50 rivo-waldemio volumio[3100]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Aug 26 00:58:50 rivo-waldemio volumio[3100]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Aug 26 00:58:50 rivo-waldemio volumio[3100]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 00:58:51 rivo-waldemio sudo[12366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-26 00:57 Aug 26 00:58:51 rivo-waldemio sudo[12366]: 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="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 11:38:48 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="8e381701610c2a79deb52e712150c089"