-- Logs begin at Thu 2019-02-14 04:11:59 CST, end at Fri 2025-05-16 17:55:07 CDT. -- May 16 17:54:23 volumio-patio volumio[873]: info: CoreCommandRouter::volumioGetState May 16 17:54:23 volumio-patio volumio[873]: info: CoreCommandRouter::volumioGetQueue May 16 17:54:23 volumio-patio volumio[873]: info: CoreStateMachine::getQueue May 16 17:54:23 volumio-patio volumio[873]: info: CorePlayQueue::getQueue May 16 17:54:25 volumio-patio volumio[873]: info: Executing endpoint metavolumio May 16 17:54:25 volumio-patio volumio[873]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 16 17:54:26 volumio-patio volumio[873]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri May 16 17:54:27 volumio-patio volumio[873]: info: handleBrowseUri took 541 milliseconds May 16 17:54:27 volumio-patio volumio[873]: info: Preload queue cleared May 16 17:54:27 volumio-patio volumio[873]: info: Preload queue cleared May 16 17:54:32 volumio-patio volumio[873]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri May 16 17:54:34 volumio-patio volumio[873]: info: handleBrowseUri took 2190 milliseconds May 16 17:54:35 volumio-patio volumio[873]: info: Preload queue cleared May 16 17:54:43 volumio-patio volumio[873]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri May 16 17:54:44 volumio-patio volumio[873]: info: handleBrowseUri took 1151 milliseconds May 16 17:54:44 volumio-patio volumio[873]: info: Preload queue cleared May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016900 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016901 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016902 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016903 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016904 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016905 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016906 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016907 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016908 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016909 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016910 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016911 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016912 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016913 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016914 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016915 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016916 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016917 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016918 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016919 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016920 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016921 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016922 May 16 17:54:44 volumio-patio volumio[873]: info: Preloading song: qobuz://song/335016923 May 16 17:54:44 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016900 in service qobuz May 16 17:54:44 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:44 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016901 in service qobuz May 16 17:54:44 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:44 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016902 in service qobuz May 16 17:54:44 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:44 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016903 in service qobuz May 16 17:54:44 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:44 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016904 in service qobuz May 16 17:54:44 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016905 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016906 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016907 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016908 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016909 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: explodeUri took 484 milliseconds May 16 17:54:45 volumio-patio volumio[873]: info: explodeUri took 461 milliseconds May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016910 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016911 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016912 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: explodeUri took 509 milliseconds May 16 17:54:45 volumio-patio volumio[873]: info: Executing endpoint getSimilarAlbums May 16 17:54:45 volumio-patio volumio[873]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums May 16 17:54:45 volumio-patio volumio[873]: info: Executing endpoint metavolumio May 16 17:54:45 volumio-patio volumio[873]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 16 17:54:45 volumio-patio volumio[873]: info: Executing endpoint metavolumio May 16 17:54:45 volumio-patio volumio[873]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016913 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: explodeUri took 502 milliseconds May 16 17:54:45 volumio-patio volumio[873]: info: explodeUri took 613 milliseconds May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016914 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016915 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016916 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016917 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: explodeUri took 666 milliseconds May 16 17:54:45 volumio-patio volumio[873]: info: explodeUri took 565 milliseconds May 16 17:54:45 volumio-patio volumio[873]: info: explodeUri took 523 milliseconds May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016918 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: explodeUri took 644 milliseconds May 16 17:54:45 volumio-patio volumio[873]: info: explodeUri took 551 milliseconds May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016919 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016920 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016921 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016922 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: Exploding uri qobuz://song/335016923 in service qobuz May 16 17:54:45 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:45 volumio-patio volumio[873]: info: explodeUri took 513 milliseconds May 16 17:54:45 volumio-patio volumio[873]: info: explodeUri took 645 milliseconds May 16 17:54:45 volumio-patio volumio[873]: info: explodeUri took 645 milliseconds May 16 17:54:46 volumio-patio volumio[873]: info: explodeUri took 593 milliseconds May 16 17:54:46 volumio-patio volumio[873]: info: explodeUri took 624 milliseconds May 16 17:54:46 volumio-patio volumio[873]: info: explodeUri took 630 milliseconds May 16 17:54:46 volumio-patio volumio[873]: info: explodeUri took 808 milliseconds May 16 17:54:46 volumio-patio volumio[873]: info: explodeUri took 766 milliseconds May 16 17:54:46 volumio-patio volumio[873]: info: explodeUri took 541 milliseconds May 16 17:54:46 volumio-patio volumio[873]: info: explodeUri took 579 milliseconds May 16 17:54:46 volumio-patio volumio[873]: info: explodeUri took 564 milliseconds May 16 17:54:46 volumio-patio volumio[873]: info: explodeUri took 807 milliseconds May 16 17:54:46 volumio-patio volumio[873]: info: explodeUri took 756 milliseconds May 16 17:54:46 volumio-patio volumio[873]: info: explodeUri took 665 milliseconds May 16 17:54:47 volumio-patio volumio[873]: info: Preload queue cleared May 16 17:54:47 volumio-patio volumio[873]: info: CoreCommandRouter::volumioReplaceandPlayItems May 16 17:54:47 volumio-patio volumio[873]: info: CoreStateMachine::ClearQueue May 16 17:54:47 volumio-patio volumio[873]: info: CoreStateMachine::stop May 16 17:54:47 volumio-patio volumio[873]: info: CoreStateMachine::serviceStop May 16 17:54:47 volumio-patio volumio[873]: info: Received STOP, but no service to execute it May 16 17:54:47 volumio-patio volumio[873]: info: CorePlayQueue::clearPlayQueue May 16 17:54:47 volumio-patio volumio[873]: info: CorePlayQueue::saveQueue May 16 17:54:47 volumio-patio volumio[873]: info: CoreCommandRouter::volumioPushQueue May 16 17:54:47 volumio-patio volumio[873]: info: CoreStateMachine::addQueueItems May 16 17:54:47 volumio-patio volumio[873]: info: CorePlayQueue::addQueueItems May 16 17:54:47 volumio-patio volumio[873]: info: Preload queue cleared May 16 17:54:47 volumio-patio volumio[873]: info: Adding Item to queue: qobuz://album/dacdjbe2k7jyc May 16 17:54:47 volumio-patio volumio[873]: info: Exploding uri qobuz://album/dacdjbe2k7jyc in service qobuz May 16 17:54:47 volumio-patio volumio[873]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 16 17:54:48 volumio-patio volumio[873]: info: explodeUri took 381 milliseconds May 16 17:54:48 volumio-patio volumio[873]: info: CoreCommandRouter::volumioPushQueue May 16 17:54:48 volumio-patio volumio[873]: info: CorePlayQueue::saveQueue May 16 17:54:48 volumio-patio volumio[873]: info: CoreStateMachine::updateTrackBlock May 16 17:54:48 volumio-patio volumio[873]: info: CorePlayQueue::getTrackBlock May 16 17:54:48 volumio-patio volumio[873]: info: CoreCommandRouter::volumioPlay May 16 17:54:48 volumio-patio volumio[873]: verbose: UNSET VOLATILE: Service: undefined May 16 17:54:48 volumio-patio volumio[873]: info: QobuzConnect: onUnsetVolatile() called, relinquishing Volumio State to another service May 16 17:54:48 volumio-patio volumio[873]: info: QobuzConnect: Received stop May 16 17:54:48 volumio-patio volumio[873]: info: CoreCommandRouter::volumioGetState May 16 17:54:48 volumio-patio volumio[873]: info: CoreStateMachine::play index 0 May 16 17:54:48 volumio-patio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 17:54:48 volumio-patio volumio[873]: info: CoreStateMachine::stop May 16 17:54:48 volumio-patio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 17:54:48 volumio-patio volumio[873]: info: CoreStateMachine::play index undefined May 16 17:54:48 volumio-patio volumio[873]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 17:54:48 volumio-patio volumio[873]: info: CorePlayQueue::getTrack 0 May 16 17:54:48 volumio-patio volumio[873]: info: CoreStateMachine::startPlaybackTimer May 16 17:54:48 volumio-patio volumio[873]: info: CorePlayQueue::getTrack 0 May 16 17:54:48 volumio-patio volumio[873]: info: [1747436088195] ControllerQobuz::clearAddPlayTrack May 16 17:54:48 volumio-patio volumio[873]: verbose: ControllerMpd::sendMpdCommand stop May 16 17:54:48 volumio-patio volumio[873]: info: sendMpdCommand stop took 9 milliseconds May 16 17:54:48 volumio-patio volumio[873]: verbose: ControllerMpd::sendMpdCommand clear May 16 17:54:48 volumio-patio volumio[873]: info: May 16 17:54:48 volumio-patio volumio[873]: ---------------------------- MPD announces system playlist update May 16 17:54:48 volumio-patio volumio[873]: info: Ignoring MPD Status Update May 16 17:54:48 volumio-patio volumio[873]: info: sendMpdCommand clear took 4 milliseconds May 16 17:54:48 volumio-patio volumio[873]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/335016900" May 16 17:54:48 volumio-patio volumio[873]: info: May 16 17:54:48 volumio-patio volumio[873]: ---------------------------- MPD announces system playlist update May 16 17:54:48 volumio-patio volumio[873]: info: Ignoring MPD Status Update May 16 17:54:48 volumio-patio volumio[873]: info: May 16 17:54:48 volumio-patio volumio[873]: ---------------------------- MPD announces system playlist update May 16 17:54:48 volumio-patio volumio[873]: info: Ignoring MPD Status Update May 16 17:54:48 volumio-patio volumio[873]: error: updateQueue error: null May 16 17:54:48 volumio-patio volumio[873]: info: ------------------------------ 11ms May 16 17:54:48 volumio-patio volumio[873]: STREAMING PROXY: Handling url /?data=qobuz://song/335016900 May 16 17:54:48 volumio-patio volumio[873]: info: Executing endpoint getStreamUrlqobuz May 16 17:54:48 volumio-patio volumio[873]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 16 17:54:48 volumio-patio volumio[873]: info: getStreamUrl took 389 milliseconds May 16 17:54:48 volumio-patio volumio[873]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=1305605&eid=335016900&fmt=6&profile=raw&app_id=539451548&cid=2686672&etsp=1747439688&hmac=XBMuSGntyj0J_XVYIDB_MvNB7XE May 16 17:54:58 volumio-patio volumio[873]: info: Discovery: Disconnected from remote: 192.168.1.240 May 16 17:54:58 volumio-patio volumio[873]: info: MRS: Cleaning up device: dc4ba9cd-7172-416e-8907-35c15638fb5a May 16 17:54:58 volumio-patio volumio[873]: info: Removing audio output: May 16 17:55:04 volumio-patio mpd_monitor.sh[476]: MPD Monitor Service: MPD Appears to be inactive, restarting May 16 17:55:04 volumio-patio volumio[873]: info: Discovery: Remote connection error: 192.168.1.240 May 16 17:55:04 volumio-patio volumio[873]: info: Discovery: Remote connection timeout: 192.168.1.240 May 16 17:55:04 volumio-patio systemd[1]: musicservicesshield.service: Succeeded. May 16 17:55:04 volumio-patio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. May 16 17:55:04 volumio-patio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... May 16 17:55:04 volumio-patio systemd[1]: Stopping Music Player Daemon... May 16 17:55:04 volumio-patio volumio[873]: ------------------------------------ BT MESSAGE: BT STATUS: running May 16 17:55:04 volumio-patio volumio[873]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 16 17:55:05 volumio-patio volumio[873]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 16 17:55:05 volumio-patio volumio[873]: Error: read ECONNRESET May 16 17:55:05 volumio-patio volumio[873]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { May 16 17:55:05 volumio-patio volumio[873]: errno: -104, May 16 17:55:05 volumio-patio volumio[873]: code: 'ECONNRESET', May 16 17:55:05 volumio-patio volumio[873]: syscall: 'read' May 16 17:55:05 volumio-patio volumio[873]: } May 16 17:55:05 volumio-patio volumio[873]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 16 17:55:06 volumio-patio volumio[873]: STREAMING PROXY: Response: 200, length: 33789046 May 16 17:55:06 volumio-patio volumio[873]: STREAMING PROXY: Client dropped request, destroying May 16 17:55:07 volumio-patio sudo[8567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-16 17:54 May 16 17:55:07 volumio-patio sudo[8567]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"