-- Logs begin at Thu 2025-05-22 01:41:08 CST, end at Thu 2025-05-22 21:39:11 CST. -- May 22 21:38:06 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 21:38:07 volumio volumio[1161]: info: Preload queue cleared May 22 21:38:13 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 21:38:13 volumio volumio[1161]: info: TuneIn handleBrowseUri: tunein/popular May 22 21:38:26 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 22 21:38:26 volumio volumio[1161]: info: CURURI: artists:// May 22 21:38:26 volumio volumio[1161]: info: Preload queue cleared May 22 21:38:29 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 22 21:38:29 volumio volumio[1161]: info: CURURI: albums:// May 22 21:38:29 volumio volumio[1161]: info: listAlbums - loading Albums from cache May 22 21:38:29 volumio volumio[1161]: info: Preload queue cleared May 22 21:38:30 volumio volumio[1161]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E8%8F%B2/Faye%20Best/c0f93818-161c-4969-8b8b-219edb673d41.jpg' May 22 21:38:34 volumio volumio[1161]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E8%8F%B2/%E5%AF%93%E8%A8%80/f04ffa9d-ee31-48d0-87b2-6d407a1b4c70.jpg' May 22 21:38:39 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 22 21:38:39 volumio volumio[1161]: info: Preload queue cleared May 22 21:38:41 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 22 21:38:46 volumio volumio[1161]: info: Preload queue cleared May 22 21:38:46 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 22 21:38:46 volumio volumio[1161]: info: Preload queue cleared May 22 21:38:47 volumio nmbd[812]: [2025/05/22 21:38:47.776029, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 22 21:38:47 volumio nmbd[812]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.166 for name WORKGROUP<1d>. May 22 21:38:47 volumio nmbd[812]: This response was from IP 192.168.0.1, reporting an IP address of 192.168.0.1. May 22 21:38:49 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: calmradio , handleBrowseUri May 22 21:38:49 volumio volumio[1161]: info: Calm Radio root browse May 22 21:38:49 volumio volumio[1161]: info: Getting Calm Radio categories data May 22 21:38:51 volumio volumio[1161]: info: Listing Calm Radio Groups May 22 21:38:51 volumio volumio[1161]: info: Preload queue cleared May 22 21:38:59 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri May 22 21:38:59 volumio volumio[1161]: info: [1747921139127] [80s80s] handleBrowseUri curUri: 80s80s May 22 21:38:59 volumio volumio[1161]: info: Preload queue cleared May 22 21:38:59 volumio volumio[1161]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B5%9C%E5%B4%8E%E3%81%82%E3%82%86%E3%81%BF/GUILTY/f9054d9c-ebcc-4649-bc75-decb73d5ec8c.jpg' May 22 21:39:00 volumio volumio[1161]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E8%8F%B2/%E4%BD%A0%E5%9C%A8%E7%BB%88%E7%82%B9%E7%AD%89%E6%88%91/69f45ea5-8450-4568-8fa1-8bc2b6ab4b51.png' May 22 21:39:01 volumio volumio[1161]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/G.E.M.%E9%82%93%E7%B4%AB%E6%A3%8B/%E5%85%89%E5%B9%B4%E4%B9%8B%E5%A4%96/5937b4f1-8361-46bf-8cea-4b079b82bc8d.jpg' May 22 21:39:02 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri May 22 21:39:02 volumio volumio[1161]: info: [1747921142409] [80s80s] handleBrowseUri curUri: 80s80s/eighties May 22 21:39:02 volumio volumio[1161]: info: [1747921142409] [80s80s] getRadioContent url: eighties May 22 21:39:02 volumio volumio[1161]: info: Preload queue cleared May 22 21:39:03 volumio volumio[1161]: info: Preload queue cleared May 22 21:39:03 volumio volumio[1161]: info: CoreCommandRouter::volumioReplaceandPlayItems May 22 21:39:03 volumio volumio[1161]: info: CoreStateMachine::ClearQueue May 22 21:39:03 volumio volumio[1161]: info: CoreStateMachine::stop May 22 21:39:03 volumio volumio[1161]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 21:39:03 volumio volumio[1161]: info: CorePlayQueue::clearPlayQueue May 22 21:39:03 volumio volumio[1161]: info: CorePlayQueue::saveQueue May 22 21:39:03 volumio volumio[1161]: info: CoreCommandRouter::volumioPushQueue May 22 21:39:03 volumio volumio[1161]: info: CoreStateMachine::addQueueItems May 22 21:39:03 volumio volumio[1161]: info: CorePlayQueue::addQueueItems May 22 21:39:03 volumio volumio[1161]: info: Preload queue cleared May 22 21:39:03 volumio volumio[1161]: info: Adding Item to queue: webeighties/0 May 22 21:39:03 volumio volumio[1161]: info: Exploding uri webeighties/0 in service 80s80s May 22 21:39:03 volumio volumio[1161]: info: [1747921143551] [80s80s] explodeUri: webeighties/0 May 22 21:39:03 volumio volumio[1161]: info: CoreCommandRouter::volumioPushQueue May 22 21:39:03 volumio volumio[1161]: info: CorePlayQueue::saveQueue May 22 21:39:03 volumio volumio[1161]: info: CoreStateMachine::updateTrackBlock May 22 21:39:03 volumio volumio[1161]: info: CorePlayQueue::getTrackBlock May 22 21:39:03 volumio volumio[1161]: info: CoreCommandRouter::volumioPlay May 22 21:39:03 volumio volumio[1161]: info: CoreStateMachine::play index 0 May 22 21:39:03 volumio volumio[1161]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 21:39:03 volumio volumio[1161]: info: CoreStateMachine::stop May 22 21:39:03 volumio volumio[1161]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 21:39:03 volumio volumio[1161]: info: CoreStateMachine::play index undefined May 22 21:39:03 volumio volumio[1161]: info: CoreStateMachine::setConsumeUpdateService undefined May 22 21:39:03 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 May 22 21:39:03 volumio volumio[1161]: info: CoreStateMachine::startPlaybackTimer May 22 21:39:03 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 May 22 21:39:03 volumio volumio[1161]: info: [1747921143573] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/web/mp3-192/volumio May 22 21:39:03 volumio volumio[1161]: info: [1747921143573] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=2 May 22 21:39:05 volumio volumio[1161]: info: [1747921145975] [80s80s] received new event containing 2 songs. May 22 21:39:05 volumio volumio[1161]: verbose: ControllerMpd::sendMpdCommand stop May 22 21:39:05 volumio volumio[1161]: info: sendMpdCommand stop took 2 milliseconds May 22 21:39:05 volumio volumio[1161]: verbose: ControllerMpd::sendMpdCommand clear May 22 21:39:05 volumio volumio[1161]: info: May 22 21:39:05 volumio volumio[1161]: ---------------------------- MPD announces system playlist update May 22 21:39:05 volumio volumio[1161]: info: Ignoring MPD Status Update May 22 21:39:05 volumio volumio[1161]: info: sendMpdCommand clear took 3 milliseconds May 22 21:39:05 volumio volumio[1161]: verbose: ControllerMpd::sendMpdCommand consume 1 May 22 21:39:05 volumio volumio[1161]: info: May 22 21:39:05 volumio volumio[1161]: ---------------------------- MPD announces system playlist update May 22 21:39:05 volumio volumio[1161]: info: Ignoring MPD Status Update May 22 21:39:05 volumio volumio[1161]: info: May 22 21:39:05 volumio volumio[1161]: ---------------------------- MPD announces system playlist update May 22 21:39:05 volumio volumio[1161]: info: Ignoring MPD Status Update May 22 21:39:05 volumio volumio[1161]: error: updateQueue error: null May 22 21:39:05 volumio volumio[1161]: info: May 22 21:39:05 volumio volumio[1161]: ---------------------------- MPD announces state update: options May 22 21:39:06 volumio volumio[1161]: error: updateQueue error: null May 22 21:39:06 volumio volumio[1161]: error: updateQueue error: null May 22 21:39:06 volumio volumio[1161]: info: ------------------------------ 18ms May 22 21:39:06 volumio volumio[1161]: info: sendMpdCommand consume 1 took 14 milliseconds May 22 21:39:06 volumio volumio[1161]: info: ControllerMpd::getState May 22 21:39:06 volumio volumio[1161]: verbose: ControllerMpd::sendMpdCommand status May 22 21:39:06 volumio volumio[1161]: info: ------------------------------ 14ms May 22 21:39:06 volumio volumio[1161]: info: ------------------------------ 12ms May 22 21:39:06 volumio volumio[1161]: info: [1747921146004] [80s80s] adding url: http://streams.80s80s.de/web/mp3-192/volumio May 22 21:39:06 volumio volumio[1161]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" May 22 21:39:06 volumio volumio[1161]: info: May 22 21:39:06 volumio volumio[1161]: ---------------------------- MPD announces state update: options May 22 21:39:06 volumio volumio[1161]: info: ControllerMpd::getState May 22 21:39:06 volumio volumio[1161]: verbose: ControllerMpd::sendMpdCommand status May 22 21:39:06 volumio volumio[1161]: info: May 22 21:39:06 volumio volumio[1161]: ---------------------------- MPD announces state update: options May 22 21:39:06 volumio volumio[1161]: info: ControllerMpd::getState May 22 21:39:06 volumio volumio[1161]: verbose: ControllerMpd::sendMpdCommand status May 22 21:39:06 volumio volumio[1161]: error: Upnp client error: Error: This socket has been ended by the other party May 22 21:39:06 volumio volumio[1161]: info: May 22 21:39:06 volumio volumio[1161]: ---------------------------- MPD announces system playlist update May 22 21:39:06 volumio volumio[1161]: info: Ignoring MPD Status Update May 22 21:39:06 volumio volumio[1161]: info: sendMpdCommand status took 22 milliseconds May 22 21:39:06 volumio volumio[1161]: info: sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" took 22 milliseconds May 22 21:39:06 volumio volumio[1161]: info: sendMpdCommand status took 10 milliseconds May 22 21:39:06 volumio volumio[1161]: info: sendMpdCommand status took 8 milliseconds May 22 21:39:06 volumio volumio[1161]: verbose: ControllerMpd::parseState May 22 21:39:06 volumio volumio[1161]: verbose: ControllerMpd::sendMpdCommand play May 22 21:39:06 volumio volumio[1161]: verbose: ControllerMpd::parseState May 22 21:39:06 volumio volumio[1161]: verbose: ControllerMpd::parseState May 22 21:39:06 volumio volumio[1161]: info: ControllerMpd::pushState May 22 21:39:06 volumio volumio[1161]: info: CoreCommandRouter::servicePushState May 22 21:39:06 volumio volumio[1161]: info: CoreStateMachine::pushState May 22 21:39:06 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 May 22 21:39:06 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 21:39:06 volumio volumio[1161]: info: CoreCommandRouter::volumioPushState May 22 21:39:06 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 May 22 21:39:06 volumio volumio[1161]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 22 21:39:06 volumio volumio[1161]: info: ControllerMpd::pushState May 22 21:39:06 volumio volumio[1161]: info: CoreCommandRouter::servicePushState May 22 21:39:06 volumio volumio[1161]: info: CoreStateMachine::pushState May 22 21:39:06 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 May 22 21:39:06 volumio volumio[1161]: info: CoreCommandRouter::volumioPushState May 22 21:39:06 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 May 22 21:39:06 volumio volumio[1161]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 22 21:39:06 volumio volumio[1161]: info: ControllerMpd::pushState May 22 21:39:06 volumio volumio[1161]: info: CoreCommandRouter::servicePushState May 22 21:39:06 volumio volumio[1161]: info: CoreStateMachine::pushState May 22 21:39:06 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 May 22 21:39:06 volumio volumio[1161]: info: CoreCommandRouter::volumioPushState May 22 21:39:06 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 May 22 21:39:06 volumio volumio[1161]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd May 22 21:39:06 volumio volumio[1161]: info: ------------------------------ 71ms May 22 21:39:06 volumio volumio[1161]: info: ------------------------------ 72ms May 22 21:39:06 volumio volumio[1161]: info: ------------------------------ 61ms May 22 21:39:06 volumio volumio[1161]: info: May 22 21:39:06 volumio volumio[1161]: ---------------------------- MPD announces system playlist update May 22 21:39:06 volumio volumio[1161]: info: Ignoring MPD Status Update May 22 21:39:06 volumio volumio[1161]: info: May 22 21:39:06 volumio volumio[1161]: ---------------------------- MPD announces system playlist update May 22 21:39:06 volumio volumio[1161]: info: Ignoring MPD Status Update May 22 21:39:06 volumio volumio[1161]: info: ------------------------------ 61ms May 22 21:39:06 volumio volumio[1161]: info: sendMpdCommand play took 51 milliseconds May 22 21:39:06 volumio volumio[1161]: info: ------------------------------ 6ms May 22 21:39:06 volumio volumio[1161]: info: ------------------------------ 5ms May 22 21:39:06 volumio volumio[1161]: info: [1747921146088] [80s80s] Pushing the next song state: Cock Robin - Just Around the Corner May 22 21:39:06 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 May 22 21:39:06 volumio volumio[1161]: info: CoreCommandRouter::servicePushState May 22 21:39:06 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 May 22 21:39:06 volumio volumio[1161]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music124/v4/56/f5/8a/56f58a84-134e-7dd9-b278-aaae456248d2/source/600x600bb.jpg","name":"Cock Robin - Just Around the Corner","title":"Just Around the Corner","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"233","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 22 21:39:06 volumio volumio[1161]: verbose: CURRENT POSITION 0 May 22 21:39:06 volumio volumio[1161]: info: CoreStateMachine::syncState stateService play May 22 21:39:06 volumio volumio[1161]: info: CoreStateMachine::syncState currentStatus stop May 22 21:39:06 volumio volumio[1161]: info: [1747921146094] [80s80s] PlayNextTrack API delay: 30 May 22 21:39:06 volumio volumio[1161]: info: [1747921146116] [80s80s] Setting timer to: 233000 milliseconds. May 22 21:39:06 volumio volumio[1161]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 22 21:39:06 volumio volumio[1161]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 22 21:39:06 volumio volumio[1161]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 22 21:39:06 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 May 22 21:39:06 volumio volumio[1161]: info: CorePlayQueue::getTrack 1 May 22 21:39:11 volumio volumio[1161]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 21:39:11 volumio volumio[1161]: Error: Client network socket disconnected before secure TLS connection was established May 22 21:39:11 volumio volumio[1161]: at connResetException (internal/errors.js:639:14) May 22 21:39:11 volumio volumio[1161]: at TLSSocket.onConnectEnd (_tls_wrap.js:1570:19) May 22 21:39:11 volumio volumio[1161]: at TLSSocket.emit (events.js:412:35) May 22 21:39:11 volumio volumio[1161]: at endReadableNT (internal/streams/readable.js:1333:12) May 22 21:39:11 volumio volumio[1161]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { May 22 21:39:11 volumio volumio[1161]: code: 'ECONNRESET', May 22 21:39:11 volumio volumio[1161]: path: null, May 22 21:39:11 volumio volumio[1161]: host: 'lastfm.freetls.fastly.net', May 22 21:39:11 volumio volumio[1161]: port: 443, May 22 21:39:11 volumio volumio[1161]: localAddress: undefined May 22 21:39:11 volumio volumio[1161]: } May 22 21:39:11 volumio volumio[1161]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 21:39:11 volumio sudo[29394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 21:38 May 22 21:39:11 volumio sudo[29394]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"