-- 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"