-- 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"