-- Logs begin at Thu 2019-02-14 04:12:00 CST, end at Fri 2024-09-06 12:55:06 CDT. -- Sep 06 12:54:00 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:54:04 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:54:06 volumio wpa_supplicant[872]: wlan0: Failed to initiate sched scan Sep 06 12:54:09 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:54:13 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:54:14 volumio wpa_supplicant[872]: wlan0: Failed to initiate sched scan Sep 06 12:54:16 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:54:20 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:54:22 volumio wpa_supplicant[872]: wlan0: Failed to initiate sched scan Sep 06 12:54:24 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:54:28 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:54:29 volumio wpa_supplicant[872]: wlan0: Failed to initiate sched scan Sep 06 12:54:33 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:54:36 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:54:37 volumio wpa_supplicant[872]: wlan0: Failed to initiate sched scan Sep 06 12:54:39 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 06 12:54:39 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand rescan Sep 06 12:54:39 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:54:39 volumio volumio[1073]: info: Sep 06 12:54:39 volumio volumio[1073]: ---------------------------- MPD announces state update: update Sep 06 12:54:39 volumio volumio[1073]: info: sendMpdCommand rescan took 3 milliseconds Sep 06 12:54:39 volumio volumio[1073]: info: ControllerMpd::getState Sep 06 12:54:39 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:54:39 volumio volumio[1073]: info: sendMpdCommand status took 3 milliseconds Sep 06 12:54:39 volumio volumio[1073]: verbose: ControllerMpd::parseState Sep 06 12:54:39 volumio volumio[1073]: info: Command Router : Notfying DB Updatetrue Sep 06 12:54:39 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:54:39 volumio volumio[1073]: info: Sep 06 12:54:39 volumio volumio[1073]: ---------------------------- MPD announces state update: update Sep 06 12:54:39 volumio volumio[1073]: info: sendMpdCommand status took 27 milliseconds Sep 06 12:54:39 volumio volumio[1073]: info: ControllerMpd::getState Sep 06 12:54:39 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Sep 06 12:54:39 volumio volumio[1073]: verbose: ControllerMpd::parseState Sep 06 12:54:39 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:54:39 volumio volumio[1073]: info: sendMpdCommand status took 4 milliseconds Sep 06 12:54:39 volumio volumio[1073]: info: sendMpdCommand status took 4 milliseconds Sep 06 12:54:39 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 06 12:54:39 volumio volumio[1073]: verbose: ControllerMpd::parseState Sep 06 12:54:39 volumio volumio[1073]: info: Command Router : Notfying DB Updatefalse Sep 06 12:54:39 volumio volumio[1073]: info: CoreCommandRouter::Close All Modals sent Sep 06 12:54:39 volumio volumio[1073]: verbose: ControllerMpd::parseState Sep 06 12:54:39 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 06 12:54:39 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:54:39 volumio volumio[1073]: info: ControllerMpd::pushState Sep 06 12:54:39 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Sep 06 12:54:39 volumio volumio[1073]: info: CorePlayQueue::getTrack 5 Sep 06 12:54:39 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":96964,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1043 Kbps","isStreaming":false,"title":"120341662","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/120341662","trackType":"qobuz"} Sep 06 12:54:39 volumio volumio[1073]: verbose: CURRENT POSITION 5 Sep 06 12:54:39 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Sep 06 12:54:39 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:54:39 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Sep 06 12:54:39 volumio volumio[1073]: info: CoreStateMachine::pushState Sep 06 12:54:39 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:54:39 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Sep 06 12:54:39 volumio volumio[1073]: info: MRS: Pushing multiroomSync output update for this device Sep 06 12:54:39 volumio volumio[1073]: info: MRS: Pushing multiroomSync output Sep 06 12:54:39 volumio volumio[1073]: info: CoreStateMachine::pushState Sep 06 12:54:39 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Sep 06 12:54:39 volumio volumio[1073]: info: MRS: Pushing multiroomSync output update for this device Sep 06 12:54:39 volumio volumio[1073]: info: MRS: Pushing multiroomSync output Sep 06 12:54:39 volumio volumio[1073]: info: ------------------------------ 71ms Sep 06 12:54:39 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 15 milliseconds Sep 06 12:54:39 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Sep 06 12:54:39 volumio volumio[1073]: info: ControllerMpd::pushState Sep 06 12:54:39 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Sep 06 12:54:39 volumio volumio[1073]: info: CorePlayQueue::getTrack 5 Sep 06 12:54:39 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":0,"seek":96964,"duration":292,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1043 Kbps","isStreaming":false,"title":"120341662","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/120341662","trackType":"qobuz"} Sep 06 12:54:39 volumio volumio[1073]: verbose: CURRENT POSITION 5 Sep 06 12:54:39 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Sep 06 12:54:39 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Sep 06 12:54:39 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Sep 06 12:54:39 volumio volumio[1073]: info: CoreStateMachine::pushState Sep 06 12:54:39 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 06 12:54:39 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Sep 06 12:54:39 volumio volumio[1073]: info: MRS: Pushing multiroomSync output update for this device Sep 06 12:54:39 volumio volumio[1073]: info: MRS: Pushing multiroomSync output Sep 06 12:54:39 volumio volumio[1073]: info: CoreStateMachine::pushState Sep 06 12:54:39 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Sep 06 12:54:39 volumio volumio[1073]: info: MRS: Pushing multiroomSync output update for this device Sep 06 12:54:39 volumio volumio[1073]: info: MRS: Pushing multiroomSync output Sep 06 12:54:39 volumio volumio[1073]: info: ------------------------------ 62ms Sep 06 12:54:40 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:54:44 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:54:45 volumio wpa_supplicant[872]: wlan0: Failed to initiate sched scan Sep 06 12:54:48 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:54:52 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:54:53 volumio wpa_supplicant[872]: wlan0: Failed to initiate sched scan Sep 06 12:54:57 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:55:00 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:55:01 volumio wpa_supplicant[872]: wlan0: Failed to initiate sched scan Sep 06 12:55:04 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 06 12:55:05 volumio volumio[1073]: info: Disabling MyMusic plugin upnp Sep 06 12:55:05 volumio sudo[4357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 06 12:55:05 volumio sudo[4357]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 06 12:55:05 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Sep 06 12:55:05 volumio volumio[1073]: error: Upnp client error: Error: This socket has been ended by the other party Sep 06 12:55:05 volumio volumio[1073]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 06 12:55:06 volumio volumio[1073]: info: Enabling MyMusic plugin upnp Sep 06 12:55:06 volumio volumio[1073]: info: Enabling plugin upnp Sep 06 12:55:06 volumio volumio[1073]: info: Loading plugin "upnp"... Sep 06 12:55:06 volumio volumio[1073]: info: [1725645306527] Starting Upmpd Daemon Sep 06 12:55:06 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 06 12:55:06 volumio volumio[1073]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 12:55:06 volumio volumio[1073]: Error: listen EADDRINUSE: address already in use :::6599 Sep 06 12:55:06 volumio volumio[1073]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Sep 06 12:55:06 volumio volumio[1073]: at listenInCluster (net.js:1366:12) Sep 06 12:55:06 volumio volumio[1073]: at Server.listen (net.js:1452:7) Sep 06 12:55:06 volumio volumio[1073]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Sep 06 12:55:06 volumio volumio[1073]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Sep 06 12:55:06 volumio volumio[1073]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Sep 06 12:55:06 volumio volumio[1073]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 06 12:55:06 volumio volumio[1073]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Sep 06 12:55:06 volumio volumio[1073]: code: 'EADDRINUSE', Sep 06 12:55:06 volumio volumio[1073]: errno: -98, Sep 06 12:55:06 volumio volumio[1073]: syscall: 'listen', Sep 06 12:55:06 volumio volumio[1073]: address: '::', Sep 06 12:55:06 volumio volumio[1073]: port: 6599 Sep 06 12:55:06 volumio volumio[1073]: } Sep 06 12:55:06 volumio volumio[1073]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 06 12:55:06 volumio sudo[4368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-06 12:54 Sep 06 12:55:06 volumio sudo[4368]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"