-- Logs begin at Sun 2024-03-31 19:11:33 +04, end at Sun 2024-07-21 18:22:45 +04. -- Jul 21 18:21:02 minidsp-shd volumio[718]: info: CorePlayQueue::getTrack 190 Jul 21 18:21:02 minidsp-shd volumio[718]: info: CorePlayQueue::getTrack 191 Jul 21 18:21:02 minidsp-shd volumio[718]: info: Prefetching next song Jul 21 18:21:02 minidsp-shd volumio[718]: info: [1721571662250] ControllerQobuz::prefetch Jul 21 18:21:02 minidsp-shd volumio[718]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/147440886" Jul 21 18:21:02 minidsp-shd volumio[718]: STREAMING PROXY: Handling url /?data=qobuz://song/147440886 Jul 21 18:21:02 minidsp-shd volumio[718]: info: Executing endpoint getStreamUrlqobuz Jul 21 18:21:02 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jul 21 18:21:02 minidsp-shd volumio[718]: info: getStreamUrl took 465 milliseconds Jul 21 18:21:02 minidsp-shd volumio[718]: STREAMING PROXY: Got real url: http://streaming-qobuz-std.akamaized.net:80/file?uid=3057711&eid=147440886&fmt=7&profile=raw&app_id=539451548&cid=2218933&etsp=1721575262&hmac=oRfcdi6LsxGFLC72gmmtUVGhcio Jul 21 18:21:02 minidsp-shd volumio[718]: STREAMING PROXY: Response: 200, length: 52888752 Jul 21 18:21:02 minidsp-shd volumio[718]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/147440886" Jul 21 18:21:02 minidsp-shd volumio[718]: info: Jul 21 18:21:02 minidsp-shd volumio[718]: ---------------------------- MPD announces system playlist update Jul 21 18:21:02 minidsp-shd volumio[718]: info: Ignoring MPD Status Update Jul 21 18:21:02 minidsp-shd volumio[718]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/147440886" took 2 milliseconds Jul 21 18:21:02 minidsp-shd volumio[718]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 21 18:21:02 minidsp-shd volumio[718]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 21 18:21:02 minidsp-shd volumio[718]: STREAMING PROXY: Client dropped request, destroying Jul 21 18:21:02 minidsp-shd volumio[718]: info: ------------------------------ 4ms Jul 21 18:21:02 minidsp-shd volumio[718]: info: sendMpdCommand consume 1 took 3 milliseconds Jul 21 18:21:03 minidsp-shd volumio[718]: STREAMING PROXY: Client dropped request, destroying Jul 21 18:21:04 minidsp-shd volumio[718]: info: Jul 21 18:21:04 minidsp-shd volumio[718]: ---------------------------- MPD announces system playlist update Jul 21 18:21:04 minidsp-shd volumio[718]: info: Ignoring MPD Status Update Jul 21 18:21:04 minidsp-shd volumio[718]: info: Jul 21 18:21:04 minidsp-shd volumio[718]: ---------------------------- MPD announces state update: player Jul 21 18:21:04 minidsp-shd volumio[718]: info: ControllerMpd::getState Jul 21 18:21:04 minidsp-shd volumio[718]: verbose: ControllerMpd::sendMpdCommand status Jul 21 18:21:04 minidsp-shd volumio[718]: info: ------------------------------ 4ms Jul 21 18:21:04 minidsp-shd volumio[718]: info: sendMpdCommand status took 2 milliseconds Jul 21 18:21:04 minidsp-shd volumio[718]: verbose: ControllerMpd::parseState Jul 21 18:21:04 minidsp-shd volumio[718]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 21 18:21:04 minidsp-shd volumio[718]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 21 18:21:04 minidsp-shd volumio[718]: verbose: ControllerMpd::parseTrackInfo Jul 21 18:21:04 minidsp-shd volumio[718]: info: ControllerMpd::pushState Jul 21 18:21:04 minidsp-shd volumio[718]: info: CoreCommandRouter::servicePushState Jul 21 18:21:04 minidsp-shd volumio[718]: info: CorePlayQueue::getTrack 190 Jul 21 18:21:04 minidsp-shd volumio[718]: verbose: STATE SERVICE {"status":"play","position":0,"seek":115,"duration":151,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2098 Kbps","isStreaming":false,"title":"147440892","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/147440892","trackType":"qobuz"} Jul 21 18:21:04 minidsp-shd volumio[718]: verbose: CURRENT POSITION 190 Jul 21 18:21:04 minidsp-shd volumio[718]: info: CoreStateMachine::syncState stateService play Jul 21 18:21:04 minidsp-shd volumio[718]: info: CoreStateMachine::syncState currentStatus play Jul 21 18:21:04 minidsp-shd volumio[718]: info: Received an update from plugin. extracting info from payload Jul 21 18:21:04 minidsp-shd volumio[718]: info: CoreStateMachine::pushState Jul 21 18:21:04 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 18:21:04 minidsp-shd volumio[718]: info: CoreCommandRouter::volumioPushState Jul 21 18:21:04 minidsp-shd volumio[718]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:21:04 minidsp-shd volumio[718]: info: MRS: Pushing multiroomSync output Jul 21 18:21:04 minidsp-shd volumio[718]: info: CoreStateMachine::pushState Jul 21 18:21:04 minidsp-shd volumio[718]: info: CoreCommandRouter::volumioPushState Jul 21 18:21:04 minidsp-shd volumio[718]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:21:04 minidsp-shd volumio[718]: info: MRS: Pushing multiroomSync output Jul 21 18:21:04 minidsp-shd volumio[718]: info: ------------------------------ 28ms Jul 21 18:21:06 minidsp-shd volumio[718]: info: CoreStateMachine::startPlaybackTimer Jul 21 18:21:06 minidsp-shd volumio[718]: info: CorePlayQueue::getTrack 191 Jul 21 18:21:07 minidsp-shd volumio[718]: info: CoreStateMachine::pushState Jul 21 18:21:07 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 18:21:07 minidsp-shd volumio[718]: info: CoreCommandRouter::volumioPushState Jul 21 18:21:07 minidsp-shd volumio[718]: info: MRS: Pushing multiroomSync output update for this device Jul 21 18:21:07 minidsp-shd volumio[718]: info: MRS: Pushing multiroomSync output Jul 21 18:21:49 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 21 18:21:49 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 21 18:21:49 minidsp-shd volumio[718]: info: Discovery: Getting this device information Jul 21 18:21:49 minidsp-shd volumio[718]: info: CoreCommandRouter::volumioGetState Jul 21 18:21:49 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 21 18:21:52 minidsp-shd volumio[718]: info: CoreCommandRouter::Close All Modals sent Jul 21 18:22:01 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 21 18:22:01 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 21 18:22:01 minidsp-shd volumio[718]: info: Discovery: Getting this device information Jul 21 18:22:01 minidsp-shd volumio[718]: info: CoreCommandRouter::volumioGetState Jul 21 18:22:01 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 21 18:22:06 minidsp-shd volumio[718]: Searching plugin music_service/mpd Jul 21 18:22:06 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 21 18:22:06 minidsp-shd volumio[718]: info: All search sources collected, pushing search results Jul 21 18:22:06 minidsp-shd volumio[718]: Searching plugin music_service/mpd Jul 21 18:22:06 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 21 18:22:06 minidsp-shd volumio[718]: info: All search sources collected, pushing search results Jul 21 18:22:08 minidsp-shd volumio[718]: Searching plugin music_service/mpd Jul 21 18:22:08 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 21 18:22:08 minidsp-shd volumio[718]: info: All search sources collected, pushing search results Jul 21 18:22:09 minidsp-shd volumio[718]: Searching plugin music_service/mpd Jul 21 18:22:09 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 21 18:22:09 minidsp-shd volumio[718]: info: All search sources collected, pushing search results Jul 21 18:22:10 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 21 18:22:10 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 21 18:22:10 minidsp-shd volumio[718]: info: Discovery: Getting this device information Jul 21 18:22:10 minidsp-shd volumio[718]: info: CoreCommandRouter::volumioGetState Jul 21 18:22:10 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 21 18:22:11 minidsp-shd volumio[718]: Searching plugin music_service/mpd Jul 21 18:22:11 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 21 18:22:11 minidsp-shd volumio[718]: info: All search sources collected, pushing search results Jul 21 18:22:16 minidsp-shd volumio[718]: Searching plugin music_service/mpd Jul 21 18:22:16 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 21 18:22:16 minidsp-shd volumio[718]: info: All search sources collected, pushing search results Jul 21 18:22:19 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 21 18:22:19 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 21 18:22:19 minidsp-shd volumio[718]: info: Discovery: Getting this device information Jul 21 18:22:19 minidsp-shd volumio[718]: info: CoreCommandRouter::volumioGetState Jul 21 18:22:19 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 21 18:22:28 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 21 18:22:28 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 21 18:22:28 minidsp-shd volumio[718]: info: Discovery: Getting this device information Jul 21 18:22:28 minidsp-shd volumio[718]: info: CoreCommandRouter::volumioGetState Jul 21 18:22:28 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 21 18:22:38 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 21 18:22:38 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 21 18:22:38 minidsp-shd volumio[718]: info: Discovery: Getting this device information Jul 21 18:22:38 minidsp-shd volumio[718]: info: CoreCommandRouter::volumioGetState Jul 21 18:22:38 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 21 18:22:44 minidsp-shd volumio[718]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Jul 21 18:22:44 minidsp-shd volumio[718]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 21 18:22:44 minidsp-shd volumio[718]: TypeError: Cannot read property 'then' of undefined Jul 21 18:22:44 minidsp-shd volumio[718]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:470:20) Jul 21 18:22:44 minidsp-shd volumio[718]: at Socket.emit (events.js:400:28) Jul 21 18:22:44 minidsp-shd volumio[718]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jul 21 18:22:44 minidsp-shd volumio[718]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jul 21 18:22:44 minidsp-shd volumio[718]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 21 18:22:45 minidsp-shd sudo[24518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-21 18:21 Jul 21 18:22:45 minidsp-shd sudo[24518]: 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="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:00:28 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo2" VOLUMIO_DEVICENAME="NanoPi Neo2" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="29ccfe8a9cff19911db4b736879ac117"