-- Logs begin at Tue 2024-09-17 17:06:54 CEST, end at Wed 2024-10-02 20:23:23 CEST. -- Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 20:22:01 volumio volumio[913]: info: Retrieving Cloud Streaming UI Oct 02 20:22:01 volumio volumio[913]: info: Getting Tidal Cloud Configuration Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 20:22:01 volumio volumio[913]: info: Getting Qobuz Cloud Configuration Oct 02 20:22:01 volumio volumio[913]: info: Asking plugin for UI Config Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 20:22:01 volumio volumio[913]: info: Getting Spotify Cloud Configuration Oct 02 20:22:01 volumio volumio[913]: info: Asking plugin for UI Config Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 20:22:01 volumio volumio[913]: info: Saving Spotify Acccount Oct 02 20:22:01 volumio volumio[913]: info: Got Tidal Cloud Configuration Oct 02 20:22:01 volumio volumio[913]: info: Got it Oct 02 20:22:01 volumio volumio[913]: info: Got it Oct 02 20:22:01 volumio volumio[913]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 20:22:01 volumio volumio[913]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:22:01 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 02 20:22:05 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:22:05 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 20:22:05 volumio volumio[913]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 02 20:22:05 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 02 20:22:05 volumio volumio[913]: info: Received Get System Version Oct 02 20:22:05 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 20:22:05 volumio volumio[913]: info: Received Get System Info Oct 02 20:22:05 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 20:22:05 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 20:22:05 volumio volumio[913]: info: Discovery: Getting this device information Oct 02 20:22:05 volumio volumio[913]: info: CoreCommandRouter::volumioGetState Oct 02 20:22:05 volumio volumio[913]: info: CorePlayQueue::getTrack 0 Oct 02 20:22:05 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 20:22:05 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 02 20:22:05 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 02 20:22:09 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:22:13 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:22:17 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:22:21 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:22:25 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:22:26 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Oct 02 20:22:26 volumio volumio[913]: info: Adding a new share Oct 02 20:22:26 volumio volumio[913]: info: No correspondence found in configuration for share storages on IP 192.168.1.92 Oct 02 20:22:26 volumio sudo[1896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.92/storages /mnt/NAS/storages Oct 02 20:22:26 volumio sudo[1896]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 20:22:26 volumio kernel: Key type cifs.spnego registered Oct 02 20:22:26 volumio kernel: Key type cifs.idmap registered Oct 02 20:22:26 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Oct 02 20:22:26 volumio kernel: CIFS: Attempting to mount \\192.168.1.92\storages Oct 02 20:22:27 volumio sudo[1896]: pam_unix(sudo:session): session closed for user root Oct 02 20:22:27 volumio volumio[913]: verbose: ControllerMpd::sendMpdCommand status Oct 02 20:22:27 volumio volumio[913]: info: Oct 02 20:22:27 volumio volumio[913]: ---------------------------- MPD announces state update: update Oct 02 20:22:27 volumio volumio[913]: info: ControllerMpd::getState Oct 02 20:22:27 volumio volumio[913]: verbose: ControllerMpd::sendMpdCommand status Oct 02 20:22:27 volumio volumio[913]: verbose: ControllerMpd::sendMpdCommand status Oct 02 20:22:27 volumio volumio[913]: info: Oct 02 20:22:27 volumio volumio[913]: ---------------------------- MPD announces state update: update Oct 02 20:22:27 volumio volumio[913]: info: ControllerMpd::getState Oct 02 20:22:27 volumio volumio[913]: verbose: ControllerMpd::sendMpdCommand status Oct 02 20:22:27 volumio volumio[913]: info: sendMpdCommand status took 19 milliseconds Oct 02 20:22:27 volumio volumio[913]: info: sendMpdCommand status took 16 milliseconds Oct 02 20:22:27 volumio volumio[913]: info: sendMpdCommand status took 16 milliseconds Oct 02 20:22:27 volumio volumio[913]: info: sendMpdCommand status took 14 milliseconds Oct 02 20:22:27 volumio volumio[913]: verbose: ControllerMpd::parseState Oct 02 20:22:27 volumio volumio[913]: info: Command Router : Notfying DB Updatetrue Oct 02 20:22:27 volumio volumio[913]: verbose: ControllerMpd::parseState Oct 02 20:22:27 volumio volumio[913]: verbose: ControllerMpd::parseState Oct 02 20:22:27 volumio volumio[913]: info: Command Router : Notfying DB Updatetrue Oct 02 20:22:27 volumio volumio[913]: info: CoreCommandRouter::Close All Modals sent Oct 02 20:22:27 volumio volumio[913]: verbose: ControllerMpd::parseState Oct 02 20:22:27 volumio volumio[913]: info: ControllerMpd::pushState Oct 02 20:22:27 volumio volumio[913]: info: CoreCommandRouter::servicePushState Oct 02 20:22:27 volumio volumio[913]: info: CoreStateMachine::pushState Oct 02 20:22:27 volumio volumio[913]: info: CorePlayQueue::getTrack 0 Oct 02 20:22:27 volumio volumio[913]: info: CoreCommandRouter::volumioPushState Oct 02 20:22:27 volumio volumio[913]: info: CorePlayQueue::getTrack 0 Oct 02 20:22:27 volumio volumio[913]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 02 20:22:27 volumio volumio[913]: verbose: CURRENT POSITION 0 Oct 02 20:22:27 volumio volumio[913]: info: CoreStateMachine::syncState stateService stop Oct 02 20:22:27 volumio volumio[913]: info: CoreStateMachine::syncState currentStatus stop Oct 02 20:22:27 volumio volumio[913]: info: CoreStateMachine::pushState Oct 02 20:22:27 volumio volumio[913]: info: CorePlayQueue::getTrack 0 Oct 02 20:22:27 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 20:22:27 volumio volumio[913]: info: CoreCommandRouter::volumioPushState Oct 02 20:22:27 volumio volumio[913]: info: No code Oct 02 20:22:27 volumio volumio[913]: info: CoreStateMachine::pushState Oct 02 20:22:27 volumio volumio[913]: info: CorePlayQueue::getTrack 0 Oct 02 20:22:27 volumio volumio[913]: info: CoreCommandRouter::volumioPushState Oct 02 20:22:27 volumio volumio[913]: info: ControllerMpd::pushState Oct 02 20:22:27 volumio volumio[913]: info: CoreCommandRouter::servicePushState Oct 02 20:22:27 volumio volumio[913]: info: CoreStateMachine::pushState Oct 02 20:22:27 volumio volumio[913]: info: CorePlayQueue::getTrack 0 Oct 02 20:22:27 volumio volumio[913]: info: CoreCommandRouter::volumioPushState Oct 02 20:22:27 volumio volumio[913]: info: CorePlayQueue::getTrack 0 Oct 02 20:22:27 volumio volumio[913]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 02 20:22:27 volumio volumio[913]: verbose: CURRENT POSITION 0 Oct 02 20:22:27 volumio volumio[913]: info: CoreStateMachine::syncState stateService stop Oct 02 20:22:27 volumio volumio[913]: info: CoreStateMachine::syncState currentStatus stop Oct 02 20:22:27 volumio volumio[913]: info: CoreStateMachine::pushState Oct 02 20:22:27 volumio volumio[913]: info: CorePlayQueue::getTrack 0 Oct 02 20:22:27 volumio volumio[913]: info: CoreCommandRouter::volumioPushState Oct 02 20:22:27 volumio volumio[913]: info: No code Oct 02 20:22:27 volumio volumio[913]: info: CoreStateMachine::pushState Oct 02 20:22:27 volumio volumio[913]: info: CorePlayQueue::getTrack 0 Oct 02 20:22:27 volumio volumio[913]: info: CoreCommandRouter::volumioPushState Oct 02 20:22:27 volumio volumio[913]: info: ------------------------------ 186ms Oct 02 20:22:27 volumio volumio[913]: info: ------------------------------ 184ms Oct 02 20:22:27 volumio volumio[913]: info: [1727893347297] Database update started Oct 02 20:22:28 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 02 20:22:29 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:22:33 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:22:37 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:22:41 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:22:45 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:22:46 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Oct 02 20:22:47 volumio sudo[1932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/storages Oct 02 20:22:47 volumio sudo[1932]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 20:22:47 volumio sudo[1932]: pam_unix(sudo:session): session closed for user root Oct 02 20:22:48 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 02 20:22:49 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:22:53 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 02 20:22:53 volumio volumio[913]: verbose: ControllerMpd::sendMpdCommand update Oct 02 20:22:53 volumio volumio[913]: info: sendMpdCommand update took 2 milliseconds Oct 02 20:22:53 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:22:54 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 02 20:22:54 volumio volumio[913]: verbose: ControllerMpd::sendMpdCommand rescan Oct 02 20:22:54 volumio volumio[913]: info: sendMpdCommand rescan took 2 milliseconds Oct 02 20:22:57 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:23:01 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:23:05 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:23:09 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:23:09 volumio volumio[913]: info: Disabling MyMusic plugin upnp_browser Oct 02 20:23:09 volumio volumio[913]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedia Servers Oct 02 20:23:09 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 20:23:09 volumio volumio[913]: info: Disabling plugin upnp_browser Oct 02 20:23:10 volumio volumio[913]: info: Done. Oct 02 20:23:13 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:23:13 volumio volumio[913]: info: Disabling MyMusic plugin airplay_emulation Oct 02 20:23:13 volumio volumio[913]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync Oct 02 20:23:13 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 20:23:13 volumio volumio[913]: info: Disabling plugin airplay_emulation Oct 02 20:23:13 volumio volumio[913]: info: Done. Oct 02 20:23:13 volumio sudo[1978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Oct 02 20:23:13 volumio sudo[1978]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 20:23:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 02 20:23:14 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 02 20:23:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 02 20:23:14 volumio sudo[1978]: pam_unix(sudo:session): session closed for user root Oct 02 20:23:14 volumio volumio[913]: info: Shairport-Sync Stopped Oct 02 20:23:17 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:23:20 volumio volumio[913]: info: Disabling MyMusic plugin upnp Oct 02 20:23:20 volumio sudo[1982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 02 20:23:20 volumio sudo[1982]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 20:23:20 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 02 20:23:20 volumio volumio[913]: error: Upnp client error: Error: This socket has been ended by the other party Oct 02 20:23:21 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 20:23:22 volumio volumio[913]: info: Enabling MyMusic plugin upnp Oct 02 20:23:22 volumio volumio[913]: info: Enabling plugin upnp Oct 02 20:23:22 volumio volumio[913]: info: Loading plugin "upnp"... Oct 02 20:23:22 volumio volumio[913]: info: [1727893402685] Starting Upmpd Daemon Oct 02 20:23:22 volumio volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 20:23:22 volumio volumio[913]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 20:23:22 volumio volumio[913]: Error: listen EADDRINUSE: address already in use :::6599 Oct 02 20:23:22 volumio volumio[913]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Oct 02 20:23:22 volumio volumio[913]: at listenInCluster (net.js:1366:12) Oct 02 20:23:22 volumio volumio[913]: at Server.listen (net.js:1452:7) Oct 02 20:23:22 volumio volumio[913]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 02 20:23:22 volumio volumio[913]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 02 20:23:22 volumio volumio[913]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 02 20:23:22 volumio volumio[913]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 02 20:23:22 volumio volumio[913]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 02 20:23:22 volumio volumio[913]: code: 'EADDRINUSE', Oct 02 20:23:22 volumio volumio[913]: errno: -98, Oct 02 20:23:22 volumio volumio[913]: syscall: 'listen', Oct 02 20:23:22 volumio volumio[913]: address: '::', Oct 02 20:23:22 volumio volumio[913]: port: 6599 Oct 02 20:23:22 volumio volumio[913]: } Oct 02 20:23:22 volumio volumio[913]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 20:23:23 volumio sudo[1994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-02 20:22 Oct 02 20:23:23 volumio sudo[1994]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"