-- Logs begin at Sun 2026-08-16 03:20:19 MSK, end at Tue 2026-08-25 13:12:09 MSK. -- Aug 25 13:11:08 smsl-n100 volumio[797]: info: CorePlayQueue::getTrack 1 Aug 25 13:11:08 smsl-n100 volumio[797]: info: CorePlayQueue::getTrack 2 Aug 25 13:11:08 smsl-n100 volumio[797]: info: Prefetching next song Aug 25 13:11:08 smsl-n100 volumio[797]: info: [1787652668649] ControllerQobuz::prefetch Aug 25 13:11:08 smsl-n100 volumio[797]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/220016365" Aug 25 13:11:08 smsl-n100 volumio[797]: STREAMING PROXY: Handling url /?data=qobuz://song/220016365 Aug 25 13:11:08 smsl-n100 volumio[797]: info: Executing endpoint getStreamUrlqobuz Aug 25 13:11:08 smsl-n100 volumio[797]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Aug 25 13:11:09 smsl-n100 volumio[797]: info: getStreamUrl took 1084 milliseconds Aug 25 13:11:09 smsl-n100 volumio[797]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=5521754&eid=220016365&fmt=7&profile=raw&app_id=539451548&cid=4529566&etsp=1787656269&hmac=MZaSmHACJt-PBeFUjz4dUKxeHXA Aug 25 13:11:10 smsl-n100 volumio[797]: STREAMING PROXY: Response: 200, length: 16836475 Aug 25 13:11:10 smsl-n100 volumio[797]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/220016365" Aug 25 13:11:10 smsl-n100 volumio[797]: STREAMING PROXY: Client dropped request, destroying Aug 25 13:11:10 smsl-n100 volumio[797]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/220016365" took 3 milliseconds Aug 25 13:11:10 smsl-n100 volumio[797]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 25 13:11:10 smsl-n100 volumio[797]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 25 13:11:10 smsl-n100 volumio[797]: info: Aug 25 13:11:10 smsl-n100 volumio[797]: ---------------------------- MPD announces system playlist update Aug 25 13:11:10 smsl-n100 volumio[797]: info: Ignoring MPD Status Update Aug 25 13:11:10 smsl-n100 volumio[797]: info: sendMpdCommand consume 1 took 4 milliseconds Aug 25 13:11:10 smsl-n100 volumio[797]: info: Aug 25 13:11:10 smsl-n100 volumio[797]: ---------------------------- MPD announces system playlist update Aug 25 13:11:10 smsl-n100 volumio[797]: info: Ignoring MPD Status Update Aug 25 13:11:10 smsl-n100 volumio[797]: info: ------------------------------ 8ms Aug 25 13:11:10 smsl-n100 volumio[797]: info: ------------------------------ 5ms Aug 25 13:11:11 smsl-n100 volumio[797]: STREAMING PROXY: Handling url /?data=qobuz://song/220016365 Aug 25 13:11:11 smsl-n100 volumio[797]: info: Executing endpoint getStreamUrlqobuz Aug 25 13:11:11 smsl-n100 volumio[797]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Aug 25 13:11:12 smsl-n100 volumio[797]: info: getStreamUrl took 1086 milliseconds Aug 25 13:11:12 smsl-n100 volumio[797]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=5521754&eid=220016365&fmt=7&profile=raw&app_id=539451548&cid=4529566&etsp=1787656272&hmac=gmUZm2pagT_SJmdTSBJ41SD8AK4 Aug 25 13:11:12 smsl-n100 volumio[797]: STREAMING PROXY: Response: 200, length: 16836475 Aug 25 13:11:13 smsl-n100 volumio[797]: info: CoreStateMachine::startPlaybackTimer Aug 25 13:11:13 smsl-n100 volumio[797]: info: CorePlayQueue::getTrack 2 Aug 25 13:11:13 smsl-n100 volumio[797]: info: CoreStateMachine::pushState Aug 25 13:11:13 smsl-n100 volumio[797]: info: CorePlayQueue::getTrack 2 Aug 25 13:11:13 smsl-n100 volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:11:13 smsl-n100 volumio[797]: info: CoreCommandRouter::volumioPushState Aug 25 13:11:13 smsl-n100 volumio[797]: info: MRS: Pushing multiroomSync output update for this device Aug 25 13:11:13 smsl-n100 volumio[797]: info: MRS: Pushing multiroomSync output Aug 25 13:11:13 smsl-n100 volumio[797]: info: Aug 25 13:11:13 smsl-n100 volumio[797]: ---------------------------- MPD announces system playlist update Aug 25 13:11:13 smsl-n100 volumio[797]: info: Ignoring MPD Status Update Aug 25 13:11:13 smsl-n100 volumio[797]: info: Aug 25 13:11:13 smsl-n100 volumio[797]: ---------------------------- MPD announces state update: player Aug 25 13:11:13 smsl-n100 volumio[797]: info: ControllerMpd::getState Aug 25 13:11:13 smsl-n100 volumio[797]: verbose: ControllerMpd::sendMpdCommand status Aug 25 13:11:13 smsl-n100 volumio[797]: info: ------------------------------ 10ms Aug 25 13:11:13 smsl-n100 volumio[797]: info: Aug 25 13:11:13 smsl-n100 volumio[797]: ---------------------------- MPD announces system playlist update Aug 25 13:11:13 smsl-n100 volumio[797]: info: Ignoring MPD Status Update Aug 25 13:11:13 smsl-n100 volumio[797]: info: Aug 25 13:11:13 smsl-n100 volumio[797]: ---------------------------- MPD announces state update: player Aug 25 13:11:13 smsl-n100 volumio[797]: info: ControllerMpd::getState Aug 25 13:11:13 smsl-n100 volumio[797]: verbose: ControllerMpd::sendMpdCommand status Aug 25 13:11:13 smsl-n100 volumio[797]: info: sendMpdCommand status took 21 milliseconds Aug 25 13:11:13 smsl-n100 volumio[797]: info: ------------------------------ 14ms Aug 25 13:11:13 smsl-n100 volumio[797]: verbose: ControllerMpd::parseState Aug 25 13:11:13 smsl-n100 volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 13:11:13 smsl-n100 volumio[797]: info: sendMpdCommand status took 15 milliseconds Aug 25 13:11:13 smsl-n100 volumio[797]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 25 13:11:13 smsl-n100 volumio[797]: verbose: ControllerMpd::parseState Aug 25 13:11:13 smsl-n100 volumio[797]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 13:11:13 smsl-n100 volumio[797]: verbose: ControllerMpd::parseTrackInfo Aug 25 13:11:13 smsl-n100 volumio[797]: info: ControllerMpd::pushState Aug 25 13:11:13 smsl-n100 volumio[797]: info: CoreCommandRouter::servicePushState Aug 25 13:11:13 smsl-n100 volumio[797]: info: CorePlayQueue::getTrack 2 Aug 25 13:11:13 smsl-n100 volumio[797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10,"duration":61,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2236 Kbps","isStreaming":false,"title":"220016365","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/220016365","trackType":"qobuz"} Aug 25 13:11:13 smsl-n100 volumio[797]: verbose: CURRENT POSITION 2 Aug 25 13:11:13 smsl-n100 volumio[797]: info: CoreStateMachine::syncState stateService play Aug 25 13:11:13 smsl-n100 volumio[797]: info: CoreStateMachine::syncState currentStatus play Aug 25 13:11:13 smsl-n100 volumio[797]: info: Received an update from plugin. extracting info from payload Aug 25 13:11:13 smsl-n100 volumio[797]: info: CoreStateMachine::pushState Aug 25 13:11:13 smsl-n100 volumio[797]: info: CorePlayQueue::getTrack 2 Aug 25 13:11:13 smsl-n100 volumio[797]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:11:13 smsl-n100 volumio[797]: info: CoreCommandRouter::volumioPushState Aug 25 13:11:14 smsl-n100 volumio[797]: info: MRS: Pushing multiroomSync output update for this device Aug 25 13:11:14 smsl-n100 volumio[797]: info: MRS: Pushing multiroomSync output Aug 25 13:11:14 smsl-n100 volumio[797]: info: CoreStateMachine::pushState Aug 25 13:11:14 smsl-n100 volumio[797]: info: CorePlayQueue::getTrack 2 Aug 25 13:11:14 smsl-n100 volumio[797]: info: CoreCommandRouter::volumioPushState Aug 25 13:11:14 smsl-n100 volumio[797]: info: MRS: Pushing multiroomSync output update for this device Aug 25 13:11:14 smsl-n100 volumio[797]: info: MRS: Pushing multiroomSync output Aug 25 13:11:14 smsl-n100 volumio[797]: info: ------------------------------ 133ms Aug 25 13:11:14 smsl-n100 volumio[797]: info: sendMpdCommand playlistinfo took 99 milliseconds Aug 25 13:11:14 smsl-n100 volumio[797]: verbose: ControllerMpd::parseTrackInfo Aug 25 13:11:14 smsl-n100 volumio[797]: info: ControllerMpd::pushState Aug 25 13:11:14 smsl-n100 volumio[797]: info: CoreCommandRouter::servicePushState Aug 25 13:11:14 smsl-n100 volumio[797]: info: CorePlayQueue::getTrack 2 Aug 25 13:11:14 smsl-n100 volumio[797]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10,"duration":61,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2236 Kbps","isStreaming":false,"title":"220016365","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/220016365","trackType":"qobuz"} Aug 25 13:11:14 smsl-n100 volumio[797]: verbose: CURRENT POSITION 2 Aug 25 13:11:14 smsl-n100 volumio[797]: info: CoreStateMachine::syncState stateService play Aug 25 13:11:14 smsl-n100 volumio[797]: info: CoreStateMachine::syncState currentStatus play Aug 25 13:11:14 smsl-n100 volumio[797]: info: Received an update from plugin. extracting info from payload Aug 25 13:11:14 smsl-n100 volumio[797]: info: CoreStateMachine::pushState Aug 25 13:11:14 smsl-n100 volumio[797]: info: CorePlayQueue::getTrack 2 Aug 25 13:11:14 smsl-n100 volumio[797]: info: CoreCommandRouter::volumioPushState Aug 25 13:11:14 smsl-n100 volumio[797]: info: MRS: Pushing multiroomSync output update for this device Aug 25 13:11:14 smsl-n100 volumio[797]: info: MRS: Pushing multiroomSync output Aug 25 13:11:14 smsl-n100 volumio[797]: info: CoreStateMachine::pushState Aug 25 13:11:14 smsl-n100 volumio[797]: info: CorePlayQueue::getTrack 2 Aug 25 13:11:14 smsl-n100 volumio[797]: info: CoreCommandRouter::volumioPushState Aug 25 13:11:14 smsl-n100 volumio[797]: info: MRS: Pushing multiroomSync output update for this device Aug 25 13:11:14 smsl-n100 volumio[797]: info: MRS: Pushing multiroomSync output Aug 25 13:11:14 smsl-n100 volumio[797]: info: ------------------------------ 207ms Aug 25 13:11:14 smsl-n100 volumio[797]: STREAMING PROXY: Client dropped request, destroying Aug 25 13:12:07 smsl-n100 volumio[797]: info: Adding qobuz://album/aej8swyr6rwhc to favourites with specific qobuz method Aug 25 13:12:08 smsl-n100 volumio[797]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 13:12:08 smsl-n100 volumio[797]: Error: socket hang up Aug 25 13:12:08 smsl-n100 volumio[797]: at connResetException (internal/errors.js:639:14) Aug 25 13:12:08 smsl-n100 volumio[797]: at TLSSocket.socketOnEnd (_http_client.js:499:23) Aug 25 13:12:08 smsl-n100 volumio[797]: at TLSSocket.emit (events.js:412:35) Aug 25 13:12:08 smsl-n100 volumio[797]: at endReadableNT (internal/streams/readable.js:1333:12) Aug 25 13:12:08 smsl-n100 volumio[797]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Aug 25 13:12:08 smsl-n100 volumio[797]: code: 'ECONNRESET' Aug 25 13:12:08 smsl-n100 volumio[797]: } Aug 25 13:12:08 smsl-n100 volumio[797]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 13:12:09 smsl-n100 sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-25 13:11 Aug 25 13:12:09 smsl-n100 sudo[1301]: 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="3441cddd43405c36fe444484553f10f2f5bc830b" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 13 Nov 2025 12:39:08 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="7a92d73f7a04bc7854b1812143743fd4"