-- Logs begin at Sun 2024-08-25 05:00:39 UTC, end at Sun 2024-09-01 14:10:37 UTC. -- Sep 01 14:09:04 volumio ntpd[18378]: Soliciting pool server 2803:bc40:8160::3 Sep 01 14:09:13 volumio volumio[673]: info: CoreCommandRouter::volumioGetState Sep 01 14:09:13 volumio volumio[673]: info: CorePlayQueue::getTrack 0 Sep 01 14:09:16 volumio volumio[673]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:09:16 volumio volumio[673]: info: Sep 01 14:09:16 volumio volumio[673]: ---------------------------- MPD announces state update: update Sep 01 14:09:16 volumio volumio[673]: info: ControllerMpd::getState Sep 01 14:09:16 volumio volumio[673]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:09:16 volumio volumio[673]: info: sendMpdCommand status took 4 milliseconds Sep 01 14:09:16 volumio volumio[673]: info: sendMpdCommand status took 4 milliseconds Sep 01 14:09:16 volumio volumio[673]: verbose: ControllerMpd::parseState Sep 01 14:09:16 volumio volumio[673]: info: Command Router : Notfying DB Updatetrue Sep 01 14:09:16 volumio volumio[673]: info: CoreCommandRouter::Close All Modals sent Sep 01 14:09:16 volumio volumio[673]: verbose: ControllerMpd::parseState Sep 01 14:09:16 volumio volumio[673]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:09:16 volumio volumio[673]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 14:09:16 volumio volumio[673]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:09:16 volumio volumio[673]: info: ControllerMpd::pushState Sep 01 14:09:16 volumio volumio[673]: info: CoreCommandRouter::servicePushState Sep 01 14:09:16 volumio volumio[673]: info: CoreStateMachine::pushState Sep 01 14:09:16 volumio volumio[673]: info: CorePlayQueue::getTrack 0 Sep 01 14:09:16 volumio volumio[673]: info: CoreCommandRouter::volumioPushState Sep 01 14:09:16 volumio volumio[673]: info: CorePlayQueue::getTrack 0 Sep 01 14:09:16 volumio volumio[673]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Sep 01 14:09:16 volumio volumio[673]: info: ------------------------------ 100ms Sep 01 14:09:19 volumio volumio[673]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 14:09:19 volumio volumio[673]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 14:09:19 volumio volumio[673]: info: Retrieving Cloud Streaming UI Sep 01 14:09:19 volumio volumio[673]: info: Getting Tidal Cloud Configuration Sep 01 14:09:19 volumio volumio[673]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 14:09:19 volumio volumio[673]: info: Getting Qobuz Cloud Configuration Sep 01 14:09:19 volumio volumio[673]: info: Asking plugin for UI Config Sep 01 14:09:19 volumio volumio[673]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 14:09:19 volumio volumio[673]: info: Getting Spotify Cloud Configuration Sep 01 14:09:19 volumio volumio[673]: info: Asking plugin for UI Config Sep 01 14:09:19 volumio volumio[673]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 14:09:19 volumio volumio[673]: info: Saving Spotify Acccount Sep 01 14:09:19 volumio volumio[673]: info: Got Tidal Cloud Configuration Sep 01 14:09:19 volumio volumio[673]: info: Got it Sep 01 14:09:19 volumio volumio[673]: info: Got it Sep 01 14:09:19 volumio volumio[673]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 01 14:09:19 volumio volumio[673]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 01 14:09:19 volumio volumio[673]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 01 14:09:19 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 01 14:09:19 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 14:09:19 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 14:09:19 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 01 14:09:19 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 14:09:19 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 14:09:19 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 14:09:19 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 14:09:19 volumio volumio[673]: info: CoreCommandRouter::volumioGetBrowseSources Sep 01 14:09:19 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:09:19 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 01 14:09:23 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Sep 01 14:09:23 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:09:23 volumio sudo[18951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Musica Sep 01 14:09:23 volumio sudo[18951]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 14:09:23 volumio sudo[18951]: pam_unix(sudo:session): session closed for user root Sep 01 14:09:23 volumio volumio[673]: error: Mount point '/mnt/NAS/Musica' cannot be removed. Error: exec error Error: Command failed: /usr/bin/sudo /bin/umount '/mnt/NAS/Musica' Sep 01 14:09:23 volumio volumio[673]: umount.nfs4: /mnt/NAS/Musica: device is busy Sep 01 14:09:24 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 01 14:09:26 volumio volumio[673]: info: [1725199766374] Database update started Sep 01 14:09:27 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:09:31 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:09:35 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:09:39 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:09:43 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:09:47 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:09:51 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:09:55 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:09:59 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:10:03 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:10:07 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:10:10 volumio ntpd[18378]: Soliciting pool server 2803:bc40:8160::3 Sep 01 14:10:11 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:10:15 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:10:19 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:10:22 volumio nmbd[542]: [2024/09/01 14:10:22.280741, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 01 14:10:22 volumio nmbd[542]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.4.51 for name WORKGROUP<1d>. Sep 01 14:10:22 volumio nmbd[542]: This response was from IP 192.168.4.62, reporting an IP address of 192.168.4.62. Sep 01 14:10:22 volumio nmbd[542]: [2024/09/01 14:10:22.282055, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 01 14:10:22 volumio nmbd[542]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.4.51 for name WORKGROUP<1d>. Sep 01 14:10:22 volumio nmbd[542]: This response was from IP 192.168.4.57, reporting an IP address of 192.168.4.62. Sep 01 14:10:23 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:10:23 volumio volumio[673]: info: Disabling MyMusic plugin upnp_browser Sep 01 14:10:23 volumio volumio[673]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers Sep 01 14:10:23 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 14:10:23 volumio volumio[673]: info: Disabling plugin upnp_browser Sep 01 14:10:23 volumio volumio[673]: info: Done. Sep 01 14:10:27 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:10:28 volumio volumio[673]: info: Disabling MyMusic plugin airplay_emulation Sep 01 14:10:28 volumio volumio[673]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Sep 01 14:10:28 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 14:10:28 volumio volumio[673]: info: Disabling plugin airplay_emulation Sep 01 14:10:28 volumio volumio[673]: info: Done. Sep 01 14:10:28 volumio sudo[18969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Sep 01 14:10:28 volumio sudo[18969]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 14:10:28 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 01 14:10:28 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 01 14:10:28 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 01 14:10:28 volumio sudo[18969]: pam_unix(sudo:session): session closed for user root Sep 01 14:10:28 volumio volumio[673]: info: Shairport-Sync Stopped Sep 01 14:10:28 volumio volumio[673]: info: Disabling MyMusic plugin upnp Sep 01 14:10:28 volumio sudo[18976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 01 14:10:28 volumio sudo[18976]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 14:10:28 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Sep 01 14:10:28 volumio volumio[673]: error: Upnp client error: Error: This socket has been ended by the other party Sep 01 14:10:31 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:10:34 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Sep 01 14:10:34 volumio sudo[18983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Musica Sep 01 14:10:34 volumio sudo[18983]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 14:10:34 volumio sudo[18983]: pam_unix(sudo:session): session closed for user root Sep 01 14:10:34 volumio volumio[673]: error: Mount point '/mnt/NAS/Musica' cannot be removed. Error: exec error Error: Command failed: /usr/bin/sudo /bin/umount '/mnt/NAS/Musica' Sep 01 14:10:34 volumio volumio[673]: umount.nfs4: /mnt/NAS/Musica: device is busy Sep 01 14:10:35 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 14:10:35 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 01 14:10:36 volumio volumio[673]: info: Enabling MyMusic plugin upnp Sep 01 14:10:36 volumio volumio[673]: info: Enabling plugin upnp Sep 01 14:10:36 volumio volumio[673]: info: Loading plugin "upnp"... Sep 01 14:10:36 volumio volumio[673]: info: [1725199836774] Starting Upmpd Daemon Sep 01 14:10:36 volumio volumio[673]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 14:10:36 volumio volumio[673]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 14:10:36 volumio volumio[673]: Error: listen EADDRINUSE: address already in use 0.0.0.0:6599 Sep 01 14:10:36 volumio volumio[673]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Sep 01 14:10:36 volumio volumio[673]: at listenInCluster (net.js:1379:12) Sep 01 14:10:36 volumio volumio[673]: at Server.listen (net.js:1465:7) Sep 01 14:10:36 volumio volumio[673]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Sep 01 14:10:36 volumio volumio[673]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Sep 01 14:10:36 volumio volumio[673]: at Promise._successFn (/volumio/app/pluginmanager.js:1854:19) Sep 01 14:10:36 volumio volumio[673]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 01 14:10:36 volumio volumio[673]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Sep 01 14:10:36 volumio volumio[673]: code: 'EADDRINUSE', Sep 01 14:10:36 volumio volumio[673]: errno: -98, Sep 01 14:10:36 volumio volumio[673]: syscall: 'listen', Sep 01 14:10:36 volumio volumio[673]: address: '0.0.0.0', Sep 01 14:10:36 volumio volumio[673]: port: 6599 Sep 01 14:10:36 volumio volumio[673]: } Sep 01 14:10:36 volumio volumio[673]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 14:10:37 volumio sudo[19002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-01 14:09 Sep 01 14:10:37 volumio sudo[19002]: 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="a89bd62ec6362775dacd2a1210dd0d7fd278d49f" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="89a9d5254c41926abc738c3a5cb276d02612dcf5" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 22 Apr 2023 09:44:37 PM CEST" VOLUMIO_VERSION="3.449" VOLUMIO_HARDWARE="odroidc2" VOLUMIO_DEVICENAME="Odroid-C2" VOLUMIO_HASH="a32fc8817cd1f12a7179c3dfe12b5ebb"