Dec 29 19:40:00 volumio volumio[18512]: info: BOOT COMPLETED Dec 29 19:40:03 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 19:40:07 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 19:40:11 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 19:40:15 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 19:40:19 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 19:40:23 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 19:40:25 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Dec 29 19:40:25 volumio volumio[18512]: info: Adding a new share Dec 29 19:40:25 volumio volumio[18512]: info: Checking key NasMounts.2e81feea-15c2-4a61-81f4-d51f72d02c87 Dec 29 19:40:25 volumio volumio[18512]: info: No correspondence found in configuration for share Kingcatnas2 on IP 192.168.219.129 Dec 29 19:40:25 volumio volumio[18512]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.219.129 -N -m SMB3_11 Dec 29 19:40:26 volumio volumio[18512]: info: Error browsing device Kingcatnas2 for shares: Command failed: smbclient --debuglevel=4 -L 192.168.219.129 -N -m SMB3_11 Dec 29 19:40:26 volumio volumio[18512]: lp_load_ex: refreshing parameters Dec 29 19:40:26 volumio volumio[18512]: Initialising global parameters Dec 29 19:40:26 volumio volumio[18512]: Processing section "[global]" Dec 29 19:40:26 volumio volumio[18512]: doing parameter netbios name = Volumio_Kingcat Dec 29 19:40:26 volumio volumio[18512]: doing parameter server string = Audiophile Music Player Dec 29 19:40:26 volumio volumio[18512]: doing parameter workgroup = WORKGROUP Dec 29 19:40:26 volumio volumio[18512]: doing parameter security = user Dec 29 19:40:26 volumio volumio[18512]: doing parameter map to guest = Bad User Dec 29 19:40:26 volumio volumio[18512]: doing parameter encrypt passwords = yes Dec 29 19:40:26 volumio volumio[18512]: lpcfg_do_global_parameter: WARNING: The "encrypt passwords" option is deprecated Dec 29 19:40:26 volumio volumio[18512]: doing parameter wins support = yes Dec 29 19:40:26 volumio volumio[18512]: doing parameter local master = no Dec 29 19:40:26 volumio volumio[18512]: doing parameter preferred master = no Dec 29 19:40:26 volumio volumio[18512]: doing parameter os level = 30 Dec 29 19:40:26 volumio volumio[18512]: pm_process() returned Yes Dec 29 19:40:26 volumio volumio[18512]: added interface wlan0 ip=192.168.219.114 bcast=192.168.219.255 netmask=255.255.255.0 Dec 29 19:40:26 volumio volumio[18512]: Client started (version 4.17.12-Debian). Dec 29 19:40:26 volumio volumio[18512]: Connecting to 192.168.219.129 at port 445 Dec 29 19:40:26 volumio volumio[18512]: session request ok Dec 29 19:40:26 volumio volumio[18512]: negotiated dialect[SMB3_11] against server[192.168.219.129] Dec 29 19:40:26 volumio volumio[18512]: GENSEC backend 'gssapi_spnego' registered Dec 29 19:40:26 volumio volumio[18512]: GENSEC backend 'gssapi_krb5' registered Dec 29 19:40:26 volumio volumio[18512]: GENSEC backend 'gssapi_krb5_sasl' registered Dec 29 19:40:26 volumio volumio[18512]: GENSEC backend 'spnego' registered Dec 29 19:40:26 volumio volumio[18512]: GENSEC backend 'schannel' registered Dec 29 19:40:26 volumio volumio[18512]: GENSEC backend 'ncalrpc_as_system' registered Dec 29 19:40:26 volumio volumio[18512]: GENSEC backend 'sasl-EXTERNAL' registered Dec 29 19:40:26 volumio volumio[18512]: GENSEC backend 'ntlmssp' registered Dec 29 19:40:26 volumio volumio[18512]: GENSEC backend 'ntlmssp_resume_ccache' registered Dec 29 19:40:26 volumio volumio[18512]: GENSEC backend 'http_basic' registered Dec 29 19:40:26 volumio volumio[18512]: GENSEC backend 'http_ntlm' registered Dec 29 19:40:26 volumio volumio[18512]: GENSEC backend 'http_negotiate' registered Dec 29 19:40:26 volumio volumio[18512]: GENSEC backend 'krb5' registered Dec 29 19:40:26 volumio volumio[18512]: GENSEC backend 'fake_gssapi_krb5' registered Dec 29 19:40:26 volumio volumio[18512]: Cannot do GSE to an IP address Dec 29 19:40:26 volumio volumio[18512]: Failed to start GENSEC client mech gse_krb5: NT_STATUS_INVALID_PARAMETER Dec 29 19:40:26 volumio volumio[18512]: Got challenge flags: Dec 29 19:40:26 volumio volumio[18512]: Got NTLMSSP neg_flags=0x628a8215 Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_UNICODE Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_REQUEST_TARGET Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_SIGN Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_NTLM Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_TARGET_TYPE_SERVER Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_TARGET_INFO Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_VERSION Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_128 Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_KEY_EXCH Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP: Set final flags: Dec 29 19:40:26 volumio volumio[18512]: Got NTLMSSP neg_flags=0x62008215 Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_UNICODE Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_REQUEST_TARGET Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_SIGN Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_NTLM Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_VERSION Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_128 Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_KEY_EXCH Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP Sign/Seal - Initialising with flags: Dec 29 19:40:26 volumio volumio[18512]: Got NTLMSSP neg_flags=0x62008215 Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_UNICODE Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_REQUEST_TARGET Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_SIGN Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_NTLM Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_VERSION Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_128 Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_KEY_EXCH Dec 29 19:40:26 volumio volumio[18512]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. Dec 29 19:40:26 volumio volumio[18512]: Got challenge flags: Dec 29 19:40:26 volumio volumio[18512]: Got NTLMSSP neg_flags=0x628a8215 Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_UNICODE Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_REQUEST_TARGET Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_SIGN Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_NTLM Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_TARGET_TYPE_SERVER Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_TARGET_INFO Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_VERSION Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_128 Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_KEY_EXCH Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP: Set final flags: Dec 29 19:40:26 volumio volumio[18512]: Got NTLMSSP neg_flags=0x62008a15 Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_UNICODE Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_REQUEST_TARGET Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_SIGN Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_NTLM Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_ANONYMOUS Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_VERSION Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_128 Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_KEY_EXCH Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP Sign/Seal - Initialising with flags: Dec 29 19:40:26 volumio volumio[18512]: Got NTLMSSP neg_flags=0x62008a15 Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_UNICODE Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_REQUEST_TARGET Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_SIGN Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_NTLM Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_ANONYMOUS Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_VERSION Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_128 Dec 29 19:40:26 volumio volumio[18512]: NTLMSSP_NEGOTIATE_KEY_EXCH Dec 29 19:40:26 volumio volumio[18512]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. Dec 29 19:40:26 volumio volumio[18512]: info: Detected device Kingcatnas2 with version null Dec 29 19:40:26 volumio sudo[19063]: volumio : unable to resolve host volumio: System error Dec 29 19:40:26 volumio sudo[19063]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=wildkitty,password=Madokano#1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.219.129/usbshare1 /mnt/NAS/Kingcatnas2 Dec 29 19:40:26 volumio sudo[19063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 19:40:26 volumio kernel: CIFS: Attempting to mount //192.168.219.129/usbshare1 Dec 29 19:40:26 volumio sudo[19063]: pam_unix(sudo:session): session closed for user root Dec 29 19:40:26 volumio volumio[18512]: verbose: ControllerMpd::sendMpdCommand status Dec 29 19:40:26 volumio volumio[18512]: info: Dec 29 19:40:26 volumio volumio[18512]: ---------------------------- MPD announces state update: update Dec 29 19:40:26 volumio volumio[18512]: info: ControllerMpd::getState Dec 29 19:40:26 volumio volumio[18512]: verbose: ControllerMpd::sendMpdCommand status Dec 29 19:40:26 volumio volumio[18512]: verbose: ControllerMpd::sendMpdCommand status Dec 29 19:40:26 volumio volumio[18512]: info: Dec 29 19:40:26 volumio volumio[18512]: ---------------------------- MPD announces state update: update Dec 29 19:40:26 volumio volumio[18512]: info: ControllerMpd::getState Dec 29 19:40:26 volumio volumio[18512]: verbose: ControllerMpd::sendMpdCommand status Dec 29 19:40:26 volumio volumio[18512]: verbose: ControllerMpd::sendMpdCommand status Dec 29 19:40:26 volumio volumio[18512]: info: Dec 29 19:40:26 volumio volumio[18512]: ---------------------------- MPD announces state update: update Dec 29 19:40:26 volumio volumio[18512]: info: ControllerMpd::getState Dec 29 19:40:26 volumio volumio[18512]: verbose: ControllerMpd::sendMpdCommand status Dec 29 19:40:26 volumio volumio[18512]: info: sendMpdCommand status took 3 milliseconds Dec 29 19:40:26 volumio volumio[18512]: info: sendMpdCommand status took 3 milliseconds Dec 29 19:40:26 volumio volumio[18512]: info: sendMpdCommand status took 3 milliseconds Dec 29 19:40:26 volumio volumio[18512]: info: sendMpdCommand status took 2 milliseconds Dec 29 19:40:26 volumio volumio[18512]: verbose: ControllerMpd::parseState Dec 29 19:40:26 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 29 19:40:26 volumio volumio[18512]: info: Command Router : Notfying DB Updatetrue Dec 29 19:40:26 volumio volumio[18512]: verbose: ControllerMpd::parseState Dec 29 19:40:26 volumio volumio[18512]: verbose: ControllerMpd::parseState Dec 29 19:40:26 volumio volumio[18512]: info: Command Router : Notfying DB Updatetrue Dec 29 19:40:26 volumio volumio[18512]: info: CoreCommandRouter::Close All Modals sent Dec 29 19:40:26 volumio volumio[18512]: verbose: ControllerMpd::parseState Dec 29 19:40:26 volumio volumio[18512]: info: ControllerMpd::pushState Dec 29 19:40:26 volumio volumio[18512]: info: CoreCommandRouter::servicePushState Dec 29 19:40:26 volumio volumio[18512]: info: CoreStateMachine::pushState Dec 29 19:40:26 volumio volumio[18512]: info: CorePlayQueue::getTrack 0 Dec 29 19:40:26 volumio volumio[18512]: info: CoreCommandRouter::volumioPushState Dec 29 19:40:26 volumio volumio[18512]: info: CorePlayQueue::getTrack 0 Dec 29 19:40:26 volumio volumio[18512]: 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} Dec 29 19:40:26 volumio volumio[18512]: verbose: CURRENT POSITION 0 Dec 29 19:40:26 volumio volumio[18512]: info: CoreStateMachine::syncState stateService stop Dec 29 19:40:26 volumio volumio[18512]: info: CoreStateMachine::syncState currentStatus stop Dec 29 19:40:26 volumio volumio[18512]: info: CoreStateMachine::pushState Dec 29 19:40:26 volumio volumio[18512]: info: CorePlayQueue::getTrack 0 Dec 29 19:40:26 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 19:40:26 volumio volumio[18512]: info: CoreCommandRouter::volumioPushState Dec 29 19:40:26 volumio volumio[18512]: info: No code Dec 29 19:40:26 volumio volumio[18512]: info: CoreStateMachine::pushState Dec 29 19:40:26 volumio volumio[18512]: info: CorePlayQueue::getTrack 0 Dec 29 19:40:26 volumio volumio[18512]: info: CoreCommandRouter::volumioPushState Dec 29 19:40:26 volumio volumio[18512]: info: ControllerMpd::pushState Dec 29 19:40:26 volumio volumio[18512]: info: CoreCommandRouter::servicePushState Dec 29 19:40:26 volumio volumio[18512]: info: CoreStateMachine::pushState Dec 29 19:40:26 volumio volumio[18512]: info: CorePlayQueue::getTrack 0 Dec 29 19:40:26 volumio volumio[18512]: info: CoreCommandRouter::volumioPushState Dec 29 19:40:26 volumio volumio[18512]: info: CorePlayQueue::getTrack 0 Dec 29 19:40:26 volumio volumio[18512]: 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} Dec 29 19:40:26 volumio volumio[18512]: verbose: CURRENT POSITION 0 Dec 29 19:40:26 volumio volumio[18512]: info: CoreStateMachine::syncState stateService stop Dec 29 19:40:26 volumio volumio[18512]: info: CoreStateMachine::syncState currentStatus stop Dec 29 19:40:26 volumio volumio[18512]: info: CoreStateMachine::pushState Dec 29 19:40:26 volumio volumio[18512]: info: CorePlayQueue::getTrack 0 Dec 29 19:40:26 volumio volumio[18512]: info: CoreCommandRouter::volumioPushState Dec 29 19:40:26 volumio volumio[18512]: info: No code Dec 29 19:40:26 volumio volumio[18512]: info: CoreStateMachine::pushState Dec 29 19:40:26 volumio volumio[18512]: info: CorePlayQueue::getTrack 0 Dec 29 19:40:26 volumio volumio[18512]: info: CoreCommandRouter::volumioPushState Dec 29 19:40:26 volumio volumio[18512]: info: ------------------------------ 228ms Dec 29 19:40:26 volumio volumio[18512]: info: ------------------------------ 227ms Dec 29 19:40:26 volumio volumio[18512]: info: sendMpdCommand status took 227 milliseconds Dec 29 19:40:26 volumio volumio[18512]: info: sendMpdCommand status took 227 milliseconds Dec 29 19:40:26 volumio volumio[18512]: verbose: ControllerMpd::parseState Dec 29 19:40:26 volumio volumio[18512]: info: Command Router : Notfying DB Updatetrue Dec 29 19:40:26 volumio volumio[18512]: info: CoreCommandRouter::Close All Modals sent Dec 29 19:40:26 volumio volumio[18512]: verbose: ControllerMpd::parseState Dec 29 19:40:26 volumio volumio[18512]: info: ControllerMpd::pushState Dec 29 19:40:26 volumio volumio[18512]: info: CoreCommandRouter::servicePushState Dec 29 19:40:26 volumio volumio[18512]: info: CoreStateMachine::pushState Dec 29 19:40:26 volumio volumio[18512]: info: CorePlayQueue::getTrack 0 Dec 29 19:40:26 volumio volumio[18512]: info: CoreCommandRouter::volumioPushState Dec 29 19:40:26 volumio volumio[18512]: info: CorePlayQueue::getTrack 0 Dec 29 19:40:26 volumio volumio[18512]: 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} Dec 29 19:40:26 volumio volumio[18512]: verbose: CURRENT POSITION 0 Dec 29 19:40:26 volumio volumio[18512]: info: CoreStateMachine::syncState stateService stop Dec 29 19:40:26 volumio volumio[18512]: info: CoreStateMachine::syncState currentStatus stop Dec 29 19:40:26 volumio volumio[18512]: info: CoreStateMachine::pushState Dec 29 19:40:26 volumio volumio[18512]: info: CorePlayQueue::getTrack 0 Dec 29 19:40:26 volumio volumio[18512]: info: CoreCommandRouter::volumioPushState Dec 29 19:40:26 volumio volumio[18512]: info: No code Dec 29 19:40:26 volumio volumio[18512]: info: CoreStateMachine::pushState Dec 29 19:40:26 volumio volumio[18512]: info: CorePlayQueue::getTrack 0 Dec 29 19:40:26 volumio volumio[18512]: info: CoreCommandRouter::volumioPushState Dec 29 19:40:26 volumio volumio[18512]: info: ------------------------------ 299ms Dec 29 19:40:26 volumio volumio[18512]: info: [1767004826622] Database update started Dec 29 19:40:26 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 29 19:40:26 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 29 19:40:27 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 29 19:40:27 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 19:40:31 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 19:40:35 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 19:40:39 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 19:40:53 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 29 19:40:53 volumio volumio[18512]: info: Preload queue cleared Dec 29 19:40:54 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 29 19:40:55 volumio volumio[18512]: info: Preload queue cleared Dec 29 19:41:04 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 29 19:41:04 volumio volumio[18512]: info: CURURI: music-library Dec 29 19:41:04 volumio volumio[18512]: info: Preload queue cleared Dec 29 19:41:07 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 29 19:41:07 volumio volumio[18512]: info: CURURI: music-library/INTERNAL Dec 29 19:41:07 volumio volumio[18512]: info: Preload queue cleared Dec 29 19:41:10 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 29 19:41:10 volumio volumio[18512]: info: CURURI: music-library/NAS Dec 29 19:41:10 volumio volumio[18512]: info: Preload queue cleared Dec 29 19:41:13 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 29 19:41:13 volumio volumio[18512]: info: CURURI: music-library/NAS/Kingcatnas2 Dec 29 19:41:13 volumio volumio[18512]: info: Preload queue cleared Dec 29 19:41:16 volumio volumio[18512]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 29 19:41:16 volumio volumio[18512]: info: CURURI: music-library/NAS/Kingcatnas2/4minute (lossless) Dec 29 19:41:17 volumio volumio[18512]: info: Preload queue cleared Dec 29 19:41:17 volumio volumio[18512]: info: Preloading song: music-library/NAS/Kingcatnas2/4minute (lossless)/Lee Hyori - Bring It Back (feat. Bekah, Jiyoon).flac Dec 29 19:41:17 volumio volumio[18512]: info: Preloading song: music-library/NAS/Kingcatnas2/4minute (lossless)/Navi - Nunmuldo Akkawo (feat. Hyun A from 4minute).flac Dec 29 19:41:18 volumio volumio[18512]: info: Exploding uri music-library/NAS/Kingcatnas2/4minute (lossless)/Lee Hyori - Bring It Back (feat. Bekah, Jiyoon).flac in service mpd Dec 29 19:41:18 volumio volumio[18512]: info: Exploding uri music-library/NAS/Kingcatnas2/4minute (lossless)/Navi - Nunmuldo Akkawo (feat. Hyun A from 4minute).flac in service mpd Dec 29 19:41:19 volumio volumio[18512]: info: ALBUMART /albumart?cacheid=128&web=Lee%20Hyori/H-Logic/extralarge&path=%2Fmnt%2FNAS%2FKingcatnas2%2F4minute%20(lossless)%2FLee%20Hyori%20-%20Bring%20It%20Back%20(feat.%20Bekah%2C%20Jiyoon).flac&metadata=false Dec 29 19:41:19 volumio volumio[18512]: info: URI /mnt/NAS/Kingcatnas2/4minute (lossless)/Lee Hyori - Bring It Back (feat. Bekah, Jiyoon).flac Dec 29 19:41:19 volumio volumio[18512]: info: ALBUMART /albumart?cacheid=128&web=Navi/HELLO/extralarge&path=%2Fmnt%2FNAS%2FKingcatnas2%2F4minute%20(lossless)%2FNavi%20-%20Nunmuldo%20Akkawo%20(feat.%20Hyun%20A%20from%204minute).flac&metadata=false Dec 29 19:41:19 volumio volumio[18512]: info: URI /mnt/NAS/Kingcatnas2/4minute (lossless)/Navi - Nunmuldo Akkawo (feat. Hyun A from 4minute).flac Dec 29 19:41:21 volumio volumio[18512]: info: Preload queue cleared Dec 29 19:41:21 volumio volumio[18512]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 19:41:21 volumio volumio[18512]: info: CoreStateMachine::ClearQueue Dec 29 19:41:21 volumio volumio[18512]: info: CoreStateMachine::stop Dec 29 19:41:21 volumio volumio[18512]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 19:41:21 volumio volumio[18512]: info: CorePlayQueue::clearPlayQueue Dec 29 19:41:21 volumio volumio[18512]: info: CorePlayQueue::saveQueue Dec 29 19:41:21 volumio volumio[18512]: info: CoreCommandRouter::volumioPushQueue Dec 29 19:41:21 volumio volumio[18512]: info: CoreStateMachine::addQueueItems Dec 29 19:41:21 volumio volumio[18512]: info: CorePlayQueue::addQueueItems Dec 29 19:41:21 volumio volumio[18512]: info: Preload queue cleared Dec 29 19:41:21 volumio volumio[18512]: info: Adding Item to queue: music-library/NAS/Kingcatnas2/4minute (lossless)/Lee Hyori - Bring It Back (feat. Bekah, Jiyoon).flac Dec 29 19:41:21 volumio volumio[18512]: info: Using cached record of: music-library/NAS/Kingcatnas2/4minute (lossless)/Lee Hyori - Bring It Back (feat. Bekah, Jiyoon).flac Dec 29 19:41:21 volumio volumio[18512]: info: CoreCommandRouter::volumioPushQueue Dec 29 19:41:21 volumio volumio[18512]: info: CorePlayQueue::saveQueue Dec 29 19:41:21 volumio volumio[18512]: info: CoreStateMachine::updateTrackBlock Dec 29 19:41:21 volumio volumio[18512]: info: CorePlayQueue::getTrackBlock Dec 29 19:41:21 volumio volumio[18512]: info: CoreCommandRouter::volumioPlay Dec 29 19:41:21 volumio volumio[18512]: info: CoreStateMachine::play index 0 Dec 29 19:41:21 volumio volumio[18512]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 19:41:21 volumio volumio[18512]: info: CoreStateMachine::addQueueItems Dec 29 19:41:21 volumio volumio[18512]: info: CorePlayQueue::addQueueItems Dec 29 19:41:21 volumio volumio[18512]: info: Preload queue cleared Dec 29 19:41:21 volumio volumio[18512]: info: Adding Item to queue: music-library/NAS/Kingcatnas2/4minute (lossless)/Navi - Nunmuldo Akkawo (feat. Hyun A from 4minute).flac Dec 29 19:41:21 volumio volumio[18512]: info: Using cached record of: music-library/NAS/Kingcatnas2/4minute (lossless)/Navi - Nunmuldo Akkawo (feat. Hyun A from 4minute).flac Dec 29 19:41:21 volumio volumio[18512]: info: Adding Item to queue: music-library/NAS/Kingcatnas2/4minute (lossless)/[2010.04.23] For Muzik For Asia (Special Edition) Dec 29 19:41:21 volumio volumio[18512]: info: Exploding uri music-library/NAS/Kingcatnas2/4minute (lossless)/[2010.04.23] For Muzik For Asia (Special Edition) in service mpd Dec 29 19:41:21 volumio volumio[18512]: info: Adding Item to queue: music-library/NAS/Kingcatnas2/4minute (lossless)/[2010.05.27] Hit Your Heart Dec 29 19:41:21 volumio volumio[18512]: info: Exploding uri music-library/NAS/Kingcatnas2/4minute (lossless)/[2010.05.27] Hit Your Heart in service mpd Dec 29 19:41:21 volumio volumio[18512]: info: Adding Item to queue: music-library/NAS/Kingcatnas2/4minute (lossless)/[2010.12.15] DIAMOND Dec 29 19:41:21 volumio volumio[18512]: info: Exploding uri music-library/NAS/Kingcatnas2/4minute (lossless)/[2010.12.15] DIAMOND in service mpd Dec 29 19:41:21 volumio volumio[18512]: info: Adding Item to queue: music-library/NAS/Kingcatnas2/4minute (lossless)/[2011.04.05] 4Minutes Left Dec 29 19:41:21 volumio volumio[18512]: info: Exploding uri music-library/NAS/Kingcatnas2/4minute (lossless)/[2011.04.05] 4Minutes Left in service mpd Dec 29 19:41:21 volumio volumio[18512]: info: Adding Item to queue: music-library/NAS/Kingcatnas2/4minute (lossless)/[2012.04.09] Volume Up Dec 29 19:41:21 volumio volumio[18512]: info: Exploding uri music-library/NAS/Kingcatnas2/4minute (lossless)/[2012.04.09] Volume Up in service mpd Dec 29 19:41:21 volumio volumio[18512]: info: Adding Item to queue: music-library/NAS/Kingcatnas2/4minute (lossless)/[2012.09.26] Best Of 4Minute Dec 29 19:41:21 volumio volumio[18512]: info: Exploding uri music-library/NAS/Kingcatnas2/4minute (lossless)/[2012.09.26] Best Of 4Minute in service mpd Dec 29 19:41:21 volumio volumio[18512]: info: Adding Item to queue: music-library/NAS/Kingcatnas2/4minute (lossless)/[2013.01.17] Harvest Moon Dec 29 19:41:21 volumio volumio[18512]: info: Exploding uri music-library/NAS/Kingcatnas2/4minute (lossless)/[2013.01.17] Harvest Moon in service mpd Dec 29 19:41:21 volumio volumio[18512]: info: Adding Item to queue: music-library/NAS/Kingcatnas2/4minute (lossless)/[2013.04.26] Name is 4minute Dec 29 19:41:21 volumio volumio[18512]: info: Exploding uri music-library/NAS/Kingcatnas2/4minute (lossless)/[2013.04.26] Name is 4minute in service mpd Dec 29 19:41:21 volumio volumio[18512]: info: Adding Item to queue: music-library/NAS/Kingcatnas2/4minute (lossless)/[2014.03.16] 4minute World Dec 29 19:41:21 volumio volumio[18512]: info: Exploding uri music-library/NAS/Kingcatnas2/4minute (lossless)/[2014.03.16] 4minute World in service mpd Dec 29 19:41:21 volumio volumio[18512]: info: Adding Item to queue: music-library/NAS/Kingcatnas2/4minute (lossless)/[2015.02.09] CRAZY Dec 29 19:41:21 volumio volumio[18512]: info: Exploding uri music-library/NAS/Kingcatnas2/4minute (lossless)/[2015.02.09] CRAZY in service mpd Dec 29 19:41:21 volumio volumio[18512]: info: CoreStateMachine::stop Dec 29 19:41:21 volumio volumio[18512]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 19:41:21 volumio volumio[18512]: info: CoreStateMachine::play index undefined Dec 29 19:41:21 volumio volumio[18512]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 19:41:21 volumio volumio[18512]: info: CorePlayQueue::getTrack 0 Dec 29 19:41:21 volumio volumio[18512]: info: CoreStateMachine::startPlaybackTimer Dec 29 19:41:21 volumio volumio[18512]: info: CorePlayQueue::getTrack 0 Dec 29 19:41:21 volumio volumio[18512]: verbose: ControllerMpd::clearAddPlayTracks NAS/Kingcatnas2/4minute (lossless)/Lee Hyori - Bring It Back (feat. Bekah, Jiyoon).flac Dec 29 19:41:21 volumio volumio[18512]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 19:41:22 volumio volumio[18512]: info: ALBUMART /albumart?cacheid=128&web=4minute/For%20Muzik%20For%20Asia/extralarge&path=%2Fmnt%2FNAS%2FKingcatnas2%2F4minute%20(lossless)%2F%5B2010.04.23%5D%20For%20Muzik%20For%20Asia%20(Special%20Edition)%2F01.%20For%20Muzik.flac&metadata=false Dec 29 19:41:22 volumio volumio[18512]: info: URI /mnt/NAS/Kingcatnas2/4minute (lossless)/[2010.04.23] For Muzik For Asia (Special Edition)/01. For Muzik.flac Dec 29 19:41:22 volumio volumio[18512]: info: ALBUMART /albumart?cacheid=128&web=4minute/For%20Muzik%20For%20Asia/extralarge&path=%2Fmnt%2FNAS%2FKingcatnas2%2F4minute%20(lossless)%2F%5B2010.04.23%5D%20For%20Muzik%20For%20Asia%20(Special%20Edition)%2F02.%20Muzik.flac&metadata=false Dec 29 19:41:22 volumio volumio[18512]: info: URI /mnt/NAS/Kingcatnas2/4minute (lossless)/[2010.04.23] For Muzik For Asia (Special Edition)/02. Muzik.flac Dec 29 19:41:22 volumio volumio[18512]: info: ALBUMART /albumart?cacheid=128&web=4minute/For%20Muzik%20For%20Asia/extralarge&path=%2Fmnt%2FNAS%2FKingcatnas2%2F4minute%20(lossless)%2F%5B2010.04.23%5D%20For%20Muzik%20For%20Asia%20(Special%20Edition)%2F03.%20Hot%20Issue.flac&metadata=false Dec 29 19:41:22 volumio volumio[18512]: info: URI /mnt/NAS/Kingcatnas2/4minute (lossless)/[2010.04.23] For Muzik For Asia (Special Edition)/03. Hot Issue.flac Dec 29 19:41:22 volumio volumio[18512]: info: ALBUMART /albumart?cacheid=128&web=4minute/For%20Muzik%20For%20Asia/extralarge&path=%2Fmnt%2FNAS%2FKingcatnas2%2F4minute%20(lossless)%2F%5B2010.04.23%5D%20For%20Muzik%20For%20Asia%20(Special%20Edition)%2F04.%20What%20A%20Girl%20Wants.flac&metadata=false Dec 29 19:41:22 volumio volumio[18512]: info: URI /mnt/NAS/Kingcatnas2/4minute (lossless)/[2010.04.23] For Muzik For Asia (Special Edition)/04. What A Girl Wants.flac Dec 29 19:41:22 volumio volumio[18512]: info: ALBUMART /albumart?cacheid=128&web=4minute/For%20Muzik%20For%20Asia/extralarge&path=%2Fmnt%2FNAS%2FKingcatnas2%2F4minute%20(lossless)%2F%5B2010.04.23%5D%20For%20Muzik%20For%20Asia%20(Special%20Edition)%2F05.%20Funny.flac&metadata=false Dec 29 19:41:22 volumio volumio[18512]: info: URI /mnt/NAS/Kingcatnas2/4minute (lossless)/[2010.04.23] For Muzik For Asia (Special Edition)/05. Funny.flac Dec 29 19:41:22 volumio volumio[18512]: info: ALBUMART /albumart?cacheid=128&web=4minute/For%20Muzik%20For%20Asia/extralarge&path=%2Fmnt%2FNAS%2FKingcatnas2%2F4minute%20(lossless)%2F%5B2010.04.23%5D%20For%20Muzik%20For%20Asia%20(Special%20Edition)%2F06.%20Won't%20Give%20You.flac&metadata=false Dec 29 19:41:22 volumio volumio[18512]: info: URI /mnt/NAS/Kingcatnas2/4minute (lossless)/[2010.04.23] For Muzik For Asia (Special Edition)/06. Won't Give You.flac Dec 29 19:41:22 volumio volumio[18512]: info: ALBUMART /albumart?cacheid=128&web=4minute/For%20Muzik%20For%20Asia/extralarge&path=%2Fmnt%2FNAS%2FKingcatnas2%2F4minute%20(lossless)%2F%5B2010.04.23%5D%20For%20Muzik%20For%20Asia%20(Special%20Edition)%2F07.%20Hot%20Issue%20(Remix).flac&metadata=false Dec 29 19:41:22 volumio volumio[18512]: info: URI /mnt/NAS/Kingcatnas2/4minute (lossless)/[2010.04.23] For Muzik For Asia (Special Edition)/07. Hot Issue (Remix).flac Dec 29 19:41:22 volumio volumio[18512]: info: ALBUMART /albumart?cacheid=128&web=4minute/For%20Muzik%20For%20Asia/extralarge&path=%2Fmnt%2FNAS%2FKingcatnas2%2F4minute%20(lossless)%2F%5B2010.04.23%5D%20For%20Muzik%20For%20Asia%20(Special%20Edition)%2F08.%20Change%20(Hyuna%20feat.%20Jun%20Hyung).flac&metadata=false Dec 29 19:41:22 volumio volumio[18512]: info: URI /mnt/NAS/Kingcatnas2/4minute (lossless)/[2010.04.23] For Muzik For Asia (Special Edition)/08. Change (Hyuna feat. Jun Hyung).flac Dec 29 19:41:22 volumio volumio[18512]: info: ALBUMART /albumart?cacheid=128&web=4minute/For%20Muzik%20For%20Asia/extralarge&path=%2Fmnt%2FNAS%2FKingcatnas2%2F4minute%20(lossless)%2F%5B2010.04.23%5D%20For%20Muzik%20For%20Asia%20(Special%20Edition)%2F09.%20Heard%20'Em%20All%20(feat.%20Amerie).flac&metadata=false Dec 29 19:41:22 volumio volumio[18512]: info: URI /mnt/NAS/Kingcatnas2/4minute (lossless)/[2010.04.23] For Muzik For Asia (Special Edition)/09. Heard 'Em All (feat. Amerie).flac Dec 29 19:41:22 volumio volumio[18512]: info: ALBUMART /albumart?cacheid=128&web=4minute/For%20Muzik%20For%20Asia/extralarge&path=%2Fmnt%2FNAS%2FKingcatnas2%2F4minute%20(lossless)%2F%5B2010.04.23%5D%20For%20Muzik%20For%20Asia%20(Special%20Edition)%2FFor%20Muzik%20For%20Asia.cue&metadata=false Dec 29 19:41:22 volumio volumio[18512]: info: URI /mnt/NAS/Kingcatnas2/4minute (lossless)/[2010.04.23] For Muzik For Asia (Special Edition)/For Muzik For Asia.cue Dec 29 19:41:22 volumio volumio[18512]: info: ALBUMART /albumart?cacheid=128&web=4minute/For%20Muzik%20For%20Asia/extralarge&path=%2Fmnt%2FNAS%2FKingcatnas2%2F4minute%20(lossless)%2F%5B2010.04.23%5D%20For%20Muzik%20For%20Asia%20(Special%20Edition)%2FFor%20Muzik%20For%20Asia.cue&metadata=false Dec 29 19:41:22 volumio volumio[18512]: info: URI /mnt/NAS/Kingcatnas2/4minute (lossless)/[2010.04.23] For Muzik For Asia (Special Edition)/For Muzik For Asia.cue Dec 29 19:41:22 volumio volumio[18512]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 19:41:22 volumio volumio[18512]: Error: Unable to resolve or reject the same promise twice Dec 29 19:41:22 volumio volumio[18512]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 29 19:41:22 volumio volumio[18512]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Dec 29 19:41:22 volumio volumio[18512]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 29 19:41:22 volumio volumio[18512]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 29 19:41:22 volumio volumio[18512]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 29 19:41:22 volumio volumio[18512]: at Socket.emit (node:events:514:28) Dec 29 19:41:22 volumio volumio[18512]: at addChunk (node:internal/streams/readable:343:12) Dec 29 19:41:22 volumio volumio[18512]: at readableAddChunk (node:internal/streams/readable:312:11) Dec 29 19:41:22 volumio volumio[18512]: at Readable.push (node:internal/streams/readable:253:10) Dec 29 19:41:22 volumio volumio[18512]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Dec 29 19:41:22 volumio volumio[18512]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 19:41:22 volumio sudo[19408]: volumio : unable to resolve host volumio: System error Dec 29 19:41:22 volumio sudo[19408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-29 19:40' Dec 29 19:41:22 volumio sudo[19408]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"