-- Logs begin at Wed 2025-03-19 20:43:27 CST, end at Wed 2025-03-19 20:52:00 CST. -- Mar 19 20:51:00 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 20:51:04 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 20:51:08 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 20:51:12 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 20:51:16 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 20:51:20 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 20:51:24 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 20:51:26 volumio ntpd[1032]: Soliciting pool server 8.217.190.48 Mar 19 20:51:28 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 20:51:29 volumio volumio[1048]: info: MPD Database updated - AlbumList cache refreshed Mar 19 20:51:29 volumio volumio[1048]: info: Mar 19 20:51:29 volumio volumio[1048]: ---------------------------- MPD announces state update: database Mar 19 20:51:29 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Mar 19 20:51:29 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Mar 19 20:51:29 volumio volumio[1048]: info: Mar 19 20:51:29 volumio volumio[1048]: ---------------------------- MPD announces state update: update Mar 19 20:51:29 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Mar 19 20:51:29 volumio volumio[1048]: info: ControllerMpd::getState Mar 19 20:51:29 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Mar 19 20:51:29 volumio volumio[1048]: info: ControllerMpd::getState Mar 19 20:51:29 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Mar 19 20:51:29 volumio volumio[1048]: info: MPD Database updated - AlbumList cache refreshed Mar 19 20:51:29 volumio volumio[1048]: info: Mar 19 20:51:29 volumio volumio[1048]: ---------------------------- MPD announces state update: database Mar 19 20:51:29 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Mar 19 20:51:29 volumio volumio[1048]: info: Mar 19 20:51:29 volumio volumio[1048]: ---------------------------- MPD announces state update: update Mar 19 20:51:29 volumio volumio[1048]: info: ControllerMpd::getState Mar 19 20:51:29 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Mar 19 20:51:29 volumio volumio[1048]: info: ControllerMpd::getState Mar 19 20:51:29 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Mar 19 20:51:29 volumio volumio[1048]: info: MPD Database updated - AlbumList cache refreshed Mar 19 20:51:29 volumio volumio[1048]: info: Mar 19 20:51:29 volumio volumio[1048]: ---------------------------- MPD announces state update: database Mar 19 20:51:29 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Mar 19 20:51:29 volumio volumio[1048]: info: Mar 19 20:51:29 volumio volumio[1048]: ---------------------------- MPD announces state update: update Mar 19 20:51:29 volumio volumio[1048]: info: ControllerMpd::getState Mar 19 20:51:29 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Mar 19 20:51:29 volumio volumio[1048]: info: ControllerMpd::getState Mar 19 20:51:29 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand status Mar 19 20:51:30 volumio volumio[1048]: info: sendMpdCommand status took 593 milliseconds Mar 19 20:51:30 volumio volumio[1048]: info: sendMpdCommand status took 593 milliseconds Mar 19 20:51:30 volumio volumio[1048]: info: sendMpdCommand status took 593 milliseconds Mar 19 20:51:30 volumio volumio[1048]: info: sendMpdCommand status took 591 milliseconds Mar 19 20:51:30 volumio volumio[1048]: info: sendMpdCommand status took 591 milliseconds Mar 19 20:51:30 volumio volumio[1048]: verbose: ControllerMpd::parseState Mar 19 20:51:30 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Mar 19 20:51:30 volumio volumio[1048]: verbose: ControllerMpd::parseState Mar 19 20:51:30 volumio volumio[1048]: info: Command Router : Notfying DB Updatefalse Mar 19 20:51:30 volumio volumio[1048]: info: CoreCommandRouter::Close All Modals sent Mar 19 20:51:30 volumio volumio[1048]: verbose: ControllerMpd::parseState Mar 19 20:51:30 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Mar 19 20:51:30 volumio volumio[1048]: verbose: ControllerMpd::parseState Mar 19 20:51:30 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 20:51:30 volumio volumio[1048]: verbose: ControllerMpd::parseState Mar 19 20:51:30 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 20:51:30 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Mar 19 20:51:30 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Mar 19 20:51:30 volumio volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":97396,"duration":6618,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"uri":"podcast/qeoblb4wulmlk74d/%7B%22title%22%3A%22351%E6%9C%9F%20-%20%E6%84%BF%E4%BD%A0%20%E7%94%9F%E8%80%8C%E4%B8%BA%E4%BA%BA%C2%B7%E6%97%A5%E6%97%A5%E8%87%AA%E5%9C%A8%22%2C%22url%22%3A%22https%3A%2F%2Ftk.wavpub.com%2FWPDL_ZkJNrQSgzLfAHCuxwRVDFcBHLbWNnyhpGjHNPzAaKcvPtDxkESWGWanhfL-60.m4a%22%2C%22albumart%22%3A%22https%3A%2F%2Ffdfs.xmcdn.com%2Fstorages%2F3214-audiofreehighqps%2F07%2F2F%2FGKwRIMALp2ypAAONEwN8qRcU.jpeg%22%7D","trackType":"Podcast","serviceName":"podcast"} Mar 19 20:51:30 volumio volumio[1048]: verbose: CURRENT POSITION 0 Mar 19 20:51:30 volumio volumio[1048]: info: CoreStateMachine::syncState stateService play Mar 19 20:51:30 volumio volumio[1048]: info: CoreStateMachine::syncState currentStatus play Mar 19 20:51:30 volumio volumio[1048]: info: Received an update from plugin. extracting info from payload Mar 19 20:51:30 volumio volumio[1048]: info: CoreStateMachine::pushState Mar 19 20:51:30 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Mar 19 20:51:30 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 20:51:30 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Mar 19 20:51:30 volumio volumio[1048]: info: CoreStateMachine::pushState Mar 19 20:51:30 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Mar 19 20:51:30 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Mar 19 20:51:30 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Mar 19 20:51:30 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Mar 19 20:51:30 volumio volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":97396,"duration":6618,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"48 Kbps","isStreaming":false,"uri":"podcast/qeoblb4wulmlk74d/%7B%22title%22%3A%22351%E6%9C%9F%20-%20%E6%84%BF%E4%BD%A0%20%E7%94%9F%E8%80%8C%E4%B8%BA%E4%BA%BA%C2%B7%E6%97%A5%E6%97%A5%E8%87%AA%E5%9C%A8%22%2C%22url%22%3A%22https%3A%2F%2Ftk.wavpub.com%2FWPDL_ZkJNrQSgzLfAHCuxwRVDFcBHLbWNnyhpGjHNPzAaKcvPtDxkESWGWanhfL-60.m4a%22%2C%22albumart%22%3A%22https%3A%2F%2Ffdfs.xmcdn.com%2Fstorages%2F3214-audiofreehighqps%2F07%2F2F%2FGKwRIMALp2ypAAONEwN8qRcU.jpeg%22%7D","trackType":"Podcast","serviceName":"podcast"} Mar 19 20:51:30 volumio volumio[1048]: verbose: CURRENT POSITION 0 Mar 19 20:51:30 volumio volumio[1048]: info: CoreStateMachine::syncState stateService play Mar 19 20:51:30 volumio volumio[1048]: info: CoreStateMachine::syncState currentStatus play Mar 19 20:51:30 volumio volumio[1048]: info: Received an update from plugin. extracting info from payload Mar 19 20:51:30 volumio volumio[1048]: info: CoreStateMachine::pushState Mar 19 20:51:30 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Mar 19 20:51:30 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Mar 19 20:51:30 volumio volumio[1048]: info: CoreStateMachine::pushState Mar 19 20:51:30 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Mar 19 20:51:30 volumio volumio[1048]: info: CoreCommandRouter::volumioPushState Mar 19 20:51:30 volumio ntpd[1032]: Soliciting pool server 8.217.190.48 Mar 19 20:51:30 volumio volumio[1048]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 19 20:51:30 volumio volumio[1048]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 19 20:51:30 volumio volumio[1048]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 19 20:51:30 volumio volumio[1048]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 19 20:51:30 volumio volumio[1048]: info: sendMpdCommand status took 1077 milliseconds Mar 19 20:51:30 volumio volumio[1048]: info: sendMpdCommand status took 1076 milliseconds Mar 19 20:51:30 volumio volumio[1048]: info: sendMpdCommand status took 1075 milliseconds Mar 19 20:51:30 volumio volumio[1048]: verbose: ControllerMpd::parseState Mar 19 20:51:30 volumio volumio[1048]: info: Command Router : Notfying DB Updatefalse Mar 19 20:51:30 volumio volumio[1048]: info: CoreCommandRouter::Close All Modals sent Mar 19 20:51:30 volumio volumio[1048]: verbose: ControllerMpd::parseState Mar 19 20:51:30 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 20:51:30 volumio volumio[1048]: verbose: ControllerMpd::parseState Mar 19 20:51:30 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 20:51:31 volumio volumio[1048]: info: sendMpdCommand status took 1405 milliseconds Mar 19 20:51:31 volumio volumio[1048]: info: sendMpdCommand status took 1405 milliseconds Mar 19 20:51:31 volumio volumio[1048]: info: sendMpdCommand status took 1404 milliseconds Mar 19 20:51:31 volumio volumio[1048]: info: sendMpdCommand playlistinfo took 716 milliseconds Mar 19 20:51:31 volumio volumio[1048]: info: sendMpdCommand playlistinfo took 716 milliseconds Mar 19 20:51:31 volumio volumio[1048]: info: sendMpdCommand playlistinfo took 294 milliseconds Mar 19 20:51:31 volumio volumio[1048]: info: sendMpdCommand playlistinfo took 294 milliseconds Mar 19 20:51:31 volumio volumio[1048]: verbose: ControllerMpd::parseState Mar 19 20:51:31 volumio volumio[1048]: info: Command Router : Notfying DB Updatefalse Mar 19 20:51:31 volumio volumio[1048]: info: CoreCommandRouter::Close All Modals sent Mar 19 20:51:31 volumio volumio[1048]: verbose: ControllerMpd::parseState Mar 19 20:51:31 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 20:51:31 volumio volumio[1048]: verbose: ControllerMpd::parseState Mar 19 20:51:31 volumio volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 20:51:31 volumio volumio[1048]: verbose: ControllerMpd::parseTrackInfo Mar 19 20:51:31 volumio volumio[1048]: verbose: ControllerMpd::parseTrackInfo Mar 19 20:51:31 volumio volumio[1048]: verbose: ControllerMpd::parseTrackInfo Mar 19 20:51:31 volumio volumio[1048]: verbose: ControllerMpd::parseTrackInfo Mar 19 20:51:31 volumio volumio[1048]: info: ControllerMpd::pushState Mar 19 20:51:31 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Mar 19 20:51:31 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Mar 19 20:51:31 volumio volumio[1048]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current podcast Received mpd Mar 19 20:51:31 volumio volumio[1048]: info: ControllerMpd::pushState Mar 19 20:51:31 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Mar 19 20:51:31 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Mar 19 20:51:31 volumio volumio[1048]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current podcast Received mpd Mar 19 20:51:31 volumio volumio[1048]: info: ControllerMpd::pushState Mar 19 20:51:31 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Mar 19 20:51:31 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Mar 19 20:51:31 volumio volumio[1048]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current podcast Received mpd Mar 19 20:51:31 volumio volumio[1048]: info: ControllerMpd::pushState Mar 19 20:51:31 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Mar 19 20:51:31 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Mar 19 20:51:31 volumio volumio[1048]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current podcast Received mpd Mar 19 20:51:31 volumio volumio[1048]: info: ------------------------------ 1487ms Mar 19 20:51:31 volumio volumio[1048]: info: ------------------------------ 1486ms Mar 19 20:51:31 volumio volumio[1048]: info: ------------------------------ 1479ms Mar 19 20:51:31 volumio volumio[1048]: info: ------------------------------ 1479ms Mar 19 20:51:31 volumio volumio[1048]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 19 20:51:31 volumio volumio[1048]: info: sendMpdCommand playlistinfo took 22 milliseconds Mar 19 20:51:31 volumio volumio[1048]: verbose: ControllerMpd::parseTrackInfo Mar 19 20:51:31 volumio volumio[1048]: verbose: ControllerMpd::parseTrackInfo Mar 19 20:51:31 volumio volumio[1048]: info: ControllerMpd::pushState Mar 19 20:51:31 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Mar 19 20:51:31 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Mar 19 20:51:31 volumio volumio[1048]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current podcast Received mpd Mar 19 20:51:31 volumio volumio[1048]: info: ControllerMpd::pushState Mar 19 20:51:31 volumio volumio[1048]: info: CoreCommandRouter::servicePushState Mar 19 20:51:31 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Mar 19 20:51:31 volumio volumio[1048]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current podcast Received mpd Mar 19 20:51:31 volumio volumio[1048]: info: ------------------------------ 1481ms Mar 19 20:51:31 volumio volumio[1048]: info: ------------------------------ 1480ms Mar 19 20:51:32 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 20:51:36 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 20:51:36 volumio ntpd[1032]: Soliciting pool server 8.217.190.48 Mar 19 20:51:40 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 20:51:44 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 19 20:51:48 volumio volumio[1048]: info: CoreCommandRouter::volumioGetState Mar 19 20:51:48 volumio volumio[1048]: info: CorePlayQueue::getTrack 0 Mar 19 20:51:53 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 19 20:51:53 volumio volumio[1048]: info: CURURI: albums:// Mar 19 20:51:53 volumio volumio[1048]: info: listAlbums - loading Albums from cache Mar 19 20:51:53 volumio volumio[1048]: info: Preload queue cleared Mar 19 20:51:57 volumio volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 19 20:51:57 volumio volumio[1048]: info: CURURI: artists:// Mar 19 20:51:57 volumio volumio[1048]: info: Preload queue cleared Mar 19 20:52:00 volumio volumio[1048]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 19 20:52:00 volumio volumio[1048]: Error: Client network socket disconnected before secure TLS connection was established Mar 19 20:52:00 volumio volumio[1048]: at connResetException (internal/errors.js:639:14) Mar 19 20:52:00 volumio volumio[1048]: at TLSSocket.onConnectEnd (_tls_wrap.js:1570:19) Mar 19 20:52:00 volumio volumio[1048]: at TLSSocket.emit (events.js:412:35) Mar 19 20:52:00 volumio volumio[1048]: at endReadableNT (internal/streams/readable.js:1333:12) Mar 19 20:52:00 volumio volumio[1048]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Mar 19 20:52:00 volumio volumio[1048]: code: 'ECONNRESET', Mar 19 20:52:00 volumio volumio[1048]: path: null, Mar 19 20:52:00 volumio volumio[1048]: host: 'lastfm.freetls.fastly.net', Mar 19 20:52:00 volumio volumio[1048]: port: 443, Mar 19 20:52:00 volumio volumio[1048]: localAddress: undefined Mar 19 20:52:00 volumio volumio[1048]: } Mar 19 20:52:00 volumio volumio[1048]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 19 20:52:00 volumio sudo[3686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-19 20:51 Mar 19 20:52:00 volumio sudo[3686]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"