-- Logs begin at Mon 2025-09-15 19:56:33 JST, end at Mon 2025-09-15 21:08:24 JST. -- Sep 15 21:07:05 volumio5 go-librespot[4785]: time="2025-09-15T21:07:05+09:00" level=trace msg="sent dealer ping" Sep 15 21:07:05 volumio5 go-librespot[4785]: time="2025-09-15T21:07:05+09:00" level=trace msg="received dealer pong" Sep 15 21:07:35 volumio5 go-librespot[4785]: time="2025-09-15T21:07:35+09:00" level=trace msg="received accesspoint ping" Sep 15 21:07:35 volumio5 go-librespot[4785]: time="2025-09-15T21:07:35+09:00" level=trace msg="received accesspoint pong ack" Sep 15 21:07:35 volumio5 go-librespot[4785]: time="2025-09-15T21:07:35+09:00" level=trace msg="sent dealer ping" Sep 15 21:07:35 volumio5 go-librespot[4785]: time="2025-09-15T21:07:35+09:00" level=trace msg="received dealer pong" Sep 15 21:07:47 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 21:07:48 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 21:07:52 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 21:07:56 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 21:08:00 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 21:08:01 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Sep 15 21:08:01 volumio5 volumio[1176]: info: Adding a new share Sep 15 21:08:01 volumio5 volumio[1176]: info: Checking key NasMounts.40d87a89-8e70-4230-919b-8584044aa4dc Sep 15 21:08:01 volumio5 volumio[1176]: info: No correspondence found in configuration for share PC on IP 192.168.11.65 Sep 15 21:08:01 volumio5 volumio[1176]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.11.65 -N -m SMB3_11 Sep 15 21:08:03 volumio5 volumio[1176]: info: Error browsing device PC for shares: Command failed: smbclient --debuglevel=4 -L 192.168.11.65 -N -m SMB3_11 Sep 15 21:08:03 volumio5 volumio[1176]: lp_load_ex: refreshing parameters Sep 15 21:08:03 volumio5 volumio[1176]: Initialising global parameters Sep 15 21:08:03 volumio5 volumio[1176]: Processing section "[global]" Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter netbios name = Volumio5 Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter server string = Audiophile Music Player Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter workgroup = WORKGROUP Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter security = user Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter map to guest = Bad User Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter encrypt passwords = yes Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter wins support = yes Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter local master = no Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter preferred master = no Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter os level = 30 Sep 15 21:08:03 volumio5 volumio[1176]: pm_process() returned Yes Sep 15 21:08:03 volumio5 volumio[1176]: directory_create_or_exist_strict: invalid ownership on directory /var/run/samba/msg.lock Sep 15 21:08:03 volumio5 volumio[1176]: cmdline_messaging_context: Unable to initialize messaging context. Sep 15 21:08:03 volumio5 volumio[1176]: Unable to initialize messaging context Sep 15 21:08:03 volumio5 volumio[1176]: lp_load_ex: refreshing parameters Sep 15 21:08:03 volumio5 volumio[1176]: Initialising global parameters Sep 15 21:08:03 volumio5 volumio[1176]: Processing section "[global]" Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter netbios name = Volumio5 Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter server string = Audiophile Music Player Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter workgroup = WORKGROUP Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter security = user Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter map to guest = Bad User Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter encrypt passwords = yes Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter wins support = yes Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter local master = no Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter preferred master = no Sep 15 21:08:03 volumio5 volumio[1176]: doing parameter os level = 30 Sep 15 21:08:03 volumio5 volumio[1176]: pm_process() returned Yes Sep 15 21:08:03 volumio5 volumio[1176]: added interface wlan0 ip=192.168.11.38 bcast=192.168.11.255 netmask=255.255.255.0 Sep 15 21:08:03 volumio5 volumio[1176]: Client started (version 4.9.5-Debian). Sep 15 21:08:03 volumio5 volumio[1176]: Connecting to 192.168.11.65 at port 445 Sep 15 21:08:03 volumio5 volumio[1176]: session request ok Sep 15 21:08:03 volumio5 volumio[1176]: negotiated dialect[SMB3_11] against server[192.168.11.65] Sep 15 21:08:03 volumio5 volumio[1176]: got OID=1.3.6.1.4.1.311.2.2.30 Sep 15 21:08:03 volumio5 volumio[1176]: got OID=1.3.6.1.4.1.311.2.2.10 Sep 15 21:08:03 volumio5 volumio[1176]: GENSEC backend 'gssapi_spnego' registered Sep 15 21:08:03 volumio5 volumio[1176]: GENSEC backend 'gssapi_krb5' registered Sep 15 21:08:03 volumio5 volumio[1176]: GENSEC backend 'gssapi_krb5_sasl' registered Sep 15 21:08:03 volumio5 volumio[1176]: GENSEC backend 'spnego' registered Sep 15 21:08:03 volumio5 volumio[1176]: GENSEC backend 'schannel' registered Sep 15 21:08:03 volumio5 volumio[1176]: GENSEC backend 'naclrpc_as_system' registered Sep 15 21:08:03 volumio5 volumio[1176]: GENSEC backend 'sasl-EXTERNAL' registered Sep 15 21:08:03 volumio5 volumio[1176]: GENSEC backend 'ntlmssp' registered Sep 15 21:08:03 volumio5 volumio[1176]: GENSEC backend 'ntlmssp_resume_ccache' registered Sep 15 21:08:03 volumio5 volumio[1176]: GENSEC backend 'http_basic' registered Sep 15 21:08:03 volumio5 volumio[1176]: GENSEC backend 'http_ntlm' registered Sep 15 21:08:03 volumio5 volumio[1176]: GENSEC backend 'http_negotiate' registered Sep 15 21:08:03 volumio5 volumio[1176]: GENSEC backend 'krb5' registered Sep 15 21:08:03 volumio5 volumio[1176]: GENSEC backend 'fake_gssapi_krb5' registered Sep 15 21:08:03 volumio5 volumio[1176]: Got challenge flags: Sep 15 21:08:03 volumio5 volumio[1176]: Got NTLMSSP neg_flags=0x628a8215 Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_UNICODE Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_REQUEST_TARGET Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_SIGN Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_NTLM Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_TARGET_TYPE_SERVER Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_TARGET_INFO Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_VERSION Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_128 Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_KEY_EXCH Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP: Set final flags: Sep 15 21:08:03 volumio5 volumio[1176]: Got NTLMSSP neg_flags=0x62008215 Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_UNICODE Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_REQUEST_TARGET Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_SIGN Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_NTLM Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_VERSION Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_128 Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_KEY_EXCH Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP Sign/Seal - Initialising with flags: Sep 15 21:08:03 volumio5 volumio[1176]: Got NTLMSSP neg_flags=0x62008215 Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_UNICODE Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_REQUEST_TARGET Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_SIGN Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_NTLM Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_VERSION Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_128 Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_KEY_EXCH Sep 15 21:08:03 volumio5 volumio[1176]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. Sep 15 21:08:03 volumio5 volumio[1176]: got OID=1.3.6.1.4.1.311.2.2.30 Sep 15 21:08:03 volumio5 volumio[1176]: got OID=1.3.6.1.4.1.311.2.2.10 Sep 15 21:08:03 volumio5 volumio[1176]: Got challenge flags: Sep 15 21:08:03 volumio5 volumio[1176]: Got NTLMSSP neg_flags=0x628a8215 Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_UNICODE Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_REQUEST_TARGET Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_SIGN Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_NTLM Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_TARGET_TYPE_SERVER Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_TARGET_INFO Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_VERSION Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_128 Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_KEY_EXCH Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP: Set final flags: Sep 15 21:08:03 volumio5 volumio[1176]: Got NTLMSSP neg_flags=0x62008a15 Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_UNICODE Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_REQUEST_TARGET Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_SIGN Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_NTLM Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_ANONYMOUS Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_VERSION Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_128 Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_KEY_EXCH Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP Sign/Seal - Initialising with flags: Sep 15 21:08:03 volumio5 volumio[1176]: Got NTLMSSP neg_flags=0x62008a15 Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_UNICODE Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_REQUEST_TARGET Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_SIGN Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_NTLM Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_ANONYMOUS Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_VERSION Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_128 Sep 15 21:08:03 volumio5 volumio[1176]: NTLMSSP_NEGOTIATE_KEY_EXCH Sep 15 21:08:03 volumio5 volumio[1176]: SPNEGO login failed: {Access Denied} A process has requested access to an object but has not been granted those access rights. Sep 15 21:08:03 volumio5 volumio[1176]: info: Detected device PC with version null Sep 15 21:08:03 volumio5 sudo[15035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=tomitatu@hotmail.co.jp,password=Snow8Bal!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.11.65/Users/Tatsuya/Music/FLAC /mnt/NAS/PC Sep 15 21:08:03 volumio5 sudo[15035]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 21:08:03 volumio5 kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Sep 15 21:08:03 volumio5 kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Sep 15 21:08:03 volumio5 kernel: CIFS: Attempting to mount //192.168.11.65/Users/Tatsuya/Music/FLAC Sep 15 21:08:03 volumio5 sudo[15035]: pam_unix(sudo:session): session closed for user root Sep 15 21:08:03 volumio5 volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Sep 15 21:08:03 volumio5 volumio[1176]: info: Sep 15 21:08:03 volumio5 volumio[1176]: ---------------------------- MPD announces state update: update Sep 15 21:08:03 volumio5 volumio[1176]: info: ControllerMpd::getState Sep 15 21:08:03 volumio5 volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Sep 15 21:08:03 volumio5 volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Sep 15 21:08:03 volumio5 volumio[1176]: info: Sep 15 21:08:03 volumio5 volumio[1176]: ---------------------------- MPD announces state update: update Sep 15 21:08:03 volumio5 volumio[1176]: info: ControllerMpd::getState Sep 15 21:08:03 volumio5 volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Sep 15 21:08:03 volumio5 volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Sep 15 21:08:03 volumio5 volumio[1176]: info: Sep 15 21:08:03 volumio5 volumio[1176]: ---------------------------- MPD announces state update: update Sep 15 21:08:03 volumio5 volumio[1176]: info: ControllerMpd::getState Sep 15 21:08:03 volumio5 volumio[1176]: verbose: ControllerMpd::sendMpdCommand status Sep 15 21:08:03 volumio5 volumio[1176]: info: sendMpdCommand status took 3 milliseconds Sep 15 21:08:03 volumio5 volumio[1176]: info: sendMpdCommand status took 2 milliseconds Sep 15 21:08:03 volumio5 volumio[1176]: info: sendMpdCommand status took 1 milliseconds Sep 15 21:08:03 volumio5 volumio[1176]: info: sendMpdCommand status took 1 milliseconds Sep 15 21:08:03 volumio5 volumio[1176]: info: sendMpdCommand status took 2 milliseconds Sep 15 21:08:03 volumio5 volumio[1176]: info: sendMpdCommand status took 1 milliseconds Sep 15 21:08:03 volumio5 volumio[1176]: verbose: ControllerMpd::parseState Sep 15 21:08:04 volumio5 volumio[1176]: info: Command Router : Notfying DB Updatetrue Sep 15 21:08:04 volumio5 volumio[1176]: info: CoreCommandRouter::Close All Modals sent Sep 15 21:08:04 volumio5 volumio[1176]: verbose: ControllerMpd::parseState Sep 15 21:08:04 volumio5 volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 21:08:04 volumio5 volumio[1176]: verbose: ControllerMpd::parseState Sep 15 21:08:04 volumio5 volumio[1176]: info: Command Router : Notfying DB Updatetrue Sep 15 21:08:04 volumio5 volumio[1176]: info: CoreCommandRouter::Close All Modals sent Sep 15 21:08:04 volumio5 volumio[1176]: verbose: ControllerMpd::parseState Sep 15 21:08:04 volumio5 volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 21:08:04 volumio5 volumio[1176]: verbose: ControllerMpd::parseState Sep 15 21:08:04 volumio5 volumio[1176]: info: Command Router : Notfying DB Updatetrue Sep 15 21:08:04 volumio5 volumio[1176]: info: CoreCommandRouter::Close All Modals sent Sep 15 21:08:04 volumio5 volumio[1176]: verbose: ControllerMpd::parseState Sep 15 21:08:04 volumio5 volumio[1176]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 15 21:08:04 volumio5 volumio[1176]: info: [1757938084051] Database update started Sep 15 21:08:04 volumio5 volumio[1176]: info: sendMpdCommand playlistinfo took 27 milliseconds Sep 15 21:08:04 volumio5 volumio[1176]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 15 21:08:04 volumio5 volumio[1176]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 15 21:08:04 volumio5 volumio[1176]: verbose: ControllerMpd::parseTrackInfo Sep 15 21:08:04 volumio5 volumio[1176]: verbose: ControllerMpd::parseTrackInfo Sep 15 21:08:04 volumio5 volumio[1176]: verbose: ControllerMpd::parseTrackInfo Sep 15 21:08:04 volumio5 volumio[1176]: info: ControllerMpd::pushState Sep 15 21:08:04 volumio5 volumio[1176]: info: CoreCommandRouter::servicePushState Sep 15 21:08:04 volumio5 volumio[1176]: info: CorePlayQueue::getTrack 0 Sep 15 21:08:04 volumio5 volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 15 21:08:04 volumio5 volumio[1176]: info: ControllerMpd::pushState Sep 15 21:08:04 volumio5 volumio[1176]: info: CoreCommandRouter::servicePushState Sep 15 21:08:04 volumio5 volumio[1176]: info: CorePlayQueue::getTrack 0 Sep 15 21:08:04 volumio5 volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 15 21:08:04 volumio5 volumio[1176]: info: ControllerMpd::pushState Sep 15 21:08:04 volumio5 volumio[1176]: info: CoreCommandRouter::servicePushState Sep 15 21:08:04 volumio5 volumio[1176]: info: CorePlayQueue::getTrack 0 Sep 15 21:08:04 volumio5 volumio[1176]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 15 21:08:04 volumio5 volumio[1176]: info: ------------------------------ 119ms Sep 15 21:08:04 volumio5 volumio[1176]: info: ------------------------------ 118ms Sep 15 21:08:04 volumio5 volumio[1176]: info: ------------------------------ 118ms Sep 15 21:08:04 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 21:08:04 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 15 21:08:05 volumio5 go-librespot[4785]: time="2025-09-15T21:08:05+09:00" level=trace msg="sent dealer ping" Sep 15 21:08:05 volumio5 go-librespot[4785]: time="2025-09-15T21:08:05+09:00" level=trace msg="received dealer pong" Sep 15 21:08:08 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 21:08:11 volumio5 sudo[15072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 15 21:08:11 volumio5 sudo[15072]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 21:08:11 volumio5 sudo[15072]: pam_unix(sudo:session): session closed for user root Sep 15 21:08:11 volumio5 sudo[15075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 15 21:08:11 volumio5 sudo[15075]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 15 21:08:11 volumio5 sudo[15075]: pam_unix(sudo:session): session closed for user root Sep 15 21:08:11 volumio5 volumio[1176]: verbose: New Socket.io Connection to 192.168.11.38 from 192.168.11.65 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Edg/140.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::volumioGetVisibleSources Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::volumioGetState Sep 15 21:08:11 volumio5 volumio[1176]: info: CorePlayQueue::getTrack 0 Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 21:08:11 volumio5 volumio[1176]: info: Retrieving Cloud Streaming UI Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 15 21:08:11 volumio5 volumio[1176]: info: Received Get System Info Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 21:08:11 volumio5 volumio[1176]: info: Discovery: Getting this device information Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::volumioGetState Sep 15 21:08:11 volumio5 volumio[1176]: info: CorePlayQueue::getTrack 0 Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::volumioGetState Sep 15 21:08:11 volumio5 volumio[1176]: info: CorePlayQueue::getTrack 0 Sep 15 21:08:11 volumio5 volumio[1176]: info: Listing playlists Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 15 21:08:11 volumio5 volumio[1176]: info: Getting Tidal Cloud Configuration Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 21:08:11 volumio5 volumio[1176]: info: Getting Qobuz Cloud Configuration Sep 15 21:08:11 volumio5 volumio[1176]: info: Asking plugin for UI Config Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 21:08:11 volumio5 volumio[1176]: info: Getting Spotify Cloud Configuration Sep 15 21:08:11 volumio5 volumio[1176]: info: Asking plugin for UI Config Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 21:08:11 volumio5 volumio[1176]: info: Saving Spotify Acccount Sep 15 21:08:11 volumio5 volumio[1176]: info: Got Tidal Cloud Configuration Sep 15 21:08:11 volumio5 volumio[1176]: info: Got it Sep 15 21:08:11 volumio5 volumio[1176]: info: Got it Sep 15 21:08:11 volumio5 volumio[1176]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 15 21:08:11 volumio5 volumio[1176]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::volumioGetBrowseSources Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::volumioGetBrowseSources Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::volumioGetBrowseSources Sep 15 21:08:11 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 21:08:12 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 15 21:08:12 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 15 21:08:13 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 15 21:08:13 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 15 21:08:13 volumio5 volumio[1176]: info: Received Get System Info Sep 15 21:08:13 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 21:08:13 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 21:08:13 volumio5 volumio[1176]: info: Discovery: Getting this device information Sep 15 21:08:13 volumio5 volumio[1176]: info: CoreCommandRouter::volumioGetState Sep 15 21:08:13 volumio5 volumio[1176]: info: CorePlayQueue::getTrack 0 Sep 15 21:08:13 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 21:08:14 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 15 21:08:14 volumio5 volumio[1176]: info: Received Get System Info Sep 15 21:08:14 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 15 21:08:14 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 15 21:08:14 volumio5 volumio[1176]: info: Discovery: Getting this device information Sep 15 21:08:14 volumio5 volumio[1176]: info: CoreCommandRouter::volumioGetState Sep 15 21:08:14 volumio5 volumio[1176]: info: CorePlayQueue::getTrack 0 Sep 15 21:08:14 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 15 21:08:16 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 21:08:19 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Sep 15 21:08:19 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Sep 15 21:08:20 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 21:08:20 volumio5 volumio[1176]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L TATSUYATC.local. -m SMB3_11 Sep 15 21:08:21 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 15 21:08:24 volumio5 volumio[1176]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 15 21:08:24 volumio5 volumio[1176]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 21:08:24 volumio5 volumio[1176]: Error: dns service error: unknown Sep 15 21:08:24 volumio5 volumio[1176]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Sep 15 21:08:24 volumio5 volumio[1176]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Sep 15 21:08:24 volumio5 volumio[1176]: errorCode: -65537 Sep 15 21:08:24 volumio5 volumio[1176]: } Sep 15 21:08:24 volumio5 volumio[1176]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 15 21:08:24 volumio5 sudo[15117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-15 21:07 Sep 15 21:08:24 volumio5 sudo[15117]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"