-- Logs begin at Mon 2025-03-17 19:57:44 CET, end at Mon 2025-03-17 20:12:54 CET. -- Mar 17 20:11:01 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:11:03 volumio ntpd[3777]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 17 20:11:05 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:11:09 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:11:13 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:11:17 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:11:22 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:11:26 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:11:28 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 20:11:28 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 20:11:28 volumio volumio[1175]: info: Discovery: Getting this device information Mar 17 20:11:28 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Mar 17 20:11:28 volumio volumio[1175]: info: CorePlayQueue::getTrack 2 Mar 17 20:11:28 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 20:11:30 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:11:34 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:11:38 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:11:42 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:11:46 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:11:50 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:11:54 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:11:58 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:11:58 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 20:11:58 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 20:11:58 volumio volumio[1175]: info: Discovery: Getting this device information Mar 17 20:11:58 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Mar 17 20:11:58 volumio volumio[1175]: info: CorePlayQueue::getTrack 2 Mar 17 20:11:58 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 20:12:02 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:12:06 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:12:10 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:12:14 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:12:18 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:12:28 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 17 20:12:28 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 17 20:12:28 volumio volumio[1175]: info: Discovery: Getting this device information Mar 17 20:12:28 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Mar 17 20:12:28 volumio volumio[1175]: info: CorePlayQueue::getTrack 2 Mar 17 20:12:28 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 17 20:12:33 volumio volumio[1175]: verbose: New Socket.io Connection to 192.168.1.143 from 192.168.1.177 UA: Mozilla/5.0 (Linux; Android 14; SM-A546B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/133.0.6943.137 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Mar 17 20:12:33 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 17 20:12:33 volumio volumio[1175]: info: CoreCommandRouter::volumioGetState Mar 17 20:12:33 volumio volumio[1175]: info: CorePlayQueue::getTrack 2 Mar 17 20:12:33 volumio volumio[1175]: info: CoreCommandRouter::volumioGetVisibleSources Mar 17 20:12:33 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 17 20:12:33 volumio volumio[1175]: info: CoreCommandRouter::volumioGetQueue Mar 17 20:12:33 volumio volumio[1175]: info: CoreStateMachine::getQueue Mar 17 20:12:33 volumio volumio[1175]: info: CorePlayQueue::getQueue Mar 17 20:12:33 volumio volumio[1175]: info: Listing playlists Mar 17 20:12:34 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:12:35 volumio volumio[1175]: info: CorePlayQueue::getTrack 2 Mar 17 20:12:35 volumio volumio[1175]: info: CorePlayQueue::getTrack 3 Mar 17 20:12:35 volumio volumio[1175]: info: Prefetching next song Mar 17 20:12:35 volumio volumio[1175]: info: DOING PREFETCH IN MPD Mar 17 20:12:35 volumio volumio[1175]: verbose: ControllerMpd::sendMpdCommand add "USB/COCU256/Dave’s True Story/Sex Without Bodies/04 Track 04.wav" Mar 17 20:12:35 volumio volumio[1175]: info: Mar 17 20:12:35 volumio volumio[1175]: ---------------------------- MPD announces system playlist update Mar 17 20:12:35 volumio volumio[1175]: info: Ignoring MPD Status Update Mar 17 20:12:35 volumio volumio[1175]: info: sendMpdCommand add "USB/COCU256/Dave’s True Story/Sex Without Bodies/04 Track 04.wav" took 1 milliseconds Mar 17 20:12:35 volumio volumio[1175]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 17 20:12:35 volumio volumio[1175]: info: Mar 17 20:12:35 volumio volumio[1175]: ---------------------------- MPD announces system playlist update Mar 17 20:12:35 volumio volumio[1175]: info: Ignoring MPD Status Update Mar 17 20:12:35 volumio volumio[1175]: info: ------------------------------ 1ms Mar 17 20:12:35 volumio volumio[1175]: info: sendMpdCommand consume 1 took 1 milliseconds Mar 17 20:12:35 volumio volumio[1175]: info: ------------------------------ 0ms Mar 17 20:12:37 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:12:39 volumio volumio[1175]: info: CoreStateMachine::startPlaybackTimer Mar 17 20:12:39 volumio volumio[1175]: info: CorePlayQueue::getTrack 3 Mar 17 20:12:39 volumio volumio[1175]: info: Mar 17 20:12:39 volumio volumio[1175]: ---------------------------- MPD announces system playlist update Mar 17 20:12:39 volumio volumio[1175]: info: Ignoring MPD Status Update Mar 17 20:12:39 volumio volumio[1175]: info: Mar 17 20:12:39 volumio volumio[1175]: ---------------------------- MPD announces state update: player Mar 17 20:12:39 volumio volumio[1175]: info: ControllerMpd::getState Mar 17 20:12:39 volumio volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:12:39 volumio volumio[1175]: info: Mar 17 20:12:39 volumio volumio[1175]: ---------------------------- MPD announces system playlist update Mar 17 20:12:39 volumio volumio[1175]: info: Ignoring MPD Status Update Mar 17 20:12:39 volumio volumio[1175]: info: Mar 17 20:12:39 volumio volumio[1175]: ---------------------------- MPD announces state update: player Mar 17 20:12:39 volumio volumio[1175]: info: ControllerMpd::getState Mar 17 20:12:39 volumio volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Mar 17 20:12:39 volumio volumio[1175]: info: ------------------------------ 2ms Mar 17 20:12:39 volumio volumio[1175]: info: sendMpdCommand status took 1 milliseconds Mar 17 20:12:39 volumio volumio[1175]: info: ------------------------------ 2ms Mar 17 20:12:39 volumio volumio[1175]: verbose: ControllerMpd::parseState Mar 17 20:12:39 volumio volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 20:12:39 volumio volumio[1175]: info: sendMpdCommand status took 1 milliseconds Mar 17 20:12:39 volumio volumio[1175]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 17 20:12:39 volumio volumio[1175]: verbose: ControllerMpd::parseState Mar 17 20:12:39 volumio volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 17 20:12:39 volumio volumio[1175]: verbose: ControllerMpd::parseTrackInfo Mar 17 20:12:39 volumio volumio[1175]: info: ControllerMpd::pushState Mar 17 20:12:39 volumio volumio[1175]: info: CoreCommandRouter::servicePushState Mar 17 20:12:39 volumio volumio[1175]: info: CorePlayQueue::getTrack 3 Mar 17 20:12:39 volumio volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":180,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 04","artist":"Dave's True Story","album":"Sex Without Bodies","uri":"USB/COCU256/Dave’s True Story/Sex Without Bodies/04 Track 04.wav","trackType":"wav"} Mar 17 20:12:39 volumio volumio[1175]: verbose: CURRENT POSITION 3 Mar 17 20:12:39 volumio volumio[1175]: info: CoreStateMachine::syncState stateService play Mar 17 20:12:39 volumio volumio[1175]: info: CoreStateMachine::syncState currentStatus play Mar 17 20:12:39 volumio volumio[1175]: info: Received an update from plugin. extracting info from payload Mar 17 20:12:39 volumio volumio[1175]: info: CoreStateMachine::pushState Mar 17 20:12:39 volumio volumio[1175]: info: CorePlayQueue::getTrack 3 Mar 17 20:12:39 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 20:12:39 volumio volumio[1175]: info: CoreCommandRouter::volumioPushState Mar 17 20:12:39 volumio volumio[1175]: info: CoreStateMachine::pushState Mar 17 20:12:39 volumio volumio[1175]: info: CorePlayQueue::getTrack 3 Mar 17 20:12:39 volumio volumio[1175]: info: CoreCommandRouter::volumioPushState Mar 17 20:12:39 volumio volumio[1175]: info: ------------------------------ 11ms Mar 17 20:12:39 volumio volumio[1175]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 17 20:12:39 volumio volumio[1175]: verbose: ControllerMpd::parseTrackInfo Mar 17 20:12:39 volumio volumio[1175]: info: ControllerMpd::pushState Mar 17 20:12:39 volumio volumio[1175]: info: CoreCommandRouter::servicePushState Mar 17 20:12:39 volumio volumio[1175]: info: CorePlayQueue::getTrack 3 Mar 17 20:12:39 volumio volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":180,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 04","artist":"Dave's True Story","album":"Sex Without Bodies","uri":"USB/COCU256/Dave’s True Story/Sex Without Bodies/04 Track 04.wav","trackType":"wav"} Mar 17 20:12:39 volumio volumio[1175]: verbose: CURRENT POSITION 3 Mar 17 20:12:39 volumio volumio[1175]: info: CoreStateMachine::syncState stateService play Mar 17 20:12:39 volumio volumio[1175]: info: CoreStateMachine::syncState currentStatus play Mar 17 20:12:39 volumio volumio[1175]: info: Received an update from plugin. extracting info from payload Mar 17 20:12:39 volumio volumio[1175]: info: CoreStateMachine::pushState Mar 17 20:12:39 volumio volumio[1175]: info: CorePlayQueue::getTrack 3 Mar 17 20:12:39 volumio volumio[1175]: info: CoreCommandRouter::volumioPushState Mar 17 20:12:39 volumio volumio[1175]: info: CoreStateMachine::pushState Mar 17 20:12:39 volumio volumio[1175]: info: CorePlayQueue::getTrack 3 Mar 17 20:12:39 volumio volumio[1175]: info: CoreCommandRouter::volumioPushState Mar 17 20:12:39 volumio volumio[1175]: info: ------------------------------ 17ms Mar 17 20:12:40 volumio volumio[1175]: info: CoreStateMachine::pushState Mar 17 20:12:40 volumio volumio[1175]: info: CorePlayQueue::getTrack 3 Mar 17 20:12:40 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 20:12:40 volumio volumio[1175]: info: CoreCommandRouter::volumioPushState Mar 17 20:12:41 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:12:41 volumio volumio[1175]: info: Disabling MyMusic plugin upnp Mar 17 20:12:41 volumio sudo[5071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Mar 17 20:12:41 volumio sudo[5071]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 20:12:41 volumio systemd[1]: Starting Cleanup of Temporary Directories... Mar 17 20:12:41 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Mar 17 20:12:41 volumio volumio[1175]: error: Upnp client error: Error: This socket has been ended by the other party Mar 17 20:12:41 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Mar 17 20:12:41 volumio systemd[1]: Started Cleanup of Temporary Directories. Mar 17 20:12:45 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:12:49 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:12:53 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 17 20:12:54 volumio volumio[1175]: info: Enabling MyMusic plugin upnp Mar 17 20:12:54 volumio volumio[1175]: info: Enabling plugin upnp Mar 17 20:12:54 volumio volumio[1175]: info: Loading plugin "upnp"... Mar 17 20:12:54 volumio volumio[1175]: info: [1742238774467] Starting Upmpd Daemon Mar 17 20:12:54 volumio volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 17 20:12:54 volumio volumio[1175]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 17 20:12:54 volumio volumio[1175]: Error: listen EADDRINUSE: address already in use :::6599 Mar 17 20:12:54 volumio volumio[1175]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Mar 17 20:12:54 volumio volumio[1175]: at listenInCluster (net.js:1379:12) Mar 17 20:12:54 volumio volumio[1175]: at Server.listen (net.js:1465:7) Mar 17 20:12:54 volumio volumio[1175]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Mar 17 20:12:54 volumio volumio[1175]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Mar 17 20:12:54 volumio volumio[1175]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Mar 17 20:12:54 volumio volumio[1175]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 17 20:12:54 volumio volumio[1175]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Mar 17 20:12:54 volumio volumio[1175]: code: 'EADDRINUSE', Mar 17 20:12:54 volumio volumio[1175]: errno: -98, Mar 17 20:12:54 volumio volumio[1175]: syscall: 'listen', Mar 17 20:12:54 volumio volumio[1175]: address: '::', Mar 17 20:12:54 volumio volumio[1175]: port: 6599 Mar 17 20:12:54 volumio volumio[1175]: } Mar 17 20:12:54 volumio volumio[1175]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 17 20:12:54 volumio sudo[5147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-17 20:11 Mar 17 20:12:54 volumio sudo[5147]: 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"