-- Logs begin at Sun 2026-08-30 13:21:47 EDT, end at Sun 2026-08-30 13:46:25 EDT. -- Aug 30 13:45:04 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 13:45:09 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 13:45:14 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 13:45:19 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 13:45:24 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 13:45:29 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 13:45:34 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 13:45:39 rivo volumio[3114]: info: CorePlayQueue::getTrack 3 Aug 30 13:45:39 rivo volumio[3114]: info: CorePlayQueue::getTrack 4 Aug 30 13:45:39 rivo volumio[3114]: info: Prefetching next song Aug 30 13:45:39 rivo volumio[3114]: info: [1788111939164] ControllerQobuz::prefetch Aug 30 13:45:39 rivo volumio[3114]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/200552458" Aug 30 13:45:39 rivo volumio[3114]: STREAMING PROXY: Handling url /?data=qobuz://song/200552458 Aug 30 13:45:39 rivo volumio[3114]: info: Executing endpoint getStreamUrlqobuz Aug 30 13:45:39 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Aug 30 13:45:39 rivo volumio[3114]: info: getStreamUrl took 237 milliseconds Aug 30 13:45:39 rivo volumio[3114]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=5053913&eid=200552458&fmt=6&profile=raw&app_id=539451548&cid=4399854&etsp=1788115539&hmac=hlNrB0oGBu6_am2Fzpm57uVHflw Aug 30 13:45:39 rivo volumio[3114]: STREAMING PROXY: Requesting upstream with range: none Aug 30 13:45:39 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 13:45:40 rivo volumio[3114]: STREAMING PROXY: Upstream status code: 200 Aug 30 13:45:40 rivo volumio[3114]: STREAMING PROXY: Client closed connection Aug 30 13:45:40 rivo volumio[3114]: STREAMING PROXY: Upstream closed. Received 15411 bytes Aug 30 13:45:40 rivo volumio[3114]: STREAMING PROXY: Incomplete transfer. Expected 16362519, got 15411 Aug 30 13:45:40 rivo volumio[3114]: STREAMING PROXY: Retrying with range: bytes=15411- (attempt 1) Aug 30 13:45:40 rivo volumio[3114]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/200552458" Aug 30 13:45:40 rivo volumio[3114]: info: Aug 30 13:45:40 rivo volumio[3114]: ---------------------------- MPD announces system playlist update Aug 30 13:45:40 rivo volumio[3114]: info: Ignoring MPD Status Update Aug 30 13:45:40 rivo volumio[3114]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/200552458" took 4 milliseconds Aug 30 13:45:40 rivo volumio[3114]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 13:45:40 rivo volumio[3114]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 30 13:45:40 rivo volumio[3114]: info: ------------------------------ 7ms Aug 30 13:45:40 rivo volumio[3114]: info: sendMpdCommand consume 1 took 2 milliseconds Aug 30 13:45:41 rivo volumio[3114]: STREAMING PROXY: Resuming with range: bytes=15411- Aug 30 13:45:41 rivo volumio[3114]: STREAMING PROXY: Resume upstream status code: 206 Aug 30 13:45:41 rivo volumio[3114]: STREAMING PROXY: Resume ended. Received 16347108 bytes Aug 30 13:45:43 rivo volumio[3114]: STREAMING PROXY: Handling url /?data=qobuz://song/200552458 Aug 30 13:45:43 rivo volumio[3114]: info: Executing endpoint getStreamUrlqobuz Aug 30 13:45:43 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Aug 30 13:45:43 rivo volumio[3114]: info: getStreamUrl took 200 milliseconds Aug 30 13:45:43 rivo volumio[3114]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=5053913&eid=200552458&fmt=6&profile=raw&app_id=539451548&cid=4399854&etsp=1788115543&hmac=xh275lwCURyctHfbZIUoj6I99Aw Aug 30 13:45:43 rivo volumio[3114]: STREAMING PROXY: Requesting upstream with range: none Aug 30 13:45:43 rivo volumio[3114]: STREAMING PROXY: Upstream status code: 200 Aug 30 13:45:43 rivo volumio[3114]: info: CoreStateMachine::startPlaybackTimer Aug 30 13:45:43 rivo volumio[3114]: info: CorePlayQueue::getTrack 4 Aug 30 13:45:43 rivo volumio[3114]: STREAMING PROXY: Upstream closed. Received 16362519 bytes Aug 30 13:45:43 rivo volumio[3114]: STREAMING PROXY: Client closed connection Aug 30 13:45:44 rivo volumio[3114]: info: CoreStateMachine::pushState Aug 30 13:45:44 rivo volumio[3114]: info: CorePlayQueue::getTrack 4 Aug 30 13:45:44 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 13:45:44 rivo volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 30 13:45:44 rivo volumio[3114]: info: CoreCommandRouter::volumioGetState Aug 30 13:45:44 rivo volumio[3114]: info: CorePlayQueue::getTrack 4 Aug 30 13:45:44 rivo volumio5-onboarding[3484]: time=2026-08-30T13:45:44.057-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" state=STATUS_PLAYING positionMs=251 volume=100 Aug 30 13:45:44 rivo volumio5-onboarding[3484]: time=2026-08-30T13:45:44.058-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" id=qobuz://song/200552458 title="La Boca" Aug 30 13:45:44 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 13:45:44 rivo volumio[3114]: info: Aug 30 13:45:44 rivo volumio[3114]: ---------------------------- MPD announces system playlist update Aug 30 13:45:44 rivo volumio[3114]: info: Ignoring MPD Status Update Aug 30 13:45:44 rivo volumio[3114]: info: Aug 30 13:45:44 rivo volumio[3114]: ---------------------------- MPD announces state update: player Aug 30 13:45:44 rivo volumio[3114]: info: ControllerMpd::getState Aug 30 13:45:44 rivo volumio[3114]: verbose: ControllerMpd::sendMpdCommand status Aug 30 13:45:44 rivo volumio[3114]: info: ------------------------------ 4ms Aug 30 13:45:44 rivo volumio[3114]: info: sendMpdCommand status took 3 milliseconds Aug 30 13:45:44 rivo volumio[3114]: verbose: ControllerMpd::parseState Aug 30 13:45:44 rivo volumio[3114]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 13:45:44 rivo volumio[3114]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 30 13:45:44 rivo volumio[3114]: verbose: ControllerMpd::parseTrackInfo Aug 30 13:45:44 rivo volumio[3114]: info: ControllerMpd::pushState Aug 30 13:45:44 rivo volumio[3114]: info: CoreCommandRouter::servicePushState Aug 30 13:45:44 rivo volumio[3114]: info: CorePlayQueue::getTrack 4 Aug 30 13:45:44 rivo volumio[3114]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"561 Kbps","isStreaming":false,"title":"200552458","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/200552458","trackType":"qobuz"} Aug 30 13:45:44 rivo volumio[3114]: verbose: CURRENT POSITION 4 Aug 30 13:45:44 rivo volumio[3114]: info: CoreStateMachine::syncState stateService play Aug 30 13:45:44 rivo volumio[3114]: info: CoreStateMachine::syncState currentStatus play Aug 30 13:45:44 rivo volumio[3114]: info: Received an update from plugin. extracting info from payload Aug 30 13:45:44 rivo volumio[3114]: info: CoreStateMachine::pushState Aug 30 13:45:44 rivo volumio[3114]: info: CorePlayQueue::getTrack 4 Aug 30 13:45:44 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 13:45:44 rivo volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 30 13:45:44 rivo volumio[3114]: info: CoreCommandRouter::volumioGetState Aug 30 13:45:44 rivo volumio[3114]: info: CorePlayQueue::getTrack 4 Aug 30 13:45:44 rivo volumio[3114]: info: CoreStateMachine::pushState Aug 30 13:45:44 rivo volumio[3114]: info: CorePlayQueue::getTrack 4 Aug 30 13:45:44 rivo volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 30 13:45:44 rivo volumio[3114]: info: CoreCommandRouter::volumioGetState Aug 30 13:45:44 rivo volumio[3114]: info: CorePlayQueue::getTrack 4 Aug 30 13:45:44 rivo volumio5-onboarding[3484]: time=2026-08-30T13:45:44.950-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" state=STATUS_PLAYING positionMs=91 volume=100 Aug 30 13:45:44 rivo volumio5-onboarding[3484]: time=2026-08-30T13:45:44.951-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" id=qobuz://song/200552458 title="La Boca" Aug 30 13:45:44 rivo volumio5-onboarding[3484]: time=2026-08-30T13:45:44.957-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" state=STATUS_PLAYING positionMs=91 volume=100 Aug 30 13:45:44 rivo volumio5-onboarding[3484]: time=2026-08-30T13:45:44.958-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" id=qobuz://song/200552458 title="La Boca" Aug 30 13:45:44 rivo volumio[3114]: info: ------------------------------ 43ms Aug 30 13:45:49 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 13:45:54 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 13:45:59 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 13:46:04 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 13:46:07 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:07.799-04:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=20.564404ms timeout=10s Aug 30 13:46:07 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:07.930-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=20.564404ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=129.628483ms Aug 30 13:46:07 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:07.996-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=20.564404ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=196.394391ms Aug 30 13:46:08 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:08.075-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=20.564404ms timeout=10s endpoint=http://pushupdates.volumio.org duration=270.061913ms Aug 30 13:46:08 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:08.096-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=20.564404ms timeout=10s endpoint=https://securetoken.googleapis.com duration=291.117926ms Aug 30 13:46:08 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:08.109-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=20.564404ms timeout=10s endpoint=https://functions.volumio.cloud duration=309.4382ms Aug 30 13:46:08 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:08.110-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=20.564404ms timeout=10s endpoint=https://functions.volumio.cloud duration=306.944757ms Aug 30 13:46:08 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:08.110-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=20.564404ms timeout=10s endpoint=https://google.com duration=310.37621ms Aug 30 13:46:08 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:08.115-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=20.564404ms timeout=10s endpoint=https://database.volumio.cloud duration=313.644911ms Aug 30 13:46:08 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:08.116-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=20.564404ms timeout=10s endpoint=https://www.googleapis.com duration=313.011405ms Aug 30 13:46:08 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:08.151-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=20.564404ms timeout=10s endpoint=http://plugins.volumio.org duration=347.754518ms Aug 30 13:46:08 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:08.153-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=20.564404ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=350.104877ms Aug 30 13:46:08 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:08.219-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=20.564404ms timeout=10s endpoint=http://cddb.volumio.org duration=419.331144ms Aug 30 13:46:08 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:08.222-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=20.564404ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=422.303674ms Aug 30 13:46:08 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 13:46:09 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:09.862-04:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=17.451153ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 30 13:46:09 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:09.876-04:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=13.664791ms timeout=10s Aug 30 13:46:09 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:09.881-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=13.664791ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=4.278337ms Aug 30 13:46:09 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:09.881-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=13.664791ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=4.60134ms Aug 30 13:46:09 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:09.884-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=13.664791ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=6.08898ms Aug 30 13:46:09 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:09.912-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=13.664791ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=34.96145ms Aug 30 13:46:09 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:09.914-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=13.664791ms timeout=10s endpoint=https://securetoken.googleapis.com duration=36.700926ms Aug 30 13:46:09 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:09.923-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=13.664791ms timeout=10s endpoint=https://database.volumio.cloud duration=44.860053ms Aug 30 13:46:09 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:09.924-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=13.664791ms timeout=10s endpoint=https://functions.volumio.cloud duration=46.677656ms Aug 30 13:46:09 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:09.926-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=13.664791ms timeout=10s endpoint=https://functions.volumio.cloud duration=48.381924ms Aug 30 13:46:09 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:09.936-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=13.664791ms timeout=10s endpoint=https://google.com duration=59.318038ms Aug 30 13:46:09 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:09.963-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=13.664791ms timeout=10s endpoint=http://pushupdates.volumio.org duration=85.44227ms Aug 30 13:46:09 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:09.981-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=13.664791ms timeout=10s endpoint=https://www.googleapis.com duration=103.96513ms Aug 30 13:46:10 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:10.008-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=13.664791ms timeout=10s endpoint=http://plugins.volumio.org duration=130.059779ms Aug 30 13:46:10 rivo volumio5-onboarding[3484]: time=2026-08-30T13:46:10.034-04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.88.241:50076,00:00:00:00:00:00%02 @ 0x1c4ac30" latency=13.664791ms timeout=10s endpoint=http://cddb.volumio.org duration=156.083427ms Aug 30 13:46:10 rivo sudo[7415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 13:46:10 rivo sudo[7415]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:46:10 rivo sudo[7417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 13:46:10 rivo sudo[7415]: pam_unix(sudo:session): session closed for user root Aug 30 13:46:10 rivo sudo[7417]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:46:10 rivo sudo[7417]: pam_unix(sudo:session): session closed for user root Aug 30 13:46:10 rivo volumio[3114]: verbose: New Socket.io Connection to 192.168.10.11 from 192.168.88.241 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:46:10 rivo volumio[3114]: info: Discovery: Getting this device information Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::volumioGetState Aug 30 13:46:10 rivo volumio[3114]: info: CorePlayQueue::getTrack 4 Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:46:10 rivo volumio[3114]: verbose: New Socket.io Connection to 192.168.10.11:3000 from 192.168.88.241 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 30 13:46:10 rivo sudo[7423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 13:46:10 rivo sudo[7423]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:46:10 rivo sudo[7423]: pam_unix(sudo:session): session closed for user root Aug 30 13:46:10 rivo sudo[7425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 13:46:10 rivo sudo[7425]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 13:46:10 rivo sudo[7425]: pam_unix(sudo:session): session closed for user root Aug 30 13:46:10 rivo volumio[3114]: verbose: New Socket.io Connection to 192.168.10.11 from 192.168.88.241 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::volumioGetState Aug 30 13:46:10 rivo volumio[3114]: info: CorePlayQueue::getTrack 4 Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 13:46:10 rivo volumio[3114]: info: Received Get System Info Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:46:10 rivo volumio[3114]: info: Discovery: Getting this device information Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::volumioGetState Aug 30 13:46:10 rivo volumio[3114]: info: CorePlayQueue::getTrack 4 Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::volumioGetState Aug 30 13:46:10 rivo volumio[3114]: info: CorePlayQueue::getTrack 4 Aug 30 13:46:10 rivo volumio[3114]: info: Listing playlists Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 13:46:10 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 30 13:46:12 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 30 13:46:12 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 13:46:12 rivo volumio[3114]: info: Received Get System Info Aug 30 13:46:12 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:46:12 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:46:12 rivo volumio[3114]: info: Discovery: Getting this device information Aug 30 13:46:12 rivo volumio[3114]: info: CoreCommandRouter::volumioGetState Aug 30 13:46:12 rivo volumio[3114]: info: CorePlayQueue::getTrack 4 Aug 30 13:46:12 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:46:12 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 13:46:12 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 13:46:12 rivo volumio[3114]: info: Received Get System Info Aug 30 13:46:12 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:46:12 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:46:12 rivo volumio[3114]: info: Discovery: Getting this device information Aug 30 13:46:12 rivo volumio[3114]: info: CoreCommandRouter::volumioGetState Aug 30 13:46:12 rivo volumio[3114]: info: CorePlayQueue::getTrack 4 Aug 30 13:46:12 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:46:13 rivo volumio[3114]: info: CoreCommandRouter::volumioGetQueue Aug 30 13:46:13 rivo volumio[3114]: info: CoreStateMachine::getQueue Aug 30 13:46:13 rivo volumio[3114]: info: CorePlayQueue::getQueue Aug 30 13:46:17 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 13:46:20 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 30 13:46:22 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 13:46:23 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:46:23 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:46:23 rivo volumio[3114]: info: Discovery: Getting this device information Aug 30 13:46:23 rivo volumio[3114]: info: CoreCommandRouter::volumioGetState Aug 30 13:46:23 rivo volumio[3114]: info: CorePlayQueue::getTrack 4 Aug 30 13:46:23 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:46:24 rivo volumio[3114]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Aug 30 13:46:24 rivo volumio[3114]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 13:46:24 rivo volumio[3114]: TypeError: Cannot read property 'then' of undefined Aug 30 13:46:24 rivo volumio[3114]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Aug 30 13:46:24 rivo volumio[3114]: at Socket.emit (events.js:400:28) Aug 30 13:46:24 rivo volumio[3114]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 30 13:46:24 rivo volumio[3114]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 30 13:46:24 rivo volumio[3114]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 13:46:25 rivo sudo[7474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-30 13:45 Aug 30 13:46:25 rivo sudo[7474]: 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"