-- Logs begin at Fri 2024-04-26 15:02:45 JST, end at Sat 2024-04-27 21:03:16 JST. -- Apr 27 21:02:02 volumio-rivo volumio[9490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 21:02:02 volumio-rivo volumio[9490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 21:02:02 volumio-rivo volumio[9490]: info: Discovery: Getting this device information Apr 27 21:02:02 volumio-rivo volumio[9490]: info: CoreCommandRouter::volumioGetState Apr 27 21:02:02 volumio-rivo volumio[9490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 21:02:17 volumio-rivo volumio[9490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 21:02:17 volumio-rivo volumio[9490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 21:02:17 volumio-rivo volumio[9490]: info: Discovery: Getting this device information Apr 27 21:02:17 volumio-rivo volumio[9490]: info: CoreCommandRouter::volumioGetState Apr 27 21:02:17 volumio-rivo volumio[9490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 21:02:20 volumio-rivo volumio[9490]: Searching plugin music_service/qobuz Apr 27 21:02:20 volumio-rivo volumio[9490]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 27 21:02:22 volumio-rivo volumio[9490]: Searching plugin music_service/qobuz Apr 27 21:02:22 volumio-rivo volumio[9490]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 27 21:02:23 volumio-rivo volumio[9490]: info: searchQOBUZUri took 3119 milliseconds Apr 27 21:02:23 volumio-rivo volumio[9490]: info: All search sources collected, pushing search results Apr 27 21:02:24 volumio-rivo volumio[9490]: Searching plugin music_service/qobuz Apr 27 21:02:24 volumio-rivo volumio[9490]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 27 21:02:25 volumio-rivo volumio[9490]: Searching plugin music_service/qobuz Apr 27 21:02:25 volumio-rivo volumio[9490]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Apr 27 21:02:25 volumio-rivo volumio[9490]: info: searchQOBUZUri took 3519 milliseconds Apr 27 21:02:25 volumio-rivo volumio[9490]: info: All search sources collected, pushing search results Apr 27 21:02:27 volumio-rivo volumio[9490]: info: searchQOBUZUri took 3358 milliseconds Apr 27 21:02:27 volumio-rivo volumio[9490]: info: All search sources collected, pushing search results Apr 27 21:02:28 volumio-rivo volumio[9490]: info: searchQOBUZUri took 3490 milliseconds Apr 27 21:02:28 volumio-rivo volumio[9490]: info: All search sources collected, pushing search results Apr 27 21:02:31 volumio-rivo volumio[9490]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Apr 27 21:02:32 volumio-rivo volumio[9490]: info: handleBrowseUri took 1032 milliseconds Apr 27 21:02:32 volumio-rivo volumio[9490]: info: Preload queue cleared Apr 27 21:02:32 volumio-rivo volumio[9490]: info: No valid Plugin REST Endpoint: getSimilarArtists Apr 27 21:02:32 volumio-rivo volumio[9490]: info: No valid Plugin REST Endpoint: getSimilarArtists Apr 27 21:02:32 volumio-rivo volumio[9490]: info: No valid Plugin REST Endpoint: getSimilarArtists Apr 27 21:02:32 volumio-rivo volumio[9490]: info: No valid Plugin REST Endpoint: getSimilarArtists Apr 27 21:02:35 volumio-rivo volumio[9490]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Apr 27 21:02:36 volumio-rivo volumio[9490]: info: handleBrowseUri took 1073 milliseconds Apr 27 21:02:36 volumio-rivo volumio[9490]: info: Preload queue cleared Apr 27 21:02:36 volumio-rivo volumio[9490]: info: Preloading song: qobuz://song/209731344 Apr 27 21:02:36 volumio-rivo volumio[9490]: info: Exploding uri qobuz://song/209731344 in service qobuz Apr 27 21:02:36 volumio-rivo volumio[9490]: info: No valid Plugin REST Endpoint: getSimilarAlbums Apr 27 21:02:36 volumio-rivo volumio[9490]: info: No valid Plugin REST Endpoint: getSimilarAlbums Apr 27 21:02:36 volumio-rivo volumio[9490]: info: No valid Plugin REST Endpoint: getSimilarAlbums Apr 27 21:02:36 volumio-rivo volumio[9490]: info: No valid Plugin REST Endpoint: getSimilarAlbums Apr 27 21:02:37 volumio-rivo volumio[9490]: info: explodeUri took 1015 milliseconds Apr 27 21:02:43 volumio-rivo volumio[9490]: info: CoreCommandRouter::volumioPause Apr 27 21:02:43 volumio-rivo volumio[9490]: info: CoreStateMachine::pause Apr 27 21:02:43 volumio-rivo volumio[9490]: info: CoreStateMachine::stPlaybackTimer Apr 27 21:02:43 volumio-rivo volumio[9490]: info: CoreStateMachine::servicePause Apr 27 21:02:43 volumio-rivo volumio[9490]: info: CorePlayQueue::getTrack 12 Apr 27 21:02:43 volumio-rivo volumio[9490]: info: CoreCommandRouter::servicePause Apr 27 21:02:43 volumio-rivo volumio[9490]: info: [1714219363422] ControllerQobuz::pause Apr 27 21:02:43 volumio-rivo volumio[9490]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 27 21:02:43 volumio-rivo volumio[9490]: info: ControllerMpd::pause Apr 27 21:02:43 volumio-rivo volumio[9490]: verbose: ControllerMpd::sendMpdCommand pause Apr 27 21:02:43 volumio-rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Apr 27 21:02:43 volumio-rivo kernel: spdif_a keep clk continuous Apr 27 21:02:43 volumio-rivo kernel: aml_spdif_close Apr 27 21:02:43 volumio-rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Apr 27 21:02:43 volumio-rivo volumio[9490]: info: Apr 27 21:02:43 volumio-rivo volumio[9490]: ---------------------------- MPD announces state update: player Apr 27 21:02:43 volumio-rivo volumio[9490]: info: sendMpdCommand pause took 5 milliseconds Apr 27 21:02:43 volumio-rivo volumio[9490]: info: ControllerMpd::getState Apr 27 21:02:43 volumio-rivo volumio[9490]: verbose: ControllerMpd::sendMpdCommand status Apr 27 21:02:43 volumio-rivo volumio[9490]: info: sendMpdCommand status took 1 milliseconds Apr 27 21:02:43 volumio-rivo volumio[9490]: verbose: ControllerMpd::parseState Apr 27 21:02:43 volumio-rivo volumio[9490]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 21:02:43 volumio-rivo volumio[9490]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 27 21:02:43 volumio-rivo volumio[9490]: verbose: ControllerMpd::parseTrackInfo Apr 27 21:02:43 volumio-rivo volumio[9490]: info: ControllerMpd::pushState Apr 27 21:02:43 volumio-rivo volumio[9490]: info: CoreCommandRouter::servicePushState Apr 27 21:02:43 volumio-rivo volumio[9490]: info: CorePlayQueue::getTrack 12 Apr 27 21:02:43 volumio-rivo volumio[9490]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":172278,"duration":214,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3150 Kbps","isStreaming":false,"title":"208846440","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/208846440","trackType":"qobuz"} Apr 27 21:02:43 volumio-rivo volumio[9490]: verbose: CURRENT POSITION 12 Apr 27 21:02:43 volumio-rivo volumio[9490]: info: CoreStateMachine::syncState stateService pause Apr 27 21:02:43 volumio-rivo volumio[9490]: info: CoreStateMachine::syncState currentStatus pause Apr 27 21:02:43 volumio-rivo volumio[9490]: info: CoreStateMachine::pushState Apr 27 21:02:43 volumio-rivo volumio[9490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 21:02:43 volumio-rivo volumio[9490]: info: CoreCommandRouter::volumioPushState Apr 27 21:02:43 volumio-rivo volumio[9490]: info: CoreStateMachine::stPlaybackTimer Apr 27 21:02:43 volumio-rivo volumio[9490]: info: ------------------------------ 12ms Apr 27 21:02:43 volumio-rivo volumio[9490]: info: MCU Signalled Playback Inactive Apr 27 21:03:15 volumio-rivo volumio[9490]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Apr 27 21:03:15 volumio-rivo volumio[9490]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 21:03:15 volumio-rivo volumio[9490]: TypeError: Cannot read property 'then' of undefined Apr 27 21:03:15 volumio-rivo volumio[9490]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:470:20) Apr 27 21:03:15 volumio-rivo volumio[9490]: at Socket.emit (events.js:400:28) Apr 27 21:03:15 volumio-rivo volumio[9490]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 27 21:03:15 volumio-rivo volumio[9490]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 27 21:03:15 volumio-rivo volumio[9490]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 21:03:16 volumio-rivo sudo[4962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-27 21:02 Apr 27 21:03:16 volumio-rivo sudo[4962]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e831866afe2db61aee9333a8f5ba9d9fd36609c7" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 18 Feb 2024 11:41:55 AM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b0437c7088d6645a661a49f6d9fa596a"