-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Tue 2024-10-08 21:12:42 CST. -- Oct 08 21:11:02 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 08 21:11:02 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 08 21:11:02 volumio volumio[883]: info: Discovery: Getting this device information Oct 08 21:11:02 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Oct 08 21:11:02 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 08 21:11:18 volumio volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 08 21:11:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 08 21:11:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 08 21:11:18 volumio sudo[2584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 08 21:11:18 volumio sudo[2584]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 08 21:11:18 volumio sudo[2584]: pam_unix(sudo:session): session closed for user root Oct 08 21:11:18 volumio sudo[2589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 08 21:11:18 volumio sudo[2589]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 08 21:11:18 volumio sudo[2589]: pam_unix(sudo:session): session closed for user root Oct 08 21:11:18 volumio sudo[2597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 08 21:11:18 volumio sudo[2597]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 08 21:11:18 volumio sudo[2597]: pam_unix(sudo:session): session closed for user root Oct 08 21:11:18 volumio sudo[2603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 08 21:11:18 volumio sudo[2603]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 08 21:11:18 volumio sudo[2603]: pam_unix(sudo:session): session closed for user root Oct 08 21:11:18 volumio sudo[2610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 08 21:11:18 volumio sudo[2610]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 08 21:11:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 08 21:11:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 08 21:11:18 volumio sudo[2610]: pam_unix(sudo:session): session closed for user root Oct 08 21:11:18 volumio sudo[2612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 08 21:11:18 volumio sudo[2612]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 08 21:11:18 volumio sudo[2612]: pam_unix(sudo:session): session closed for user root Oct 08 21:11:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 08 21:11:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 08 21:11:32 volumio volumio[883]: info: Discovery: Getting this device information Oct 08 21:11:32 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Oct 08 21:11:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 08 21:11:43 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 08 21:11:43 volumio volumio[883]: info: Preload queue cleared Oct 08 21:11:43 volumio volumio[883]: info: Preloading song: upnp/http://10.0.0.10:52100/ContentDirectory/control@F14921 Oct 08 21:11:43 volumio volumio[883]: info: Preloading song: upnp/http://10.0.0.10:52100/ContentDirectory/control@F14925 Oct 08 21:11:43 volumio volumio[883]: info: Preloading song: upnp/http://10.0.0.10:52100/ContentDirectory/control@F14928 Oct 08 21:11:43 volumio volumio[883]: info: Preloading song: upnp/http://10.0.0.10:52100/ContentDirectory/control@F14932 Oct 08 21:11:43 volumio volumio[883]: info: Preloading song: upnp/http://10.0.0.10:52100/ContentDirectory/control@F14936 Oct 08 21:11:43 volumio volumio[883]: info: Preloading song: upnp/http://10.0.0.10:52100/ContentDirectory/control@F14941 Oct 08 21:11:43 volumio volumio[883]: info: Preloading song: upnp/http://10.0.0.10:52100/ContentDirectory/control@F14945 Oct 08 21:11:43 volumio volumio[883]: info: Preloading song: upnp/http://10.0.0.10:52100/ContentDirectory/control@F14948 Oct 08 21:11:43 volumio volumio[883]: info: Preloading song: upnp/http://10.0.0.10:52100/ContentDirectory/control@F14952 Oct 08 21:11:43 volumio volumio[883]: info: Preloading song: upnp/http://10.0.0.10:52100/ContentDirectory/control@F14956 Oct 08 21:11:43 volumio volumio[883]: info: Preloading song: upnp/http://10.0.0.10:52100/ContentDirectory/control@F14958 Oct 08 21:11:43 volumio volumio[883]: info: Preloading song: upnp/http://10.0.0.10:52100/ContentDirectory/control@F14964 Oct 08 21:11:43 volumio volumio[883]: info: Exploding uri upnp/http://10.0.0.10:52100/ContentDirectory/control@F14921 in service upnp_browser Oct 08 21:11:43 volumio volumio[883]: info: Exploding uri upnp/http://10.0.0.10:52100/ContentDirectory/control@F14925 in service upnp_browser Oct 08 21:11:43 volumio volumio[883]: info: Exploding uri upnp/http://10.0.0.10:52100/ContentDirectory/control@F14928 in service upnp_browser Oct 08 21:11:43 volumio volumio[883]: info: Exploding uri upnp/http://10.0.0.10:52100/ContentDirectory/control@F14932 in service upnp_browser Oct 08 21:11:43 volumio volumio[883]: info: Exploding uri upnp/http://10.0.0.10:52100/ContentDirectory/control@F14936 in service upnp_browser Oct 08 21:11:44 volumio volumio[883]: info: Exploding uri upnp/http://10.0.0.10:52100/ContentDirectory/control@F14941 in service upnp_browser Oct 08 21:11:44 volumio volumio[883]: info: Exploding uri upnp/http://10.0.0.10:52100/ContentDirectory/control@F14945 in service upnp_browser Oct 08 21:11:44 volumio volumio[883]: info: Exploding uri upnp/http://10.0.0.10:52100/ContentDirectory/control@F14948 in service upnp_browser Oct 08 21:11:44 volumio volumio[883]: info: Exploding uri upnp/http://10.0.0.10:52100/ContentDirectory/control@F14952 in service upnp_browser Oct 08 21:11:44 volumio volumio[883]: info: Exploding uri upnp/http://10.0.0.10:52100/ContentDirectory/control@F14956 in service upnp_browser Oct 08 21:11:44 volumio volumio[883]: info: Exploding uri upnp/http://10.0.0.10:52100/ContentDirectory/control@F14958 in service upnp_browser Oct 08 21:11:44 volumio volumio[883]: info: Exploding uri upnp/http://10.0.0.10:52100/ContentDirectory/control@F14964 in service upnp_browser Oct 08 21:11:53 volumio go-librespot[1117]: time="2024-10-08T21:11:53+08:00" level=error msg="failed receiving packet" error="failed reading packet header: EOF" Oct 08 21:11:53 volumio go-librespot[1117]: time="2024-10-08T21:11:53+08:00" level=debug msg="completed keyexchange" Oct 08 21:11:54 volumio go-librespot[1117]: time="2024-10-08T21:11:54+08:00" level=debug msg="completed challenge" Oct 08 21:11:54 volumio go-librespot[1117]: time="2024-10-08T21:11:54+08:00" level=debug msg="authenticated as 313azrxmjfh6sie35zdbghlhywc4" Oct 08 21:11:54 volumio go-librespot[1117]: time="2024-10-08T21:11:54+08:00" level=debug msg="re-established accesspoint connection" Oct 08 21:11:54 volumio go-librespot[1117]: time="2024-10-08T21:11:54+08:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 08 21:11:54 volumio go-librespot[1117]: time="2024-10-08T21:11:54+08:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 08 21:11:54 volumio go-librespot[1117]: time="2024-10-08T21:11:54+08:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 08 21:11:54 volumio go-librespot[1117]: time="2024-10-08T21:11:54+08:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 08 21:11:54 volumio go-librespot[1117]: time="2024-10-08T21:11:54+08:00" level=debug msg="autoplay enabled: false" Oct 08 21:11:54 volumio go-librespot[1117]: time="2024-10-08T21:11:54+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 493" Oct 08 21:12:02 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 08 21:12:02 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 08 21:12:02 volumio volumio[883]: info: Discovery: Getting this device information Oct 08 21:12:02 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Oct 08 21:12:02 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 08 21:12:20 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 08 21:12:20 volumio volumio[883]: info: Preload queue cleared Oct 08 21:12:20 volumio volumio[883]: info: Preloading song: upnp/http://10.0.0.10:52100/ContentDirectory/control@F13304 Oct 08 21:12:20 volumio volumio[883]: info: Preloading song: upnp/http://10.0.0.10:52100/ContentDirectory/control@F13308 Oct 08 21:12:20 volumio volumio[883]: info: Preloading song: upnp/http://10.0.0.10:52100/ContentDirectory/control@F13312 Oct 08 21:12:20 volumio volumio[883]: info: Preloading song: upnp/http://10.0.0.10:52100/ContentDirectory/control@F13316 Oct 08 21:12:20 volumio volumio[883]: info: Preloading song: upnp/http://10.0.0.10:52100/ContentDirectory/control@F13322 Oct 08 21:12:20 volumio volumio[883]: info: Preloading song: upnp/http://10.0.0.10:52100/ContentDirectory/control@F13327 Oct 08 21:12:21 volumio volumio[883]: info: Exploding uri upnp/http://10.0.0.10:52100/ContentDirectory/control@F13304 in service upnp_browser Oct 08 21:12:21 volumio volumio[883]: info: Exploding uri upnp/http://10.0.0.10:52100/ContentDirectory/control@F13308 in service upnp_browser Oct 08 21:12:21 volumio volumio[883]: info: Exploding uri upnp/http://10.0.0.10:52100/ContentDirectory/control@F13312 in service upnp_browser Oct 08 21:12:21 volumio volumio[883]: info: Exploding uri upnp/http://10.0.0.10:52100/ContentDirectory/control@F13316 in service upnp_browser Oct 08 21:12:21 volumio volumio[883]: info: Exploding uri upnp/http://10.0.0.10:52100/ContentDirectory/control@F13322 in service upnp_browser Oct 08 21:12:21 volumio volumio[883]: info: Exploding uri upnp/http://10.0.0.10:52100/ContentDirectory/control@F13327 in service upnp_browser Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 08 21:12:32 volumio volumio[883]: info: Discovery: Getting this device information Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::volumioGetState Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 08 21:12:32 volumio volumio[883]: info: Retrieving Cloud Streaming UI Oct 08 21:12:32 volumio volumio[883]: info: Getting Tidal Cloud Configuration Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 08 21:12:32 volumio volumio[883]: info: Getting Qobuz Cloud Configuration Oct 08 21:12:32 volumio volumio[883]: info: Asking plugin for UI Config Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 08 21:12:32 volumio volumio[883]: info: Getting Spotify Cloud Configuration Oct 08 21:12:32 volumio volumio[883]: info: Asking plugin for UI Config Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 08 21:12:32 volumio volumio[883]: info: Saving Spotify Acccount Oct 08 21:12:32 volumio volumio[883]: info: Got Tidal Cloud Configuration Oct 08 21:12:32 volumio volumio[883]: info: Got it Oct 08 21:12:32 volumio volumio[883]: info: Got it Oct 08 21:12:32 volumio volumio[883]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 08 21:12:32 volumio volumio[883]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::volumioGetBrowseSources Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::volumioGetBrowseSources Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::volumioGetBrowseSources Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 08 21:12:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 08 21:12:36 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 08 21:12:40 volumio volumio[883]: info: Disabling MyMusic plugin upnp Oct 08 21:12:40 volumio sudo[2664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 08 21:12:40 volumio sudo[2664]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 08 21:12:40 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 08 21:12:40 volumio volumio[883]: error: Upnp client error: Error: This socket has been ended by the other party Oct 08 21:12:40 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 08 21:12:41 volumio volumio[883]: info: Enabling MyMusic plugin upnp Oct 08 21:12:41 volumio volumio[883]: info: Enabling plugin upnp Oct 08 21:12:41 volumio volumio[883]: info: Loading plugin "upnp"... Oct 08 21:12:41 volumio volumio[883]: info: [1728393161602] Starting Upmpd Daemon Oct 08 21:12:41 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 08 21:12:41 volumio volumio[883]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 08 21:12:41 volumio volumio[883]: Error: listen EADDRINUSE: address already in use :::6599 Oct 08 21:12:41 volumio volumio[883]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Oct 08 21:12:41 volumio volumio[883]: at listenInCluster (net.js:1366:12) Oct 08 21:12:41 volumio volumio[883]: at Server.listen (net.js:1452:7) Oct 08 21:12:41 volumio volumio[883]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 08 21:12:41 volumio volumio[883]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 08 21:12:41 volumio volumio[883]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 08 21:12:41 volumio volumio[883]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 08 21:12:41 volumio volumio[883]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 08 21:12:41 volumio volumio[883]: code: 'EADDRINUSE', Oct 08 21:12:41 volumio volumio[883]: errno: -98, Oct 08 21:12:41 volumio volumio[883]: syscall: 'listen', Oct 08 21:12:41 volumio volumio[883]: address: '::', Oct 08 21:12:41 volumio volumio[883]: port: 6599 Oct 08 21:12:41 volumio volumio[883]: } Oct 08 21:12:41 volumio volumio[883]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 08 21:12:42 volumio sudo[2675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-08 21:11 Oct 08 21:12:42 volumio sudo[2675]: 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"