-- Logs begin at Thu 2019-02-14 14:11:58 +04, end at Sat 2025-12-06 17:29:19 +04. -- Dec 06 17:28:03 minidsp-shd volumio[753]: verbose: New Socket.io Connection to 192.168.1.62 from 192.168.1.91 UA: Mozilla/5.0 (Linux; Android 16; SM-F956B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.171 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 06 17:28:03 minidsp-shd volumio[753]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 06 17:28:03 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioGetVisibleSources Dec 06 17:28:03 minidsp-shd volumio[753]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 17:28:03 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioGetState Dec 06 17:28:03 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 2 Dec 06 17:28:03 minidsp-shd volumio[753]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 06 17:28:03 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioGetQueue Dec 06 17:28:03 minidsp-shd volumio[753]: info: CoreStateMachine::getQueue Dec 06 17:28:03 minidsp-shd volumio[753]: info: CorePlayQueue::getQueue Dec 06 17:28:03 minidsp-shd volumio[753]: info: Listing playlists Dec 06 17:28:03 minidsp-shd volumio[753]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 06 17:28:03 minidsp-shd volumio[753]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 06 17:28:03 minidsp-shd volumio[753]: info: Received Get System Info Dec 06 17:28:03 minidsp-shd volumio[753]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 17:28:03 minidsp-shd volumio[753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 17:28:03 minidsp-shd volumio[753]: info: Discovery: Getting this device information Dec 06 17:28:03 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioGetState Dec 06 17:28:03 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 2 Dec 06 17:28:03 minidsp-shd volumio[753]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 17:28:03 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioGetState Dec 06 17:28:03 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 2 Dec 06 17:28:04 minidsp-shd volumio[753]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 06 17:28:21 minidsp-shd volumiologrotate[463]: ls: cannot access '/var/log/samba/log.wb-MINIDSP': No such file or directory Dec 06 17:28:21 minidsp-shd volumiologrotate[463]: ls: cannot access 'SHD': No such file or directory Dec 06 17:28:51 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 2 Dec 06 17:28:51 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:51 minidsp-shd volumio[753]: info: Prefetching next song Dec 06 17:28:51 minidsp-shd volumio[753]: info: [1765027731064] ControllerQobuz::prefetch Dec 06 17:28:51 minidsp-shd volumio[753]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/5325678" Dec 06 17:28:51 minidsp-shd volumio[753]: STREAMING PROXY: Handling url /?data=qobuz://song/5325678 Dec 06 17:28:51 minidsp-shd volumio[753]: info: Executing endpoint getStreamUrlqobuz Dec 06 17:28:51 minidsp-shd volumio[753]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Dec 06 17:28:51 minidsp-shd volumio[753]: info: getStreamUrl took 319 milliseconds Dec 06 17:28:51 minidsp-shd volumio[753]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3057711&eid=5325678&fmt=6&profile=raw&app_id=539451548&cid=3492816&etsp=1765031331&hmac=qK0RqUIWiGWBo2LQT_03xFAvoFM Dec 06 17:28:52 minidsp-shd volumio[753]: STREAMING PROXY: Response: 200, length: 22007806 Dec 06 17:28:52 minidsp-shd volumio[753]: STREAMING PROXY: Client dropped request, destroying Dec 06 17:28:52 minidsp-shd volumio[753]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/5325678" Dec 06 17:28:52 minidsp-shd volumio[753]: info: Dec 06 17:28:52 minidsp-shd volumio[753]: ---------------------------- MPD announces system playlist update Dec 06 17:28:52 minidsp-shd volumio[753]: info: Ignoring MPD Status Update Dec 06 17:28:52 minidsp-shd volumio[753]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/5325678" took 5 milliseconds Dec 06 17:28:52 minidsp-shd volumio[753]: STREAMING PROXY: Handling url /?data=qobuz://song/5325678 Dec 06 17:28:52 minidsp-shd volumio[753]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 06 17:28:52 minidsp-shd volumio[753]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 06 17:28:52 minidsp-shd volumio[753]: info: Dec 06 17:28:52 minidsp-shd volumio[753]: ---------------------------- MPD announces system playlist update Dec 06 17:28:52 minidsp-shd volumio[753]: info: Ignoring MPD Status Update Dec 06 17:28:52 minidsp-shd volumio[753]: info: Dec 06 17:28:52 minidsp-shd volumio[753]: ---------------------------- MPD announces system playlist update Dec 06 17:28:52 minidsp-shd volumio[753]: info: Ignoring MPD Status Update Dec 06 17:28:52 minidsp-shd volumio[753]: info: ------------------------------ 12ms Dec 06 17:28:52 minidsp-shd volumio[753]: info: sendMpdCommand consume 1 took 9 milliseconds Dec 06 17:28:52 minidsp-shd volumio[753]: info: ------------------------------ 7ms Dec 06 17:28:52 minidsp-shd volumio[753]: info: ------------------------------ 5ms Dec 06 17:28:52 minidsp-shd volumio[753]: info: Executing endpoint getStreamUrlqobuz Dec 06 17:28:52 minidsp-shd volumio[753]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Dec 06 17:28:52 minidsp-shd volumio[753]: info: getStreamUrl took 453 milliseconds Dec 06 17:28:52 minidsp-shd volumio[753]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3057711&eid=5325678&fmt=6&profile=raw&app_id=539451548&cid=3492816&etsp=1765031332&hmac=dkOsSaJy5WixOs-9KpEKZbXiA0Q Dec 06 17:28:52 minidsp-shd volumio[753]: STREAMING PROXY: Response: 200, length: 22007806 Dec 06 17:28:54 minidsp-shd volumio[753]: STREAMING PROXY: Client dropped request, destroying Dec 06 17:28:55 minidsp-shd volumio[753]: info: CoreStateMachine::startPlaybackTimer Dec 06 17:28:55 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:55 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioGetVisibleSources Dec 06 17:28:55 minidsp-shd volumio[753]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 17:28:55 minidsp-shd volumio[753]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreStateMachine::pushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioPushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioGetState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:56 minidsp-shd volumio[753]: info: MRS: Pushing multiroomSync output update for this device Dec 06 17:28:56 minidsp-shd volumio[753]: info: MRS: Pushing multiroomSync output Dec 06 17:28:56 minidsp-shd volumio[753]: info: Dec 06 17:28:56 minidsp-shd volumio[753]: ---------------------------- MPD announces system playlist update Dec 06 17:28:56 minidsp-shd volumio[753]: info: Ignoring MPD Status Update Dec 06 17:28:56 minidsp-shd volumio[753]: info: Dec 06 17:28:56 minidsp-shd volumio[753]: ---------------------------- MPD announces state update: player Dec 06 17:28:56 minidsp-shd volumio[753]: info: ControllerMpd::getState Dec 06 17:28:56 minidsp-shd volumio[753]: verbose: ControllerMpd::sendMpdCommand status Dec 06 17:28:56 minidsp-shd volumio[753]: info: Dec 06 17:28:56 minidsp-shd volumio[753]: ---------------------------- MPD announces system playlist update Dec 06 17:28:56 minidsp-shd volumio[753]: info: Ignoring MPD Status Update Dec 06 17:28:56 minidsp-shd volumio[753]: info: Dec 06 17:28:56 minidsp-shd volumio[753]: ---------------------------- MPD announces state update: player Dec 06 17:28:56 minidsp-shd volumio[753]: info: ControllerMpd::getState Dec 06 17:28:56 minidsp-shd volumio[753]: verbose: ControllerMpd::sendMpdCommand status Dec 06 17:28:56 minidsp-shd volumio[753]: info: Dec 06 17:28:56 minidsp-shd volumio[753]: ---------------------------- MPD announces system playlist update Dec 06 17:28:56 minidsp-shd volumio[753]: info: Ignoring MPD Status Update Dec 06 17:28:56 minidsp-shd volumio[753]: info: Dec 06 17:28:56 minidsp-shd volumio[753]: ---------------------------- MPD announces state update: player Dec 06 17:28:56 minidsp-shd volumio[753]: info: ControllerMpd::getState Dec 06 17:28:56 minidsp-shd volumio[753]: verbose: ControllerMpd::sendMpdCommand status Dec 06 17:28:56 minidsp-shd volumio[753]: info: ------------------------------ 9ms Dec 06 17:28:56 minidsp-shd volumio[753]: info: sendMpdCommand status took 8 milliseconds Dec 06 17:28:56 minidsp-shd volumio[753]: info: ------------------------------ 6ms Dec 06 17:28:56 minidsp-shd volumio[753]: info: sendMpdCommand status took 6 milliseconds Dec 06 17:28:56 minidsp-shd volumio[753]: info: ------------------------------ 5ms Dec 06 17:28:56 minidsp-shd volumio[753]: info: sendMpdCommand status took 4 milliseconds Dec 06 17:28:56 minidsp-shd volumio[753]: verbose: ControllerMpd::parseState Dec 06 17:28:56 minidsp-shd volumio[753]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 17:28:56 minidsp-shd volumio[753]: verbose: ControllerMpd::parseState Dec 06 17:28:56 minidsp-shd volumio[753]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 17:28:56 minidsp-shd volumio[753]: verbose: ControllerMpd::parseState Dec 06 17:28:56 minidsp-shd volumio[753]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 06 17:28:56 minidsp-shd volumio[753]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 06 17:28:56 minidsp-shd volumio[753]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 06 17:28:56 minidsp-shd volumio[753]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 06 17:28:56 minidsp-shd volumio[753]: verbose: ControllerMpd::parseTrackInfo Dec 06 17:28:56 minidsp-shd volumio[753]: verbose: ControllerMpd::parseTrackInfo Dec 06 17:28:56 minidsp-shd volumio[753]: verbose: ControllerMpd::parseTrackInfo Dec 06 17:28:56 minidsp-shd volumio[753]: info: ControllerMpd::pushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::servicePushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:56 minidsp-shd volumio[753]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":176,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"551 Kbps","isStreaming":false,"title":"5325678","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/5325678","trackType":"qobuz"} Dec 06 17:28:56 minidsp-shd volumio[753]: verbose: CURRENT POSITION 3 Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreStateMachine::syncState stateService play Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreStateMachine::syncState currentStatus play Dec 06 17:28:56 minidsp-shd volumio[753]: info: Received an update from plugin. extracting info from payload Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreStateMachine::pushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioPushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioGetState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:56 minidsp-shd volumio[753]: info: MRS: Pushing multiroomSync output update for this device Dec 06 17:28:56 minidsp-shd volumio[753]: info: MRS: Pushing multiroomSync output Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreStateMachine::pushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioPushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioGetState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:56 minidsp-shd volumio[753]: info: MRS: Pushing multiroomSync output update for this device Dec 06 17:28:56 minidsp-shd volumio[753]: info: MRS: Pushing multiroomSync output Dec 06 17:28:56 minidsp-shd volumio[753]: info: ControllerMpd::pushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::servicePushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:56 minidsp-shd volumio[753]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":176,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"551 Kbps","isStreaming":false,"title":"5325678","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/5325678","trackType":"qobuz"} Dec 06 17:28:56 minidsp-shd volumio[753]: verbose: CURRENT POSITION 3 Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreStateMachine::syncState stateService play Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreStateMachine::syncState currentStatus play Dec 06 17:28:56 minidsp-shd volumio[753]: info: Received an update from plugin. extracting info from payload Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreStateMachine::pushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioPushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioGetState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:56 minidsp-shd volumio[753]: info: MRS: Pushing multiroomSync output update for this device Dec 06 17:28:56 minidsp-shd volumio[753]: info: MRS: Pushing multiroomSync output Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreStateMachine::pushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioPushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioGetState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:56 minidsp-shd volumio[753]: info: MRS: Pushing multiroomSync output update for this device Dec 06 17:28:56 minidsp-shd volumio[753]: info: MRS: Pushing multiroomSync output Dec 06 17:28:56 minidsp-shd volumio[753]: info: ControllerMpd::pushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::servicePushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:56 minidsp-shd volumio[753]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":176,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"551 Kbps","isStreaming":false,"title":"5325678","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/5325678","trackType":"qobuz"} Dec 06 17:28:56 minidsp-shd volumio[753]: verbose: CURRENT POSITION 3 Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreStateMachine::syncState stateService play Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreStateMachine::syncState currentStatus play Dec 06 17:28:56 minidsp-shd volumio[753]: info: Received an update from plugin. extracting info from payload Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreStateMachine::pushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioPushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioGetState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:56 minidsp-shd volumio[753]: info: MRS: Pushing multiroomSync output update for this device Dec 06 17:28:56 minidsp-shd volumio[753]: info: MRS: Pushing multiroomSync output Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreStateMachine::pushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioPushState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CoreCommandRouter::volumioGetState Dec 06 17:28:56 minidsp-shd volumio[753]: info: CorePlayQueue::getTrack 3 Dec 06 17:28:56 minidsp-shd volumio[753]: info: MRS: Pushing multiroomSync output update for this device Dec 06 17:28:56 minidsp-shd volumio[753]: info: MRS: Pushing multiroomSync output Dec 06 17:28:56 minidsp-shd volumio[753]: info: ------------------------------ 70ms Dec 06 17:28:56 minidsp-shd volumio[753]: info: ------------------------------ 68ms Dec 06 17:28:56 minidsp-shd volumio[753]: info: ------------------------------ 67ms Dec 06 17:29:18 minidsp-shd volumio[753]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 17:29:18 minidsp-shd volumio[753]: Error: read ETIMEDOUT Dec 06 17:29:18 minidsp-shd volumio[753]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Dec 06 17:29:18 minidsp-shd volumio[753]: errno: -110, Dec 06 17:29:18 minidsp-shd volumio[753]: code: 'ETIMEDOUT', Dec 06 17:29:18 minidsp-shd volumio[753]: syscall: 'read' Dec 06 17:29:18 minidsp-shd volumio[753]: } Dec 06 17:29:18 minidsp-shd volumio[753]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 17:29:19 minidsp-shd sudo[5808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-06 17:28 Dec 06 17:29:19 minidsp-shd sudo[5808]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 18 Nov 2025 04:14:14 PM CET" VOLUMIO_VERSION="3.877" VOLUMIO_HARDWARE="nanopineo2" VOLUMIO_DEVICENAME="NanoPi Neo2" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="57978c68a391dbf6badbe915e5672a72"