Mar 24 08:24:02 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 24 08:24:03 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 08:24:03 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 08:24:03 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 08:24:04 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 08:24:04 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 08:24:04 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 24 08:24:04 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 24 08:24:04 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 24 08:24:04 volumio volumio[7499]: info: CoreCommandRouter::volumioGetBrowseSources Mar 24 08:24:04 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 08:24:05 volumio volumio[7499]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 24 08:24:05 volumio volumio[7499]: info: Completed starting MyVolumio Plugin Mar 24 08:24:05 volumio volumio[7499]: [Metrics] CommandRouter: 33s 787.67ms Mar 24 08:24:05 volumio volumio[7499]: info: CoreCommandRouter::volumiosetStartupVolume Mar 24 08:24:05 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 08:24:05 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 08:24:05 volumio volumio[7499]: info: CoreCommandRouter::Close All Modals sent Mar 24 08:24:05 volumio volumio[7499]: info: CoreCommandRouter::Close All Modals sent Mar 24 08:24:06 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 24 08:24:06 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 24 08:24:06 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 24 08:24:06 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 24 08:24:10 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 24 08:24:12 volumio volumio[7499]: info: BOOT COMPLETED Mar 24 08:24:14 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 24 08:24:18 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 24 08:24:22 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 24 08:24:26 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 24 08:24:30 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 24 08:24:34 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 24 08:24:37 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Mar 24 08:24:37 volumio volumio[7499]: info: Adding a new share Mar 24 08:24:37 volumio volumio[7499]: info: Checking key NasMounts.b0d027b5-5514-4db7-974a-76cb7a3be55b Mar 24 08:24:37 volumio volumio[7499]: info: No correspondence found in configuration for share fgggd on IP 192.168.3.6 Mar 24 08:24:37 volumio volumio[7499]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.3.6 -N -m SMB3_11 Mar 24 08:24:37 volumio volumio[7499]: info: Error browsing device fgggd for shares: Command failed: smbclient --debuglevel=4 -L 192.168.3.6 -N -m SMB3_11 Mar 24 08:24:37 volumio volumio[7499]: lp_load_ex: refreshing parameters Mar 24 08:24:37 volumio volumio[7499]: Initialising global parameters Mar 24 08:24:37 volumio volumio[7499]: Processing section "[global]" Mar 24 08:24:37 volumio volumio[7499]: doing parameter netbios name = Volumio Mar 24 08:24:37 volumio volumio[7499]: doing parameter server string = Volumio Audiophile Music Player Mar 24 08:24:37 volumio volumio[7499]: doing parameter workgroup = WORKGROUP Mar 24 08:24:37 volumio volumio[7499]: doing parameter security = user Mar 24 08:24:37 volumio volumio[7499]: doing parameter map to guest = Bad User Mar 24 08:24:37 volumio volumio[7499]: doing parameter encrypt passwords = yes Mar 24 08:24:37 volumio volumio[7499]: lpcfg_do_global_parameter: WARNING: The "encrypt passwords" option is deprecated Mar 24 08:24:37 volumio volumio[7499]: doing parameter wins support = yes Mar 24 08:24:37 volumio volumio[7499]: doing parameter local master = no Mar 24 08:24:37 volumio volumio[7499]: doing parameter preferred master = no Mar 24 08:24:37 volumio volumio[7499]: doing parameter os level = 30 Mar 24 08:24:37 volumio volumio[7499]: doing parameter fruit:model = AirPort Mar 24 08:24:37 volumio volumio[7499]: pm_process() returned Yes Mar 24 08:24:37 volumio volumio[7499]: added interface eth0 ip=192.168.3.47 bcast=192.168.3.255 netmask=255.255.255.0 Mar 24 08:24:37 volumio volumio[7499]: Client started (version 4.17.12-Debian). Mar 24 08:24:37 volumio volumio[7499]: Connecting to 192.168.3.6 at port 445 Mar 24 08:24:37 volumio volumio[7499]: session request ok Mar 24 08:24:37 volumio volumio[7499]: negotiated dialect[SMB3_11] against server[192.168.3.6] Mar 24 08:24:37 volumio volumio[7499]: GENSEC backend 'gssapi_spnego' registered Mar 24 08:24:37 volumio volumio[7499]: GENSEC backend 'gssapi_krb5' registered Mar 24 08:24:37 volumio volumio[7499]: GENSEC backend 'gssapi_krb5_sasl' registered Mar 24 08:24:37 volumio volumio[7499]: GENSEC backend 'spnego' registered Mar 24 08:24:37 volumio volumio[7499]: GENSEC backend 'schannel' registered Mar 24 08:24:37 volumio volumio[7499]: GENSEC backend 'ncalrpc_as_system' registered Mar 24 08:24:37 volumio volumio[7499]: GENSEC backend 'sasl-EXTERNAL' registered Mar 24 08:24:37 volumio volumio[7499]: GENSEC backend 'ntlmssp' registered Mar 24 08:24:37 volumio volumio[7499]: GENSEC backend 'ntlmssp_resume_ccache' registered Mar 24 08:24:37 volumio volumio[7499]: GENSEC backend 'http_basic' registered Mar 24 08:24:37 volumio volumio[7499]: GENSEC backend 'http_ntlm' registered Mar 24 08:24:37 volumio volumio[7499]: GENSEC backend 'http_negotiate' registered Mar 24 08:24:37 volumio volumio[7499]: GENSEC backend 'krb5' registered Mar 24 08:24:37 volumio volumio[7499]: GENSEC backend 'fake_gssapi_krb5' registered Mar 24 08:24:37 volumio volumio[7499]: Cannot do GSE to an IP address Mar 24 08:24:37 volumio volumio[7499]: Failed to start GENSEC client mech gse_krb5: NT_STATUS_INVALID_PARAMETER Mar 24 08:24:37 volumio volumio[7499]: Got challenge flags: Mar 24 08:24:37 volumio volumio[7499]: Got NTLMSSP neg_flags=0x628a8215 Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_UNICODE Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_REQUEST_TARGET Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_SIGN Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_NTLM Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_TARGET_TYPE_SERVER Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_TARGET_INFO Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_VERSION Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_128 Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_KEY_EXCH Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP: Set final flags: Mar 24 08:24:37 volumio volumio[7499]: Got NTLMSSP neg_flags=0x62008215 Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_UNICODE Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_REQUEST_TARGET Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_SIGN Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_NTLM Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_VERSION Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_128 Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_KEY_EXCH Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP Sign/Seal - Initialising with flags: Mar 24 08:24:37 volumio volumio[7499]: Got NTLMSSP neg_flags=0x62008215 Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_UNICODE Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_REQUEST_TARGET Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_SIGN Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_NTLM Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_VERSION Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_128 Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_KEY_EXCH Mar 24 08:24:37 volumio volumio[7499]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. Mar 24 08:24:37 volumio volumio[7499]: Got challenge flags: Mar 24 08:24:37 volumio volumio[7499]: Got NTLMSSP neg_flags=0x628a8215 Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_UNICODE Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_REQUEST_TARGET Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_SIGN Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_NTLM Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_TARGET_TYPE_SERVER Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_TARGET_INFO Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_VERSION Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_128 Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_KEY_EXCH Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP: Set final flags: Mar 24 08:24:37 volumio volumio[7499]: Got NTLMSSP neg_flags=0x62008a15 Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_UNICODE Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_REQUEST_TARGET Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_SIGN Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_NTLM Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_ANONYMOUS Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_VERSION Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_128 Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_KEY_EXCH Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP Sign/Seal - Initialising with flags: Mar 24 08:24:37 volumio volumio[7499]: Got NTLMSSP neg_flags=0x62008a15 Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_UNICODE Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_REQUEST_TARGET Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_SIGN Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_NTLM Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_ANONYMOUS Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_VERSION Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_128 Mar 24 08:24:37 volumio volumio[7499]: NTLMSSP_NEGOTIATE_KEY_EXCH Mar 24 08:24:37 volumio volumio[7499]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. Mar 24 08:24:37 volumio volumio[7499]: info: Detected device fgggd with version null Mar 24 08:24:37 volumio sudo[7961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=fgggd,password=Samfgd1974,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.3.6/东芝4T /mnt/NAS/fgggd Mar 24 08:24:37 volumio sudo[7961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 08:24:37 volumio kernel: CIFS: Attempting to mount //192.168.3.6/东芝4T Mar 24 08:24:37 volumio sudo[7961]: pam_unix(sudo:session): session closed for user root Mar 24 08:24:37 volumio volumio[7499]: verbose: ControllerMpd::sendMpdCommand status Mar 24 08:24:37 volumio volumio[7499]: info: Mar 24 08:24:37 volumio volumio[7499]: ---------------------------- MPD announces state update: update Mar 24 08:24:37 volumio volumio[7499]: info: ControllerMpd::getState Mar 24 08:24:37 volumio volumio[7499]: verbose: ControllerMpd::sendMpdCommand status Mar 24 08:24:37 volumio volumio[7499]: verbose: ControllerMpd::sendMpdCommand status Mar 24 08:24:37 volumio volumio[7499]: info: Mar 24 08:24:37 volumio volumio[7499]: ---------------------------- MPD announces state update: update Mar 24 08:24:37 volumio volumio[7499]: info: ControllerMpd::getState Mar 24 08:24:37 volumio volumio[7499]: verbose: ControllerMpd::sendMpdCommand status Mar 24 08:24:37 volumio volumio[7499]: verbose: ControllerMpd::sendMpdCommand status Mar 24 08:24:37 volumio volumio[7499]: info: Mar 24 08:24:37 volumio volumio[7499]: ---------------------------- MPD announces state update: update Mar 24 08:24:37 volumio volumio[7499]: info: ControllerMpd::getState Mar 24 08:24:37 volumio volumio[7499]: verbose: ControllerMpd::sendMpdCommand status Mar 24 08:24:37 volumio volumio[7499]: info: sendMpdCommand status took 11 milliseconds Mar 24 08:24:37 volumio volumio[7499]: info: sendMpdCommand status took 10 milliseconds Mar 24 08:24:37 volumio volumio[7499]: info: sendMpdCommand status took 9 milliseconds Mar 24 08:24:37 volumio volumio[7499]: info: sendMpdCommand status took 9 milliseconds Mar 24 08:24:37 volumio volumio[7499]: info: sendMpdCommand status took 8 milliseconds Mar 24 08:24:37 volumio volumio[7499]: info: sendMpdCommand status took 8 milliseconds Mar 24 08:24:37 volumio volumio[7499]: verbose: ControllerMpd::parseState Mar 24 08:24:37 volumio volumio[7499]: info: Command Router : Notfying DB Updatetrue Mar 24 08:24:37 volumio volumio[7499]: verbose: ControllerMpd::parseState Mar 24 08:24:37 volumio volumio[7499]: verbose: ControllerMpd::parseState Mar 24 08:24:37 volumio volumio[7499]: info: Command Router : Notfying DB Updatetrue Mar 24 08:24:37 volumio volumio[7499]: info: CoreCommandRouter::Close All Modals sent Mar 24 08:24:37 volumio volumio[7499]: verbose: ControllerMpd::parseState Mar 24 08:24:37 volumio volumio[7499]: verbose: ControllerMpd::parseState Mar 24 08:24:37 volumio volumio[7499]: info: Command Router : Notfying DB Updatetrue Mar 24 08:24:37 volumio volumio[7499]: info: CoreCommandRouter::Close All Modals sent Mar 24 08:24:37 volumio volumio[7499]: verbose: ControllerMpd::parseState Mar 24 08:24:38 volumio volumio[7499]: info: ControllerMpd::pushState Mar 24 08:24:38 volumio volumio[7499]: info: CoreCommandRouter::servicePushState Mar 24 08:24:38 volumio volumio[7499]: info: CoreStateMachine::pushState Mar 24 08:24:38 volumio volumio[7499]: info: CorePlayQueue::getTrack 0 Mar 24 08:24:38 volumio volumio[7499]: info: CoreCommandRouter::volumioPushState Mar 24 08:24:38 volumio volumio[7499]: info: CorePlayQueue::getTrack 0 Mar 24 08:24:38 volumio volumio[7499]: 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} Mar 24 08:24:38 volumio volumio[7499]: verbose: CURRENT POSITION 0 Mar 24 08:24:38 volumio volumio[7499]: info: CoreStateMachine::syncState stateService stop Mar 24 08:24:38 volumio volumio[7499]: info: CoreStateMachine::syncState currentStatus stop Mar 24 08:24:38 volumio volumio[7499]: info: CoreStateMachine::pushState Mar 24 08:24:38 volumio volumio[7499]: info: CorePlayQueue::getTrack 0 Mar 24 08:24:38 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 08:24:38 volumio volumio[7499]: info: CoreCommandRouter::volumioPushState Mar 24 08:24:38 volumio volumio[7499]: info: No code Mar 24 08:24:38 volumio volumio[7499]: info: CoreStateMachine::pushState Mar 24 08:24:38 volumio volumio[7499]: info: CorePlayQueue::getTrack 0 Mar 24 08:24:38 volumio volumio[7499]: info: CoreCommandRouter::volumioPushState Mar 24 08:24:38 volumio volumio[7499]: info: ControllerMpd::pushState Mar 24 08:24:38 volumio volumio[7499]: info: CoreCommandRouter::servicePushState Mar 24 08:24:38 volumio volumio[7499]: info: CoreStateMachine::pushState Mar 24 08:24:38 volumio volumio[7499]: info: CorePlayQueue::getTrack 0 Mar 24 08:24:38 volumio volumio[7499]: info: CoreCommandRouter::volumioPushState Mar 24 08:24:38 volumio volumio[7499]: info: CorePlayQueue::getTrack 0 Mar 24 08:24:38 volumio volumio[7499]: 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} Mar 24 08:24:38 volumio volumio[7499]: verbose: CURRENT POSITION 0 Mar 24 08:24:38 volumio volumio[7499]: info: CoreStateMachine::syncState stateService stop Mar 24 08:24:38 volumio volumio[7499]: info: CoreStateMachine::syncState currentStatus stop Mar 24 08:24:38 volumio volumio[7499]: info: CoreStateMachine::pushState Mar 24 08:24:38 volumio volumio[7499]: info: CorePlayQueue::getTrack 0 Mar 24 08:24:38 volumio volumio[7499]: info: CoreCommandRouter::volumioPushState Mar 24 08:24:38 volumio volumio[7499]: info: No code Mar 24 08:24:38 volumio volumio[7499]: info: CoreStateMachine::pushState Mar 24 08:24:38 volumio volumio[7499]: info: CorePlayQueue::getTrack 0 Mar 24 08:24:38 volumio volumio[7499]: info: CoreCommandRouter::volumioPushState Mar 24 08:24:38 volumio volumio[7499]: info: ControllerMpd::pushState Mar 24 08:24:38 volumio volumio[7499]: info: CoreCommandRouter::servicePushState Mar 24 08:24:38 volumio volumio[7499]: info: CoreStateMachine::pushState Mar 24 08:24:38 volumio volumio[7499]: info: CorePlayQueue::getTrack 0 Mar 24 08:24:38 volumio volumio[7499]: info: CoreCommandRouter::volumioPushState Mar 24 08:24:38 volumio volumio[7499]: info: CorePlayQueue::getTrack 0 Mar 24 08:24:38 volumio volumio[7499]: 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} Mar 24 08:24:38 volumio volumio[7499]: verbose: CURRENT POSITION 0 Mar 24 08:24:38 volumio volumio[7499]: info: CoreStateMachine::syncState stateService stop Mar 24 08:24:38 volumio volumio[7499]: info: CoreStateMachine::syncState currentStatus stop Mar 24 08:24:38 volumio volumio[7499]: info: CoreStateMachine::pushState Mar 24 08:24:38 volumio volumio[7499]: info: CorePlayQueue::getTrack 0 Mar 24 08:24:38 volumio volumio[7499]: info: CoreCommandRouter::volumioPushState Mar 24 08:24:38 volumio volumio[7499]: info: No code Mar 24 08:24:38 volumio volumio[7499]: info: CoreStateMachine::pushState Mar 24 08:24:38 volumio volumio[7499]: info: CorePlayQueue::getTrack 0 Mar 24 08:24:38 volumio volumio[7499]: info: CoreCommandRouter::volumioPushState Mar 24 08:24:38 volumio volumio[7499]: info: ------------------------------ 140ms Mar 24 08:24:38 volumio volumio[7499]: info: ------------------------------ 139ms Mar 24 08:24:38 volumio volumio[7499]: info: ------------------------------ 139ms Mar 24 08:24:38 volumio volumio[7499]: info: [1774340678094] Database update started Mar 24 08:24:38 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 24 08:24:38 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 24 08:24:42 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 24 08:24:46 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 24 08:24:50 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 24 08:24:53 volumio volumio[7499]: info: CoreCommandRouter::volumioGetState Mar 24 08:24:53 volumio volumio[7499]: info: CorePlayQueue::getTrack 0 Mar 24 08:25:07 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 24 08:25:07 volumio volumio[7499]: info: CURURI: music-library/NAS Mar 24 08:25:08 volumio volumio[7499]: info: Preload queue cleared Mar 24 08:25:08 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 24 08:25:08 volumio volumio[7499]: info: CURURI: music-library/NAS/fgggd Mar 24 08:25:08 volumio volumio[7499]: info: Preload queue cleared Mar 24 08:25:14 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 24 08:25:14 volumio volumio[7499]: info: CURURI: music-library/NAS/fgggd Mar 24 08:25:14 volumio volumio[7499]: info: Preload queue cleared Mar 24 08:25:15 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 24 08:25:15 volumio volumio[7499]: info: CURURI: music-library/NAS/fgggd/2026.03music Mar 24 08:25:15 volumio volumio[7499]: info: Preload queue cleared Mar 24 08:25:30 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 24 08:25:30 volumio volumio[7499]: info: CURURI: music-library/NAS/fgggd Mar 24 08:25:30 volumio volumio[7499]: info: Preload queue cleared Mar 24 08:25:33 volumio volumio[7499]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 24 08:25:33 volumio volumio[7499]: info: CURURI: music-library/NAS/216+ Mar 24 08:25:34 volumio volumio[7499]: info: Preload queue cleared Mar 24 08:25:45 volumio volumio[7499]: info: Preload queue cleared Mar 24 08:25:45 volumio volumio[7499]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 08:25:45 volumio volumio[7499]: info: CoreStateMachine::ClearQueue Mar 24 08:25:45 volumio volumio[7499]: info: CoreStateMachine::stop Mar 24 08:25:45 volumio volumio[7499]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 08:25:45 volumio volumio[7499]: info: CorePlayQueue::clearPlayQueue Mar 24 08:25:45 volumio volumio[7499]: info: CorePlayQueue::saveQueue Mar 24 08:25:45 volumio volumio[7499]: info: CoreCommandRouter::volumioPushQueue Mar 24 08:25:45 volumio volumio[7499]: info: CoreStateMachine::addQueueItems Mar 24 08:25:45 volumio volumio[7499]: info: CorePlayQueue::addQueueItems Mar 24 08:25:45 volumio volumio[7499]: info: Preload queue cleared Mar 24 08:25:45 volumio volumio[7499]: info: Adding Item to queue: music-library/NAS/216+/《世界名曲鉴赏》珍藏版 FLAC+CUE Mar 24 08:25:45 volumio volumio[7499]: info: Exploding uri music-library/NAS/216+/《世界名曲鉴赏》珍藏版 FLAC+CUE in service mpd Mar 24 08:25:46 volumio volumio[7499]: info: ALBUMART /albumart?cacheid=466&web=%3F%3F%3F%3F%3F%3F%20/%3F%EF%BF%BD%EF%BF%BD%3F%3F%3F%3F%3F%3F%3F%3F%3F%5B%3F%3F%3F%D8%B0%3F%5D/extralarge&path=%2Fmnt%2FNAS%2F216%2B%2F%E3%80%8A%E4%B8%96%E7%95%8C%E5%90%8D%E6%9B%B2%E9%89%B4%E8%B5%8F%E3%80%8B%E7%8F%8D%E8%97%8F%E7%89%88%20FLAC%2BCUE%2F%E4%B8%96%E7%95%8C%E5%90%8D%E6%9B%B2%E9%89%B4%E8%B5%8F%5B%E7%8F%8D%E8%97%8F%E7%89%88%5D(FLAC).cue&metadata=false Mar 24 08:25:46 volumio volumio[7499]: info: URI /mnt/NAS/216+/《世界名曲鉴赏》珍藏版 FLAC+CUE/世界名曲鉴赏[珍藏版](FLAC).cue Mar 24 08:25:46 volumio volumio[7499]: info: ALBUMART /albumart?cacheid=466&web=%CA%A9%3F%3F%3F%3F%CB%B9%20/%3F%EF%BF%BD%EF%BF%BD%3F%3F%3F%3F%3F%3F%3F%3F%3F%5B%3F%3F%3F%D8%B0%3F%5D/extralarge&path=%2Fmnt%2FNAS%2F216%2B%2F%E3%80%8A%E4%B8%96%E7%95%8C%E5%90%8D%E6%9B%B2%E9%89%B4%E8%B5%8F%E3%80%8B%E7%8F%8D%E8%97%8F%E7%89%88%20FLAC%2BCUE%2F%E4%B8%96%E7%95%8C%E5%90%8D%E6%9B%B2%E9%89%B4%E8%B5%8F%5B%E7%8F%8D%E8%97%8F%E7%89%88%5D(FLAC).cue&metadata=false Mar 24 08:25:46 volumio volumio[7499]: info: URI /mnt/NAS/216+/《世界名曲鉴赏》珍藏版 FLAC+CUE/世界名曲鉴赏[珍藏版](FLAC).cue Mar 24 08:25:46 volumio volumio[7499]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 08:25:46 volumio volumio[7499]: Error: Unable to resolve or reject the same promise twice Mar 24 08:25:46 volumio volumio[7499]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 24 08:25:46 volumio volumio[7499]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Mar 24 08:25:46 volumio volumio[7499]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Mar 24 08:25:46 volumio volumio[7499]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Mar 24 08:25:46 volumio volumio[7499]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 24 08:25:46 volumio volumio[7499]: at Socket.emit (node:events:514:28) Mar 24 08:25:46 volumio volumio[7499]: at addChunk (node:internal/streams/readable:343:12) Mar 24 08:25:46 volumio volumio[7499]: at readableAddChunk (node:internal/streams/readable:312:11) Mar 24 08:25:46 volumio volumio[7499]: at Readable.push (node:internal/streams/readable:253:10) Mar 24 08:25:46 volumio volumio[7499]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Mar 24 08:25:46 volumio volumio[7499]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 08:25:46 volumio sudo[8232]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 08:24' Mar 24 08:25:46 volumio sudo[8232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:38:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00e933ebe3692ad8aea9910d2289bf23"