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