-- Logs begin at Sat 2025-09-13 14:51:36 PDT, end at Sat 2025-09-13 16:36:26 PDT. -- Sep 13 16:35:04 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 16:35:04 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 16:35:04 coffee volumio[1181]: info: Discovery: Getting this device information Sep 13 16:35:04 coffee volumio[1181]: info: CoreCommandRouter::volumioGetState Sep 13 16:35:04 coffee volumio[1181]: info: CorePlayQueue::getTrack 0 Sep 13 16:35:04 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 16:35:05 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:35:05 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:35:14 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:35:14 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:35:17 coffee volumio[1181]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Sep 13 16:35:17 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Sep 13 16:35:17 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Sep 13 16:35:17 coffee sudo[16322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 13 16:35:17 coffee sudo[16322]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 16:35:17 coffee sudo[16322]: pam_unix(sudo:session): session closed for user root Sep 13 16:35:17 coffee volumio[1181]: info: MPD Permissions set Sep 13 16:35:17 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 16:35:17 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 16:35:17 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 16:35:17 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 16:35:17 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 16:35:17 coffee sudo[16325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 13 16:35:17 coffee sudo[16325]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 16:35:17 coffee systemd[1]: Stopping Music Player Daemon... Sep 13 16:35:17 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 13 16:35:17 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 13 16:35:17 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 16:35:17 coffee systemd[1]: mpd.service: Succeeded. Sep 13 16:35:17 coffee systemd[1]: Stopped Music Player Daemon. Sep 13 16:35:17 coffee volumio[1181]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 13 16:35:17 coffee volumio[1181]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 13 16:35:17 coffee systemd[1]: Starting Music Player Daemon... Sep 13 16:35:17 coffee volumio[1181]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 13 16:35:17 coffee sudo[16333]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 13 16:35:17 coffee sudo[16333]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 16:35:17 coffee sudo[16333]: pam_unix(sudo:session): session closed for user root Sep 13 16:35:20 coffee mpd[16335]: Sep 13 16:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 13 16:35:20 coffee systemd[1]: Started Music Player Daemon. Sep 13 16:35:20 coffee sudo[16325]: pam_unix(sudo:session): session closed for user root Sep 13 16:35:20 coffee volumio[1181]: error: updateQueue error: null Sep 13 16:35:22 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:35:22 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:35:23 coffee go-librespot[1493]: time="2025-09-13T16:35:23-07:00" level=trace msg="sent dealer ping" Sep 13 16:35:23 coffee go-librespot[1493]: time="2025-09-13T16:35:23-07:00" level=trace msg="received dealer pong" Sep 13 16:35:30 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:35:30 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:35:34 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 16:35:34 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 16:35:34 coffee volumio[1181]: info: Discovery: Getting this device information Sep 13 16:35:34 coffee volumio[1181]: info: CoreCommandRouter::volumioGetState Sep 13 16:35:34 coffee volumio[1181]: info: CorePlayQueue::getTrack 0 Sep 13 16:35:34 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 16:35:38 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:35:38 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:35:43 coffee volumio[1181]: verbose: New Socket.io Connection to 192.168.50.124:3000 from 192.168.50.243 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Sep 13 16:35:44 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Sep 13 16:35:44 coffee volumio[1181]: info: CoreCommandRouter::volumioGetState Sep 13 16:35:44 coffee volumio[1181]: info: CorePlayQueue::getTrack 0 Sep 13 16:35:46 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:35:46 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:35:53 coffee go-librespot[1493]: time="2025-09-13T16:35:53-07:00" level=trace msg="sent dealer ping" Sep 13 16:35:53 coffee go-librespot[1493]: time="2025-09-13T16:35:53-07:00" level=trace msg="received dealer pong" Sep 13 16:35:54 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:35:54 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:36:00 coffee volumio[1181]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Sep 13 16:36:00 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Sep 13 16:36:00 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Sep 13 16:36:00 coffee sudo[16443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 13 16:36:00 coffee sudo[16443]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 16:36:00 coffee sudo[16443]: pam_unix(sudo:session): session closed for user root Sep 13 16:36:00 coffee volumio[1181]: info: MPD Permissions set Sep 13 16:36:00 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 16:36:00 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 16:36:00 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 16:36:00 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 16:36:00 coffee sudo[16446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 13 16:36:00 coffee sudo[16446]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 16:36:00 coffee systemd[1]: Stopping Music Player Daemon... Sep 13 16:36:00 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 13 16:36:00 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 13 16:36:00 coffee systemd[1]: mpd.service: Succeeded. Sep 13 16:36:00 coffee systemd[1]: Stopped Music Player Daemon. Sep 13 16:36:00 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 13 16:36:00 coffee systemd[1]: Starting Music Player Daemon... Sep 13 16:36:00 coffee volumio[1181]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 13 16:36:00 coffee volumio[1181]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 13 16:36:00 coffee volumio[1181]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 13 16:36:00 coffee sudo[16454]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 13 16:36:00 coffee sudo[16454]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 16:36:00 coffee sudo[16454]: pam_unix(sudo:session): session closed for user root Sep 13 16:36:02 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:36:02 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:36:02 coffee mpd[16457]: Sep 13 16:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 13 16:36:02 coffee systemd[1]: Started Music Player Daemon. Sep 13 16:36:02 coffee sudo[16446]: pam_unix(sudo:session): session closed for user root Sep 13 16:36:02 coffee volumio[1181]: error: updateQueue error: null Sep 13 16:36:04 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 13 16:36:04 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 13 16:36:04 coffee volumio[1181]: info: Discovery: Getting this device information Sep 13 16:36:04 coffee volumio[1181]: info: CoreCommandRouter::volumioGetState Sep 13 16:36:04 coffee volumio[1181]: info: CorePlayQueue::getTrack 0 Sep 13 16:36:04 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 13 16:36:07 coffee volumio[1181]: info: CoreCommandRouter::volumioGetState Sep 13 16:36:07 coffee volumio[1181]: info: CorePlayQueue::getTrack 0 Sep 13 16:36:08 coffee volumio[1181]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 13 16:36:08 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 13 16:36:08 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Sep 13 16:36:08 coffee sudo[16477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Sep 13 16:36:08 coffee sudo[16477]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 16:36:08 coffee sudo[16483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 13 16:36:08 coffee sudo[16477]: pam_unix(sudo:session): session closed for user root Sep 13 16:36:08 coffee sudo[16483]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 16:36:08 coffee sudo[16483]: pam_unix(sudo:session): session closed for user root Sep 13 16:36:08 coffee sudo[16489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 13 16:36:08 coffee sudo[16489]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 16:36:08 coffee sudo[16489]: pam_unix(sudo:session): session closed for user root Sep 13 16:36:08 coffee sudo[16497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 13 16:36:08 coffee sudo[16497]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 16:36:08 coffee sudo[16497]: pam_unix(sudo:session): session closed for user root Sep 13 16:36:08 coffee sudo[16502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 13 16:36:08 coffee sudo[16502]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 16:36:08 coffee sudo[16502]: pam_unix(sudo:session): session closed for user root Sep 13 16:36:08 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 13 16:36:08 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 13 16:36:08 coffee sudo[16506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 13 16:36:08 coffee sudo[16506]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 16:36:08 coffee sudo[16506]: pam_unix(sudo:session): session closed for user root Sep 13 16:36:08 coffee sudo[16509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 13 16:36:08 coffee sudo[16509]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 13 16:36:09 coffee sudo[16509]: pam_unix(sudo:session): session closed for user root Sep 13 16:36:10 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:36:10 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:36:15 coffee volumio[1181]: info: CoreCommandRouter::volumioGetState Sep 13 16:36:15 coffee volumio[1181]: info: CorePlayQueue::getTrack 0 Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 13 16:36:16 coffee volumio[1181]: info: Retrieving Cloud Streaming UI Sep 13 16:36:16 coffee volumio[1181]: info: Getting Tidal Cloud Configuration Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 13 16:36:16 coffee volumio[1181]: info: Getting Qobuz Cloud Configuration Sep 13 16:36:16 coffee volumio[1181]: info: Asking plugin for UI Config Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 13 16:36:16 coffee volumio[1181]: info: Getting Spotify Cloud Configuration Sep 13 16:36:16 coffee volumio[1181]: info: Asking plugin for UI Config Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 13 16:36:16 coffee volumio[1181]: info: Saving Spotify Acccount Sep 13 16:36:16 coffee volumio[1181]: info: Got it Sep 13 16:36:16 coffee volumio[1181]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 13 16:36:16 coffee volumio[1181]: info: Got Tidal Cloud Configuration Sep 13 16:36:16 coffee volumio[1181]: info: Got it Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::volumioGetBrowseSources Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::volumioGetBrowseSources Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::volumioGetBrowseSources Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 16:36:16 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 13 16:36:18 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:36:18 coffee volumio[1181]: info: Discovery: Remote connection error: 192.168.50.76 Sep 13 16:36:20 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Sep 13 16:36:20 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Sep 13 16:36:20 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 16:36:22 coffee volumio[1181]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L Tower.local. -m SMB3_11 Sep 13 16:36:22 coffee volumio[1181]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L fire.local. -m SMB3_11 Sep 13 16:36:22 coffee volumio[1181]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L parks.local. -m SMB3_11 Sep 13 16:36:23 coffee go-librespot[1493]: time="2025-09-13T16:36:23-07:00" level=trace msg="sent dealer ping" Sep 13 16:36:23 coffee go-librespot[1493]: time="2025-09-13T16:36:23-07:00" level=trace msg="received dealer pong" Sep 13 16:36:24 coffee go-librespot[1493]: time="2025-09-13T16:36:24-07:00" level=trace msg="received accesspoint ping" Sep 13 16:36:24 coffee go-librespot[1493]: time="2025-09-13T16:36:24-07:00" level=trace msg="received accesspoint pong ack" Sep 13 16:36:24 coffee volumio[1181]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 13 16:36:25 coffee volumio[1181]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 13 16:36:25 coffee volumio[1181]: Error: dns service error: unknown Sep 13 16:36:25 coffee volumio[1181]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Sep 13 16:36:25 coffee volumio[1181]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Sep 13 16:36:25 coffee volumio[1181]: errorCode: -65537 Sep 13 16:36:25 coffee volumio[1181]: } Sep 13 16:36:25 coffee volumio[1181]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 13 16:36:26 coffee sudo[16573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-13 16:35 Sep 13 16:36:26 coffee sudo[16573]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"