-- Logs begin at Fri 2025-06-13 04:25:01 HKT, end at Fri 2025-06-13 15:56:45 HKT. -- Jun 13 15:55:40 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 63 Jun 13 15:55:40 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:40 rivoplus volumio[3113]: info: Prefetching next song Jun 13 15:55:40 rivoplus volumio[3113]: info: [1749801340329] ControllerQobuz::prefetch Jun 13 15:55:40 rivoplus volumio[3113]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/209578796" Jun 13 15:55:40 rivoplus volumio[3113]: STREAMING PROXY: Handling url /?data=qobuz://song/209578796 Jun 13 15:55:40 rivoplus volumio[3113]: info: Executing endpoint getStreamUrlqobuz Jun 13 15:55:40 rivoplus volumio[3113]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 13 15:55:40 rivoplus volumio[3113]: info: getStreamUrl took 348 milliseconds Jun 13 15:55:40 rivoplus volumio[3113]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=5071957&eid=209578796&fmt=6&profile=raw&app_id=539451548&cid=2822354&etsp=1749804940&hmac=-1RPuCH9JaEmf7-DXZUyLdoSA5Y Jun 13 15:55:41 rivoplus volumio[3113]: STREAMING PROXY: Response: 200, length: 32533774 Jun 13 15:55:41 rivoplus volumio[3113]: STREAMING PROXY: Client dropped request, destroying Jun 13 15:55:41 rivoplus volumio[3113]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/209578796" Jun 13 15:55:41 rivoplus volumio[3113]: STREAMING PROXY: Handling url /?data=qobuz://song/209578796 Jun 13 15:55:41 rivoplus volumio[3113]: info: Jun 13 15:55:41 rivoplus volumio[3113]: ---------------------------- MPD announces system playlist update Jun 13 15:55:41 rivoplus volumio[3113]: info: Ignoring MPD Status Update Jun 13 15:55:41 rivoplus volumio[3113]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/209578796" took 10 milliseconds Jun 13 15:55:41 rivoplus volumio[3113]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 13 15:55:41 rivoplus volumio[3113]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 13 15:55:41 rivoplus volumio[3113]: info: Jun 13 15:55:41 rivoplus volumio[3113]: ---------------------------- MPD announces system playlist update Jun 13 15:55:41 rivoplus volumio[3113]: info: Ignoring MPD Status Update Jun 13 15:55:41 rivoplus volumio[3113]: info: Jun 13 15:55:41 rivoplus volumio[3113]: ---------------------------- MPD announces system playlist update Jun 13 15:55:41 rivoplus volumio[3113]: info: Ignoring MPD Status Update Jun 13 15:55:41 rivoplus volumio[3113]: info: ------------------------------ 7ms Jun 13 15:55:41 rivoplus volumio[3113]: info: sendMpdCommand consume 1 took 6 milliseconds Jun 13 15:55:41 rivoplus volumio[3113]: info: ------------------------------ 6ms Jun 13 15:55:41 rivoplus volumio[3113]: info: ------------------------------ 4ms Jun 13 15:55:41 rivoplus volumio[3113]: info: Executing endpoint getStreamUrlqobuz Jun 13 15:55:41 rivoplus volumio[3113]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 13 15:55:42 rivoplus volumio[3113]: info: getStreamUrl took 307 milliseconds Jun 13 15:55:42 rivoplus volumio[3113]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=5071957&eid=209578796&fmt=6&profile=raw&app_id=539451548&cid=2822354&etsp=1749804941&hmac=6cisp7Otcj1wZA_CF-7XP9SyDfY Jun 13 15:55:42 rivoplus volumio[3113]: STREAMING PROXY: Response: 200, length: 32533774 Jun 13 15:55:44 rivoplus volumio[3113]: info: CoreStateMachine::startPlaybackTimer Jun 13 15:55:44 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreStateMachine::pushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 13 15:55:45 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:45 rivoplus volumio[3113]: STREAMING PROXY: Client dropped request, destroying Jun 13 15:55:45 rivoplus volumio[3113]: info: Jun 13 15:55:45 rivoplus volumio[3113]: ---------------------------- MPD announces system playlist update Jun 13 15:55:45 rivoplus volumio[3113]: info: Ignoring MPD Status Update Jun 13 15:55:45 rivoplus volumio[3113]: info: Jun 13 15:55:45 rivoplus volumio[3113]: ---------------------------- MPD announces state update: player Jun 13 15:55:45 rivoplus volumio[3113]: info: ControllerMpd::getState Jun 13 15:55:45 rivoplus volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 13 15:55:45 rivoplus volumio[3113]: info: ------------------------------ 3ms Jun 13 15:55:45 rivoplus volumio[3113]: info: sendMpdCommand status took 2 milliseconds Jun 13 15:55:45 rivoplus volumio[3113]: verbose: ControllerMpd::parseState Jun 13 15:55:45 rivoplus volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 15:55:45 rivoplus volumio[3113]: info: Jun 13 15:55:45 rivoplus volumio[3113]: ---------------------------- MPD announces system playlist update Jun 13 15:55:45 rivoplus volumio[3113]: info: Ignoring MPD Status Update Jun 13 15:55:45 rivoplus volumio[3113]: info: Jun 13 15:55:45 rivoplus volumio[3113]: ---------------------------- MPD announces state update: player Jun 13 15:55:45 rivoplus volumio[3113]: info: ControllerMpd::getState Jun 13 15:55:45 rivoplus volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 13 15:55:45 rivoplus volumio[3113]: info: Jun 13 15:55:45 rivoplus volumio[3113]: ---------------------------- MPD announces system playlist update Jun 13 15:55:45 rivoplus volumio[3113]: info: Ignoring MPD Status Update Jun 13 15:55:45 rivoplus volumio[3113]: info: Jun 13 15:55:45 rivoplus volumio[3113]: ---------------------------- MPD announces state update: player Jun 13 15:55:45 rivoplus volumio[3113]: info: ControllerMpd::getState Jun 13 15:55:45 rivoplus volumio[3113]: verbose: ControllerMpd::sendMpdCommand status Jun 13 15:55:45 rivoplus volumio[3113]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 13 15:55:45 rivoplus volumio[3113]: info: ------------------------------ 10ms Jun 13 15:55:45 rivoplus volumio[3113]: info: sendMpdCommand status took 9 milliseconds Jun 13 15:55:45 rivoplus volumio[3113]: info: ------------------------------ 8ms Jun 13 15:55:45 rivoplus volumio[3113]: info: sendMpdCommand status took 4 milliseconds Jun 13 15:55:45 rivoplus volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 13 15:55:45 rivoplus volumio[3113]: verbose: ControllerMpd::parseState Jun 13 15:55:45 rivoplus volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 15:55:45 rivoplus volumio[3113]: verbose: ControllerMpd::parseState Jun 13 15:55:45 rivoplus volumio[3113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 13 15:55:45 rivoplus volumio[3113]: info: ControllerMpd::pushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::servicePushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:45 rivoplus volumio[3113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":356,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"763 Kbps","isStreaming":false,"title":"209578796","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/209578796","trackType":"qobuz"} Jun 13 15:55:45 rivoplus volumio[3113]: verbose: CURRENT POSITION 64 Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 13 15:55:45 rivoplus volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreStateMachine::pushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 13 15:55:45 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreStateMachine::pushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 13 15:55:45 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:45 rivoplus volumio[3113]: info: ------------------------------ 35ms Jun 13 15:55:45 rivoplus volumio[3113]: info: sendMpdCommand playlistinfo took 19 milliseconds Jun 13 15:55:45 rivoplus volumio[3113]: info: sendMpdCommand playlistinfo took 19 milliseconds Jun 13 15:55:45 rivoplus volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 13 15:55:45 rivoplus volumio[3113]: verbose: ControllerMpd::parseTrackInfo Jun 13 15:55:45 rivoplus volumio[3113]: info: ControllerMpd::pushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::servicePushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:45 rivoplus volumio[3113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":356,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"763 Kbps","isStreaming":false,"title":"209578796","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/209578796","trackType":"qobuz"} Jun 13 15:55:45 rivoplus volumio[3113]: verbose: CURRENT POSITION 64 Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 13 15:55:45 rivoplus volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreStateMachine::pushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 13 15:55:45 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreStateMachine::pushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 13 15:55:45 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:45 rivoplus volumio[3113]: info: ControllerMpd::pushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::servicePushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:45 rivoplus volumio[3113]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":356,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"763 Kbps","isStreaming":false,"title":"209578796","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/209578796","trackType":"qobuz"} Jun 13 15:55:45 rivoplus volumio[3113]: verbose: CURRENT POSITION 64 Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreStateMachine::syncState stateService play Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreStateMachine::syncState currentStatus play Jun 13 15:55:45 rivoplus volumio[3113]: info: Received an update from plugin. extracting info from payload Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreStateMachine::pushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 13 15:55:45 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreStateMachine::pushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::volumioPushState Jun 13 15:55:45 rivoplus volumio[3113]: info: CoreCommandRouter::volumioGetState Jun 13 15:55:45 rivoplus volumio[3113]: info: CorePlayQueue::getTrack 64 Jun 13 15:55:45 rivoplus volumio[3113]: info: ------------------------------ 61ms Jun 13 15:55:45 rivoplus volumio[3113]: info: ------------------------------ 60ms Jun 13 15:56:10 rivoplus volumio[3113]: info: CoreCommandRouter::volumioGetQueue Jun 13 15:56:10 rivoplus volumio[3113]: info: CoreStateMachine::getQueue Jun 13 15:56:10 rivoplus volumio[3113]: info: CorePlayQueue::getQueue Jun 13 15:56:44 rivoplus volumio[3113]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Jun 13 15:56:44 rivoplus volumio[3113]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 13 15:56:44 rivoplus volumio[3113]: TypeError: Cannot read property 'then' of undefined Jun 13 15:56:44 rivoplus volumio[3113]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Jun 13 15:56:44 rivoplus volumio[3113]: at Socket.emit (events.js:400:28) Jun 13 15:56:44 rivoplus volumio[3113]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 13 15:56:44 rivoplus volumio[3113]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jun 13 15:56:44 rivoplus volumio[3113]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 13 15:56:45 rivoplus sudo[14717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-13 15:55 Jun 13 15:56:45 rivoplus sudo[14717]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:41:30 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="e2256d79dd6d0ae59eb47f10e57a5e95"