-- Logs begin at Mon 2024-07-15 00:07:52 EEST, end at Tue 2024-07-16 18:45:28 EEST. -- Jul 16 18:44:00 elektra volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 16 18:44:00 elektra volumio[888]: info: CURURI: music-library/NAS/FromHeadphones Jul 16 18:44:01 elektra volumio[888]: info: Preload queue cleared Jul 16 18:44:04 elektra volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 16 18:44:04 elektra volumio[888]: info: CURURI: music-library/NAS/FromHeadphones/Lossless Jul 16 18:44:04 elektra volumio[888]: info: Preload queue cleared Jul 16 18:44:08 elektra volumio[888]: info: CoreCommandRouter::volumioGetState Jul 16 18:44:18 elektra volumio[888]: info: CoreCommandRouter::volumioGetState Jul 16 18:44:18 elektra volumio[888]: info: Listing playlists Jul 16 18:44:18 elektra volumio[888]: info: Listing playlists Jul 16 18:44:28 elektra volumio[888]: info: CoreCommandRouter::volumioGetState Jul 16 18:44:38 elektra volumio[888]: info: CoreCommandRouter::volumioGetState Jul 16 18:44:38 elektra volumio[888]: info: Listing playlists Jul 16 18:44:38 elektra volumio[888]: info: Listing playlists Jul 16 18:44:47 elektra volumio[888]: info: CoreCommandRouter::volumioGetState Jul 16 18:44:54 elektra volumio[888]: info: MRS: Updating multiroomSync output Jul 16 18:44:57 elektra volumio[888]: info: CoreCommandRouter::volumioGetState Jul 16 18:44:57 elektra volumio[888]: info: Listing playlists Jul 16 18:44:57 elektra volumio[888]: info: Listing playlists Jul 16 18:45:05 elektra volumio[888]: info: CoreCommandRouter::volumioGetState Jul 16 18:45:07 elektra volumio[888]: info: Executing endpoint metavolumio Jul 16 18:45:07 elektra volumio[888]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 16 18:45:07 elektra volumio[888]: info: Executing endpoint metavolumio Jul 16 18:45:07 elektra volumio[888]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 16 18:45:07 elektra volumio[888]: info: Executing endpoint metavolumio Jul 16 18:45:07 elektra volumio[888]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 16 18:45:07 elektra volumio[888]: info: CoreCommandRouter::volumioGetState Jul 16 18:45:08 elektra volumio[888]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 16 18:45:08 elektra volumio[888]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 16 18:45:08 elektra volumio[888]: info: Retrieving Cloud Streaming UI Jul 16 18:45:08 elektra volumio[888]: info: Getting Tidal Cloud Configuration Jul 16 18:45:08 elektra volumio[888]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 16 18:45:08 elektra volumio[888]: info: Getting Qobuz Cloud Configuration Jul 16 18:45:08 elektra volumio[888]: info: Asking plugin for UI Config Jul 16 18:45:08 elektra volumio[888]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 16 18:45:08 elektra volumio[888]: info: Getting Spotify Cloud Configuration Jul 16 18:45:08 elektra volumio[888]: info: Asking plugin for UI Config Jul 16 18:45:08 elektra volumio[888]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 16 18:45:08 elektra volumio[888]: info: Saving Spotify Acccount Jul 16 18:45:08 elektra volumio[888]: info: Got it Jul 16 18:45:08 elektra volumio[888]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 16 18:45:08 elektra volumio[888]: info: Got Tidal Cloud Configuration Jul 16 18:45:08 elektra volumio[888]: info: Got it Jul 16 18:45:08 elektra volumio[888]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 16 18:45:08 elektra volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 16 18:45:08 elektra volumio[888]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 16 18:45:08 elektra volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 16 18:45:08 elektra volumio[888]: info: CoreCommandRouter::volumioGetBrowseSources Jul 16 18:45:08 elektra volumio[888]: info: CoreCommandRouter::volumioGetBrowseSources Jul 16 18:45:08 elektra volumio[888]: info: CoreCommandRouter::volumioGetBrowseSources Jul 16 18:45:08 elektra volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 16 18:45:08 elektra volumio[888]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 16 18:45:10 elektra volumio[888]: error: Failed request for metavolumio API Jul 16 18:45:11 elektra volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jul 16 18:45:11 elektra volumio[888]: verbose: ControllerMpd::sendMpdCommand update Jul 16 18:45:11 elektra volumio[888]: verbose: ControllerMpd::sendMpdCommand status Jul 16 18:45:11 elektra volumio[888]: info: Jul 16 18:45:11 elektra volumio[888]: ---------------------------- MPD announces state update: update Jul 16 18:45:11 elektra volumio[888]: info: sendMpdCommand update took 19 milliseconds Jul 16 18:45:11 elektra volumio[888]: info: ControllerMpd::getState Jul 16 18:45:11 elektra volumio[888]: verbose: ControllerMpd::sendMpdCommand status Jul 16 18:45:11 elektra volumio[888]: info: sendMpdCommand status took 14 milliseconds Jul 16 18:45:11 elektra volumio[888]: info: sendMpdCommand status took 2 milliseconds Jul 16 18:45:11 elektra volumio[888]: verbose: ControllerMpd::parseState Jul 16 18:45:11 elektra volumio[888]: info: Command Router : Notfying DB Updatetrue Jul 16 18:45:11 elektra volumio[888]: verbose: ControllerMpd::parseState Jul 16 18:45:11 elektra volumio[888]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 18:45:11 elektra volumio[888]: info: sendMpdCommand playlistinfo took 21 milliseconds Jul 16 18:45:11 elektra volumio[888]: verbose: ControllerMpd::parseTrackInfo Jul 16 18:45:11 elektra volumio[888]: info: ControllerMpd::pushState Jul 16 18:45:11 elektra volumio[888]: info: CoreCommandRouter::servicePushState Jul 16 18:45:11 elektra volumio[888]: info: CorePlayQueue::getTrack 0 Jul 16 18:45:11 elektra volumio[888]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3597,"duration":210,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"715 Kbps","isStreaming":false,"title":"144634896&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/144634896&soundQuality=LOSSLESS","trackType":"tidal"} Jul 16 18:45:11 elektra volumio[888]: verbose: CURRENT POSITION 0 Jul 16 18:45:11 elektra volumio[888]: info: CoreStateMachine::syncState stateService pause Jul 16 18:45:11 elektra volumio[888]: info: CoreStateMachine::syncState currentStatus pause Jul 16 18:45:11 elektra volumio[888]: info: CoreStateMachine::pushState Jul 16 18:45:11 elektra volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 18:45:11 elektra volumio[888]: info: CoreCommandRouter::volumioPushState Jul 16 18:45:11 elektra volumio[888]: info: MRS: Pushing multiroomSync output update for this device Jul 16 18:45:11 elektra volumio[888]: info: MRS: Pushing multiroomSync output Jul 16 18:45:11 elektra volumio[888]: info: CoreStateMachine::stPlaybackTimer Jul 16 18:45:11 elektra volumio[888]: info: ------------------------------ 149ms Jul 16 18:45:12 elektra volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 16 18:45:13 elektra volumio[888]: info: CoreCommandRouter::Close All Modals sent Jul 16 18:45:16 elektra volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 16 18:45:17 elektra volumio[888]: info: CoreCommandRouter::volumioGetState Jul 16 18:45:17 elektra volumio[888]: info: Listing playlists Jul 16 18:45:17 elektra volumio[888]: info: Listing playlists Jul 16 18:45:20 elektra volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 16 18:45:23 elektra volumio[888]: info: Disabling MyMusic plugin upnp Jul 16 18:45:23 elektra sudo[19413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jul 16 18:45:23 elektra sudo[19413]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 16 18:45:23 elektra systemd[1]: Stopping UPnP Renderer front-end to MPD... Jul 16 18:45:23 elektra volumio[888]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 16 18:45:23 elektra volumio[888]: error: Upnp client error: Error: This socket has been ended by the other party Jul 16 18:45:24 elektra volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 16 18:45:27 elektra volumio[888]: info: CoreCommandRouter::volumioGetState Jul 16 18:45:28 elektra volumio[888]: info: Enabling MyMusic plugin upnp Jul 16 18:45:28 elektra volumio[888]: info: Enabling plugin upnp Jul 16 18:45:28 elektra volumio[888]: info: Loading plugin "upnp"... Jul 16 18:45:28 elektra volumio[888]: info: [1721144728406] Starting Upmpd Daemon Jul 16 18:45:28 elektra volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 16 18:45:28 elektra volumio[888]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 16 18:45:28 elektra volumio[888]: Error: listen EADDRINUSE: address already in use :::6599 Jul 16 18:45:28 elektra volumio[888]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Jul 16 18:45:28 elektra volumio[888]: at listenInCluster (net.js:1379:12) Jul 16 18:45:28 elektra volumio[888]: at Server.listen (net.js:1465:7) Jul 16 18:45:28 elektra volumio[888]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jul 16 18:45:28 elektra volumio[888]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jul 16 18:45:28 elektra volumio[888]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jul 16 18:45:28 elektra volumio[888]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 16 18:45:28 elektra volumio[888]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Jul 16 18:45:28 elektra volumio[888]: code: 'EADDRINUSE', Jul 16 18:45:28 elektra volumio[888]: errno: -98, Jul 16 18:45:28 elektra volumio[888]: syscall: 'listen', Jul 16 18:45:28 elektra volumio[888]: address: '::', Jul 16 18:45:28 elektra volumio[888]: port: 6599 Jul 16 18:45:28 elektra volumio[888]: } Jul 16 18:45:28 elektra volumio[888]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 16 18:45:28 elektra sudo[19424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-16 18:44 Jul 16 18:45:28 elektra sudo[19424]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"