Jan 29 02:44:00 volumio sudo[1936]: pam_unix(sudo:session): session closed for user root Jan 29 02:44:00 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jan 29 02:44:00 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Jan 29 02:44:00 volumio volumio[1098]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jan 29 02:44:01 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 29 02:44:05 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 29 02:44:09 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 29 02:44:11 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Jan 29 02:44:11 volumio volumio[1098]: info: Adding a new share Jan 29 02:44:11 volumio volumio[1098]: info: Checking key NasMounts.a41bf3a5-2ad6-4ddb-b34e-019630dbfd96 Jan 29 02:44:11 volumio volumio[1098]: info: No correspondence found in configuration for share nas_music on IP 192.168.0.9 Jan 29 02:44:11 volumio volumio[1098]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.0.9 -N -m SMB3_11 Jan 29 02:44:12 volumio volumio[1098]: info: Error browsing device nas_music for shares: Command failed: smbclient --debuglevel=4 -L 192.168.0.9 -N -m SMB3_11 Jan 29 02:44:12 volumio volumio[1098]: lp_load_ex: refreshing parameters Jan 29 02:44:12 volumio volumio[1098]: Initialising global parameters Jan 29 02:44:12 volumio volumio[1098]: Processing section "[global]" Jan 29 02:44:12 volumio volumio[1098]: doing parameter netbios name = Volumio Jan 29 02:44:12 volumio volumio[1098]: doing parameter server string = Volumio Audiophile Music Player Jan 29 02:44:12 volumio volumio[1098]: doing parameter workgroup = WORKGROUP Jan 29 02:44:12 volumio volumio[1098]: doing parameter security = user Jan 29 02:44:12 volumio volumio[1098]: doing parameter map to guest = Bad User Jan 29 02:44:12 volumio volumio[1098]: doing parameter encrypt passwords = yes Jan 29 02:44:12 volumio volumio[1098]: lpcfg_do_global_parameter: WARNING: The "encrypt passwords" option is deprecated Jan 29 02:44:12 volumio volumio[1098]: doing parameter wins support = yes Jan 29 02:44:12 volumio volumio[1098]: doing parameter local master = no Jan 29 02:44:12 volumio volumio[1098]: doing parameter preferred master = no Jan 29 02:44:12 volumio volumio[1098]: doing parameter os level = 30 Jan 29 02:44:12 volumio volumio[1098]: doing parameter fruit:model = AirPort Jan 29 02:44:12 volumio volumio[1098]: pm_process() returned Yes Jan 29 02:44:12 volumio volumio[1098]: added interface eth0 ip=192.168.0.123 bcast=192.168.0.255 netmask=255.255.255.0 Jan 29 02:44:12 volumio volumio[1098]: Client started (version 4.17.8-Raspbian). Jan 29 02:44:12 volumio volumio[1098]: Connecting to 192.168.0.9 at port 445 Jan 29 02:44:12 volumio volumio[1098]: session request ok Jan 29 02:44:12 volumio volumio[1098]: negotiated dialect[SMB3_11] against server[192.168.0.9] Jan 29 02:44:12 volumio volumio[1098]: GENSEC backend 'gssapi_spnego' registered Jan 29 02:44:12 volumio volumio[1098]: GENSEC backend 'gssapi_krb5' registered Jan 29 02:44:12 volumio volumio[1098]: GENSEC backend 'gssapi_krb5_sasl' registered Jan 29 02:44:12 volumio volumio[1098]: GENSEC backend 'spnego' registered Jan 29 02:44:12 volumio volumio[1098]: GENSEC backend 'schannel' registered Jan 29 02:44:12 volumio volumio[1098]: GENSEC backend 'ncalrpc_as_system' registered Jan 29 02:44:12 volumio volumio[1098]: GENSEC backend 'sasl-EXTERNAL' registered Jan 29 02:44:12 volumio volumio[1098]: GENSEC backend 'ntlmssp' registered Jan 29 02:44:12 volumio volumio[1098]: GENSEC backend 'ntlmssp_resume_ccache' registered Jan 29 02:44:12 volumio volumio[1098]: GENSEC backend 'http_basic' registered Jan 29 02:44:12 volumio volumio[1098]: GENSEC backend 'http_ntlm' registered Jan 29 02:44:12 volumio volumio[1098]: GENSEC backend 'http_negotiate' registered Jan 29 02:44:12 volumio volumio[1098]: GENSEC backend 'krb5' registered Jan 29 02:44:12 volumio volumio[1098]: GENSEC backend 'fake_gssapi_krb5' registered Jan 29 02:44:12 volumio volumio[1098]: Cannot do GSE to an IP address Jan 29 02:44:12 volumio volumio[1098]: Failed to start GENSEC client mech gse_krb5: NT_STATUS_INVALID_PARAMETER Jan 29 02:44:12 volumio volumio[1098]: Got challenge flags: Jan 29 02:44:12 volumio volumio[1098]: Got NTLMSSP neg_flags=0x628a8215 Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_UNICODE Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_REQUEST_TARGET Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_SIGN Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_NTLM Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_TARGET_TYPE_SERVER Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_TARGET_INFO Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_VERSION Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_128 Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_KEY_EXCH Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP: Set final flags: Jan 29 02:44:12 volumio volumio[1098]: Got NTLMSSP neg_flags=0x62008215 Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_UNICODE Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_REQUEST_TARGET Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_SIGN Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_NTLM Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_VERSION Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_128 Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_KEY_EXCH Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP Sign/Seal - Initialising with flags: Jan 29 02:44:12 volumio volumio[1098]: Got NTLMSSP neg_flags=0x62008215 Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_UNICODE Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_REQUEST_TARGET Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_SIGN Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_NTLM Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_VERSION Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_128 Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_KEY_EXCH Jan 29 02:44:12 volumio volumio[1098]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. Jan 29 02:44:12 volumio volumio[1098]: Got challenge flags: Jan 29 02:44:12 volumio volumio[1098]: Got NTLMSSP neg_flags=0x628a8215 Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_UNICODE Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_REQUEST_TARGET Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_SIGN Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_NTLM Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_TARGET_TYPE_SERVER Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_TARGET_INFO Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_VERSION Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_128 Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_KEY_EXCH Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP: Set final flags: Jan 29 02:44:12 volumio volumio[1098]: Got NTLMSSP neg_flags=0x62008a15 Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_UNICODE Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_REQUEST_TARGET Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_SIGN Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_NTLM Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_ANONYMOUS Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_VERSION Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_128 Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_KEY_EXCH Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP Sign/Seal - Initialising with flags: Jan 29 02:44:12 volumio volumio[1098]: Got NTLMSSP neg_flags=0x62008a15 Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_UNICODE Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_REQUEST_TARGET Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_SIGN Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_NTLM Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_ANONYMOUS Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_VERSION Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_128 Jan 29 02:44:12 volumio volumio[1098]: NTLMSSP_NEGOTIATE_KEY_EXCH Jan 29 02:44:12 volumio volumio[1098]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. Jan 29 02:44:12 volumio volumio[1098]: info: Detected device nas_music with version null Jan 29 02:44:12 volumio sudo[1980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=sky,password=aA1234321,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.9/music /mnt/NAS/nas_music Jan 29 02:44:12 volumio sudo[1980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 29 02:44:12 volumio kernel: CIFS: Attempting to mount //192.168.0.9/music Jan 29 02:44:12 volumio sudo[1980]: pam_unix(sudo:session): session closed for user root Jan 29 02:44:12 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Jan 29 02:44:12 volumio volumio[1098]: info: Jan 29 02:44:12 volumio volumio[1098]: ---------------------------- MPD announces state update: update Jan 29 02:44:12 volumio volumio[1098]: info: ControllerMpd::getState Jan 29 02:44:12 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Jan 29 02:44:12 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Jan 29 02:44:12 volumio volumio[1098]: info: Jan 29 02:44:12 volumio volumio[1098]: ---------------------------- MPD announces state update: update Jan 29 02:44:12 volumio volumio[1098]: info: ControllerMpd::getState Jan 29 02:44:12 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Jan 29 02:44:12 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Jan 29 02:44:12 volumio volumio[1098]: info: Jan 29 02:44:12 volumio volumio[1098]: ---------------------------- MPD announces state update: update Jan 29 02:44:12 volumio volumio[1098]: info: ControllerMpd::getState Jan 29 02:44:12 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Jan 29 02:44:12 volumio volumio[1098]: info: sendMpdCommand status took 26 milliseconds Jan 29 02:44:12 volumio volumio[1098]: info: sendMpdCommand status took 22 milliseconds Jan 29 02:44:12 volumio volumio[1098]: info: sendMpdCommand status took 14 milliseconds Jan 29 02:44:12 volumio volumio[1098]: info: sendMpdCommand status took 13 milliseconds Jan 29 02:44:12 volumio volumio[1098]: verbose: ControllerMpd::parseState Jan 29 02:44:12 volumio volumio[1098]: info: Command Router : Notfying DB Updatetrue Jan 29 02:44:12 volumio volumio[1098]: info: CoreCommandRouter::Close All Modals sent Jan 29 02:44:12 volumio volumio[1098]: verbose: ControllerMpd::parseState Jan 29 02:44:12 volumio volumio[1098]: verbose: ControllerMpd::parseState Jan 29 02:44:12 volumio volumio[1098]: info: Command Router : Notfying DB Updatetrue Jan 29 02:44:12 volumio volumio[1098]: info: CoreCommandRouter::Close All Modals sent Jan 29 02:44:12 volumio volumio[1098]: verbose: ControllerMpd::parseState Jan 29 02:44:12 volumio volumio[1098]: info: ControllerMpd::pushState Jan 29 02:44:12 volumio volumio[1098]: info: CoreCommandRouter::servicePushState Jan 29 02:44:12 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:44:12 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jan 29 02:44:12 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:44:12 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jan 29 02:44:12 volumio volumio[1098]: 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} Jan 29 02:44:12 volumio volumio[1098]: verbose: CURRENT POSITION 0 Jan 29 02:44:12 volumio volumio[1098]: info: CoreStateMachine::syncState stateService stop Jan 29 02:44:12 volumio volumio[1098]: info: CoreStateMachine::syncState currentStatus stop Jan 29 02:44:12 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:44:12 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jan 29 02:44:12 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 29 02:44:12 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:44:12 volumio volumio[1098]: info: No code Jan 29 02:44:12 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:44:12 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jan 29 02:44:12 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:44:12 volumio volumio[1098]: info: ControllerMpd::pushState Jan 29 02:44:12 volumio volumio[1098]: info: CoreCommandRouter::servicePushState Jan 29 02:44:12 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:44:12 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jan 29 02:44:12 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:44:12 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jan 29 02:44:12 volumio volumio[1098]: 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} Jan 29 02:44:12 volumio volumio[1098]: verbose: CURRENT POSITION 0 Jan 29 02:44:12 volumio volumio[1098]: info: CoreStateMachine::syncState stateService stop Jan 29 02:44:12 volumio volumio[1098]: info: CoreStateMachine::syncState currentStatus stop Jan 29 02:44:12 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:44:12 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jan 29 02:44:12 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:44:12 volumio volumio[1098]: info: No code Jan 29 02:44:12 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:44:12 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jan 29 02:44:12 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:44:13 volumio volumio[1098]: info: ------------------------------ 430ms Jan 29 02:44:13 volumio volumio[1098]: info: ------------------------------ 418ms Jan 29 02:44:13 volumio volumio[1098]: info: sendMpdCommand status took 435 milliseconds Jan 29 02:44:13 volumio volumio[1098]: info: sendMpdCommand status took 428 milliseconds Jan 29 02:44:13 volumio volumio[1098]: verbose: ControllerMpd::parseState Jan 29 02:44:13 volumio volumio[1098]: info: Command Router : Notfying DB Updatetrue Jan 29 02:44:13 volumio volumio[1098]: info: CoreCommandRouter::Close All Modals sent Jan 29 02:44:13 volumio volumio[1098]: verbose: ControllerMpd::parseState Jan 29 02:44:13 volumio volumio[1098]: info: ControllerMpd::pushState Jan 29 02:44:13 volumio volumio[1098]: info: CoreCommandRouter::servicePushState Jan 29 02:44:13 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:44:13 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jan 29 02:44:13 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:44:13 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jan 29 02:44:13 volumio volumio[1098]: 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} Jan 29 02:44:13 volumio volumio[1098]: verbose: CURRENT POSITION 0 Jan 29 02:44:13 volumio volumio[1098]: info: CoreStateMachine::syncState stateService stop Jan 29 02:44:13 volumio volumio[1098]: info: CoreStateMachine::syncState currentStatus stop Jan 29 02:44:13 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:44:13 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jan 29 02:44:13 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:44:13 volumio volumio[1098]: info: No code Jan 29 02:44:13 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:44:13 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jan 29 02:44:13 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:44:13 volumio volumio[1098]: info: ------------------------------ 628ms Jan 29 02:44:13 volumio volumio[1098]: info: [1769625853471] Database update started Jan 29 02:44:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 29 02:44:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 29 02:44:18 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jan 29 02:44:18 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jan 29 02:44:20 volumio volumio[1098]: info: CoreCommandRouter::volumioGetQueue Jan 29 02:44:20 volumio volumio[1098]: info: CoreStateMachine::getQueue Jan 29 02:44:20 volumio volumio[1098]: info: CorePlayQueue::getQueue Jan 29 02:44:21 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 29 02:44:21 volumio volumio[1098]: info: CURURI: playlists Jan 29 02:44:21 volumio volumio[1098]: info: Listing playlists Jan 29 02:44:21 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:44:25 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 29 02:44:25 volumio volumio[1098]: info: CURURI: music-library Jan 29 02:44:25 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:44:26 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 29 02:44:26 volumio volumio[1098]: info: CURURI: music-library/NAS Jan 29 02:44:26 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:44:27 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 29 02:44:27 volumio volumio[1098]: info: CURURI: music-library/NAS/nas_music Jan 29 02:44:27 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:44:31 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jan 29 02:44:31 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jan 29 02:44:32 volumio volumio[1098]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 29 02:44:32 volumio volumio[1098]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 29 02:44:32 volumio volumio[1098]: info: Retrieving Cloud Streaming UI Jan 29 02:44:32 volumio volumio[1098]: info: Getting Tidal Cloud Configuration Jan 29 02:44:32 volumio volumio[1098]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 29 02:44:32 volumio volumio[1098]: info: Getting Qobuz Cloud Configuration Jan 29 02:44:32 volumio volumio[1098]: info: Asking plugin for UI Config Jan 29 02:44:32 volumio volumio[1098]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 29 02:44:32 volumio volumio[1098]: info: Getting Spotify Cloud Configuration Jan 29 02:44:32 volumio volumio[1098]: info: Asking plugin for UI Config Jan 29 02:44:32 volumio volumio[1098]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 29 02:44:32 volumio volumio[1098]: info: Saving Spotify Acccount Jan 29 02:44:32 volumio volumio[1098]: info: Got Tidal Cloud Configuration Jan 29 02:44:32 volumio volumio[1098]: info: Got it Jan 29 02:44:32 volumio volumio[1098]: info: Got it Jan 29 02:44:32 volumio volumio[1098]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 29 02:44:32 volumio volumio[1098]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 29 02:44:32 volumio volumio[1098]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jan 29 02:44:32 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 29 02:44:32 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 29 02:44:32 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 29 02:44:32 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 29 02:44:32 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 29 02:44:32 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 29 02:44:32 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 29 02:44:32 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 29 02:44:32 volumio volumio[1098]: info: CoreCommandRouter::volumioGetBrowseSources Jan 29 02:44:32 volumio volumio[1098]: info: CoreCommandRouter::volumioGetBrowseSources Jan 29 02:44:32 volumio volumio[1098]: info: CoreCommandRouter::volumioGetBrowseSources Jan 29 02:44:33 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 29 02:44:33 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 29 02:44:37 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 29 02:44:41 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 29 02:44:45 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 29 02:44:49 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 29 02:44:51 volumio volumio[1098]: info: CoreCommandRouter::volumioGetState Jan 29 02:44:51 volumio volumio[1098]: info: CorePlayQueue::getTrack 0 Jan 29 02:44:54 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 29 02:44:54 volumio volumio[1098]: info: CURURI: music-library Jan 29 02:44:54 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:44:54 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 29 02:44:54 volumio volumio[1098]: info: CURURI: music-library/NAS Jan 29 02:44:55 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:44:55 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 29 02:44:55 volumio volumio[1098]: info: CURURI: music-library/NAS/nas_music Jan 29 02:44:55 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:44:58 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 29 02:44:58 volumio volumio[1098]: info: CURURI: music-library/NAS/nas_music Jan 29 02:44:58 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:44:59 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 29 02:44:59 volumio volumio[1098]: info: CURURI: music-library/INTERNAL Jan 29 02:45:00 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:45:06 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 29 02:45:06 volumio volumio[1098]: info: CURURI: music-library/INTERNAL Jan 29 02:45:06 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:45:09 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 29 02:45:09 volumio volumio[1098]: info: CURURI: music-library/INTERNAL/ir_controller Jan 29 02:45:09 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:45:11 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 29 02:45:11 volumio volumio[1098]: info: CURURI: music-library/INTERNAL/PeppySpectrum Jan 29 02:45:11 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:45:13 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 29 02:45:13 volumio volumio[1098]: info: CURURI: music-library/INTERNAL/PeppyMeterBasic Jan 29 02:45:13 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:45:15 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 29 02:45:15 volumio volumio[1098]: info: CURURI: music-library/NAS Jan 29 02:45:15 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:45:16 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 29 02:45:16 volumio volumio[1098]: info: CURURI: music-library/NAS/nas_music Jan 29 02:45:16 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:45:26 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::volumioAddQueueItems Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::addQueueItems Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::addQueueItems Jan 29 02:45:26 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:45:26 volumio volumio[1098]: info: Adding Item to queue: music-library/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC Jan 29 02:45:26 volumio volumio[1098]: info: Exploding uri music-library/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC in service mpd Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F01%20John%20Williams%20-%20Main%20Title%20and%20The%20Attack%20on%20the%20Ja.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/01 John Williams - Main Title and The Attack on the Ja.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F02%20John%20Williams%20-%20The%20Scavenger.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/02 John Williams - The Scavenger.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F03%20John%20Williams%20-%20I%20Can%20Fly%20Anything.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/03 John Williams - I Can Fly Anything.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F04%20John%20Williams%20-%20Rey%20Meets%20BB-8.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/04 John Williams - Rey Meets BB-8.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F05%20John%20Williams%20-%20Follow%20Me.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/05 John Williams - Follow Me.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F06%20John%20Williams%20-%20Rey's%20Theme.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/06 John Williams - Rey's Theme.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F07%20John%20Williams%20-%20The%20Falcon.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/07 John Williams - The Falcon.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F08%20John%20Williams%20-%20That%20Girl%20with%20the%20Staff.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/08 John Williams - That Girl with the Staff.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F09%20John%20Williams%20-%20The%20Rathtars!.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/09 John Williams - The Rathtars!.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F10%20John%20Williams%20-%20Finn's%20Confession.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/10 John Williams - Finn's Confession.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F11%20John%20Williams%20-%20Maz's%20Counsel.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/11 John Williams - Maz's Counsel.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F12%20John%20Williams%20-%20The%20Starkiller.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/12 John Williams - The Starkiller.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F13%20John%20Williams%20-%20Kylo%20Ren%20Arrives%20at%20the%20Battle.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/13 John Williams - Kylo Ren Arrives at the Battle.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F14%20John%20Williams%20-%20The%20Abduction.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/14 John Williams - The Abduction.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F15%20John%20Williams%20-%20Han%20and%20Leia.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/15 John Williams - Han and Leia.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F16%20John%20Williams%20-%20March%20of%20the%20Resistance.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/16 John Williams - March of the Resistance.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F17%20John%20Williams%20-%20Snoke.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/17 John Williams - Snoke.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F18%20John%20Williams%20-%20On%20the%20Inside.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/18 John Williams - On the Inside.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F19%20John%20Williams%20-%20Torn%20Apart.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/19 John Williams - Torn Apart.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F20%20John%20Williams%20-%20The%20Ways%20of%20the%20Force.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/20 John Williams - The Ways of the Force.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F21%20John%20Williams%20-%20Scherzo%20for%20X-Wings.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/21 John Williams - Scherzo for X-Wings.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F22%20John%20Williams%20-%20Farewell%20and%20The%20Trip.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/22 John Williams - Farewell and The Trip.flac Jan 29 02:45:26 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=John%20Williams/Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2FJohn%20Williams%20-%20Star%20Wars%EF%BC%9AThe%20Force%20Awakens%20%5BSoundtrack%5D%20%5B24bit-192KHz%5D%20-%202015%20FLAC%2F23%20John%20Williams%20-%20The%20Jedi%20Steps%20and%20Finale.flac&metadata=false Jan 29 02:45:26 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/23 John Williams - The Jedi Steps and Finale.flac Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::volumioPushQueue Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::saveQueue Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::updateTrackBlock Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrackBlock Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::volumioPlay Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::play index 21 Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::stop Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::play index undefined Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrack 21 Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::startPlaybackTimer Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrack 21 Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::clearAddPlayTracks NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/01 John Williams - Main Title and The Attack on the Ja.flac Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand stop Jan 29 02:45:26 volumio volumio[1098]: info: sendMpdCommand stop took 1 milliseconds Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand clear Jan 29 02:45:26 volumio volumio[1098]: info: Jan 29 02:45:26 volumio volumio[1098]: ---------------------------- MPD announces system playlist update Jan 29 02:45:26 volumio volumio[1098]: info: Ignoring MPD Status Update Jan 29 02:45:26 volumio volumio[1098]: info: sendMpdCommand clear took 77 milliseconds Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand add "NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/01 John Williams - Main Title and The Attack on the Ja.flac" Jan 29 02:45:26 volumio volumio[1098]: info: Jan 29 02:45:26 volumio volumio[1098]: ---------------------------- MPD announces system playlist update Jan 29 02:45:26 volumio volumio[1098]: info: Ignoring MPD Status Update Jan 29 02:45:26 volumio volumio[1098]: info: Jan 29 02:45:26 volumio volumio[1098]: ---------------------------- MPD announces system playlist update Jan 29 02:45:26 volumio volumio[1098]: info: Ignoring MPD Status Update Jan 29 02:45:26 volumio volumio[1098]: error: updateQueue error: null Jan 29 02:45:26 volumio volumio[1098]: info: Jan 29 02:45:26 volumio volumio[1098]: ---------------------------- MPD announces system playlist update Jan 29 02:45:26 volumio volumio[1098]: info: Ignoring MPD Status Update Jan 29 02:45:26 volumio volumio[1098]: info: ------------------------------ 36ms Jan 29 02:45:26 volumio volumio[1098]: info: sendMpdCommand add "NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/01 John Williams - Main Title and The Attack on the Ja.flac" took 3 milliseconds Jan 29 02:45:26 volumio volumio[1098]: info: ------------------------------ 3ms Jan 29 02:45:26 volumio volumio[1098]: info: ------------------------------ 3ms Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand play Jan 29 02:45:26 volumio volumio[1098]: info: Jan 29 02:45:26 volumio volumio[1098]: ---------------------------- MPD announces system playlist update Jan 29 02:45:26 volumio volumio[1098]: info: Ignoring MPD Status Update Jan 29 02:45:26 volumio volumio[1098]: info: Jan 29 02:45:26 volumio volumio[1098]: ---------------------------- MPD announces system playlist update Jan 29 02:45:26 volumio volumio[1098]: info: Ignoring MPD Status Update Jan 29 02:45:26 volumio volumio[1098]: info: Jan 29 02:45:26 volumio volumio[1098]: ---------------------------- MPD announces state update: player Jan 29 02:45:26 volumio volumio[1098]: info: Jan 29 02:45:26 volumio volumio[1098]: ---------------------------- MPD announces state update: player Jan 29 02:45:26 volumio volumio[1098]: info: ------------------------------ 80ms Jan 29 02:45:26 volumio volumio[1098]: info: sendMpdCommand play took 81 milliseconds Jan 29 02:45:26 volumio volumio[1098]: info: ------------------------------ 81ms Jan 29 02:45:26 volumio volumio[1098]: info: ControllerMpd::getState Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Jan 29 02:45:26 volumio volumio[1098]: info: ------------------------------ 81ms Jan 29 02:45:26 volumio volumio[1098]: info: ControllerMpd::getState Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Jan 29 02:45:26 volumio volumio[1098]: info: Jan 29 02:45:26 volumio volumio[1098]: ---------------------------- MPD announces state update: player Jan 29 02:45:26 volumio volumio[1098]: info: ControllerMpd::getState Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Jan 29 02:45:26 volumio volumio[1098]: info: Jan 29 02:45:26 volumio volumio[1098]: ---------------------------- MPD announces state update: player Jan 29 02:45:26 volumio volumio[1098]: info: ControllerMpd::getState Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Jan 29 02:45:26 volumio volumio[1098]: info: sendMpdCommand status took 67 milliseconds Jan 29 02:45:26 volumio volumio[1098]: info: sendMpdCommand status took 70 milliseconds Jan 29 02:45:26 volumio volumio[1098]: info: sendMpdCommand status took 41 milliseconds Jan 29 02:45:26 volumio volumio[1098]: info: sendMpdCommand status took 28 milliseconds Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::parseState Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::parseState Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::parseState Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::parseState Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 29 02:45:26 volumio volumio[1098]: info: Jan 29 02:45:26 volumio volumio[1098]: ---------------------------- MPD announces state update: player Jan 29 02:45:26 volumio volumio[1098]: info: ControllerMpd::getState Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Jan 29 02:45:26 volumio volumio[1098]: info: Jan 29 02:45:26 volumio volumio[1098]: ---------------------------- MPD announces state update: player Jan 29 02:45:26 volumio volumio[1098]: info: ControllerMpd::getState Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand status Jan 29 02:45:26 volumio volumio[1098]: info: sendMpdCommand playlistinfo took 72 milliseconds Jan 29 02:45:26 volumio volumio[1098]: info: sendMpdCommand playlistinfo took 69 milliseconds Jan 29 02:45:26 volumio volumio[1098]: info: sendMpdCommand playlistinfo took 60 milliseconds Jan 29 02:45:26 volumio volumio[1098]: info: sendMpdCommand playlistinfo took 59 milliseconds Jan 29 02:45:26 volumio volumio[1098]: info: sendMpdCommand status took 36 milliseconds Jan 29 02:45:26 volumio volumio[1098]: info: sendMpdCommand status took 25 milliseconds Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::parseTrackInfo Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::parseTrackInfo Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::parseTrackInfo Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::parseTrackInfo Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::parseState Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::parseState Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 29 02:45:26 volumio volumio[1098]: info: ControllerMpd::pushState Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::servicePushState Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrack 21 Jan 29 02:45:26 volumio volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":678,"duration":386,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"6771 Kbps","isStreaming":false,"title":"Main Title and The Attack on the Jakku Village","artist":"John Williams","album":"Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz]","uri":"NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/01 John Williams - Main Title and The Attack on the Ja.flac","trackType":"flac"} Jan 29 02:45:26 volumio volumio[1098]: verbose: CURRENT POSITION 21 Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::syncState stateService play Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::syncState currentStatus stop Jan 29 02:45:26 volumio volumio[1098]: info: ControllerMpd::pushState Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::servicePushState Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrack 21 Jan 29 02:45:26 volumio volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":678,"duration":386,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"6771 Kbps","isStreaming":false,"title":"Main Title and The Attack on the Jakku Village","artist":"John Williams","album":"Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz]","uri":"NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/01 John Williams - Main Title and The Attack on the Ja.flac","trackType":"flac"} Jan 29 02:45:26 volumio volumio[1098]: verbose: CURRENT POSITION 21 Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::syncState stateService play Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::syncState currentStatus play Jan 29 02:45:26 volumio volumio[1098]: info: Received an update from plugin. extracting info from payload Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrack 21 Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrack 21 Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:45:26 volumio volumio[1098]: info: ControllerMpd::pushState Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::servicePushState Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrack 21 Jan 29 02:45:26 volumio volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":678,"duration":386,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"6771 Kbps","isStreaming":false,"title":"Main Title and The Attack on the Jakku Village","artist":"John Williams","album":"Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz]","uri":"NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/01 John Williams - Main Title and The Attack on the Ja.flac","trackType":"flac"} Jan 29 02:45:26 volumio volumio[1098]: verbose: CURRENT POSITION 21 Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::syncState stateService play Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::syncState currentStatus play Jan 29 02:45:26 volumio volumio[1098]: info: Received an update from plugin. extracting info from payload Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrack 21 Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrack 21 Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:45:26 volumio volumio[1098]: info: ControllerMpd::pushState Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::servicePushState Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrack 21 Jan 29 02:45:26 volumio volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":762,"duration":386,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"6744 Kbps","isStreaming":false,"title":"Main Title and The Attack on the Jakku Village","artist":"John Williams","album":"Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz]","uri":"NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/01 John Williams - Main Title and The Attack on the Ja.flac","trackType":"flac"} Jan 29 02:45:26 volumio volumio[1098]: verbose: CURRENT POSITION 21 Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::syncState stateService play Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::syncState currentStatus play Jan 29 02:45:26 volumio volumio[1098]: info: Received an update from plugin. extracting info from payload Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrack 21 Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrack 21 Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:45:26 volumio volumio[1098]: info: ------------------------------ 311ms Jan 29 02:45:26 volumio volumio[1098]: info: ------------------------------ 418ms Jan 29 02:45:26 volumio volumio[1098]: info: ------------------------------ 416ms Jan 29 02:45:26 volumio volumio[1098]: info: ------------------------------ 393ms Jan 29 02:45:26 volumio volumio[1098]: info: sendMpdCommand playlistinfo took 257 milliseconds Jan 29 02:45:26 volumio volumio[1098]: info: sendMpdCommand playlistinfo took 257 milliseconds Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::parseTrackInfo Jan 29 02:45:26 volumio volumio[1098]: verbose: ControllerMpd::parseTrackInfo Jan 29 02:45:26 volumio volumio[1098]: info: ControllerMpd::pushState Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::servicePushState Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrack 21 Jan 29 02:45:26 volumio volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":762,"duration":386,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"6744 Kbps","isStreaming":false,"title":"Main Title and The Attack on the Jakku Village","artist":"John Williams","album":"Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz]","uri":"NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/01 John Williams - Main Title and The Attack on the Ja.flac","trackType":"flac"} Jan 29 02:45:26 volumio volumio[1098]: verbose: CURRENT POSITION 21 Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::syncState stateService play Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::syncState currentStatus play Jan 29 02:45:26 volumio volumio[1098]: info: Received an update from plugin. extracting info from payload Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrack 21 Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrack 21 Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:45:26 volumio volumio[1098]: info: ControllerMpd::pushState Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::servicePushState Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrack 21 Jan 29 02:45:26 volumio volumio[1098]: verbose: STATE SERVICE {"status":"play","position":0,"seek":762,"duration":386,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"6744 Kbps","isStreaming":false,"title":"Main Title and The Attack on the Jakku Village","artist":"John Williams","album":"Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz]","uri":"NAS/nas_music/John Williams - Star Wars：The Force Awakens [Soundtrack] [24bit-192KHz] - 2015 FLAC/01 John Williams - Main Title and The Attack on the Ja.flac","trackType":"flac"} Jan 29 02:45:26 volumio volumio[1098]: verbose: CURRENT POSITION 21 Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::syncState stateService play Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::syncState currentStatus play Jan 29 02:45:26 volumio volumio[1098]: info: Received an update from plugin. extracting info from payload Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrack 21 Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:45:26 volumio volumio[1098]: info: CoreStateMachine::pushState Jan 29 02:45:26 volumio volumio[1098]: info: CorePlayQueue::getTrack 21 Jan 29 02:45:26 volumio volumio[1098]: info: CoreCommandRouter::volumioPushState Jan 29 02:45:27 volumio volumio[1098]: info: ------------------------------ 452ms Jan 29 02:45:27 volumio volumio[1098]: info: ------------------------------ 442ms Jan 29 02:45:34 volumio volumio[1098]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 29 02:45:34 volumio volumio[1098]: info: CURURI: music-library/NAS/nas_music/_20170503 Jan 29 02:45:34 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:45:45 volumio volumio[1098]: info: CoreCommandRouter::volumioAddQueueItems Jan 29 02:45:45 volumio volumio[1098]: info: CoreStateMachine::addQueueItems Jan 29 02:45:45 volumio volumio[1098]: info: CorePlayQueue::addQueueItems Jan 29 02:45:45 volumio volumio[1098]: info: Preload queue cleared Jan 29 02:45:45 volumio volumio[1098]: info: Adding Item to queue: music-library/NAS/nas_music/_20170503/Daniel_Gaede(violin)&Xuesu_Liu(piano)-The_Tube_Only_Violin Jan 29 02:45:45 volumio volumio[1098]: info: Exploding uri music-library/NAS/nas_music/_20170503/Daniel_Gaede(violin)&Xuesu_Liu(piano)-The_Tube_Only_Violin in service mpd Jan 29 02:45:45 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=Daniel%20Gaede%20(violin)%20%26%20Xuesu%20Liu%20(piano)/The%20Tube%20Only%20Violin/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2F_20170503%2FDaniel_Gaede(violin)%26Xuesu_Liu(piano)-The_Tube_Only_Violin%2FDaniel_Gaede(violin)%26Xuesu_Liu(piano)-The_Tube_Only_Violin.cue&metadata=false Jan 29 02:45:45 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/_20170503/Daniel_Gaede(violin)&Xuesu_Liu(piano)-The_Tube_Only_Violin/Daniel_Gaede(violin)&Xuesu_Liu(piano)-The_Tube_Only_Violin.cue Jan 29 02:45:45 volumio volumio[1098]: info: ALBUMART /albumart?cacheid=369&web=Daniel%20Gaede%20(violin)%20%26%20Xuesu%20Liu%20(piano)/The%20Tube%20Only%20Violin/extralarge&path=%2Fmnt%2FNAS%2Fnas_music%2F_20170503%2FDaniel_Gaede(violin)%26Xuesu_Liu(piano)-The_Tube_Only_Violin%2FDaniel_Gaede(violin)%26Xuesu_Liu(piano)-The_Tube_Only_Violin.cue&metadata=false Jan 29 02:45:45 volumio volumio[1098]: info: URI /mnt/NAS/nas_music/_20170503/Daniel_Gaede(violin)&Xuesu_Liu(piano)-The_Tube_Only_Violin/Daniel_Gaede(violin)&Xuesu_Liu(piano)-The_Tube_Only_Violin.cue Jan 29 02:45:45 volumio volumio[1098]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 29 02:45:45 volumio volumio[1098]: Error: Unable to resolve or reject the same promise twice Jan 29 02:45:45 volumio volumio[1098]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jan 29 02:45:45 volumio volumio[1098]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Jan 29 02:45:45 volumio volumio[1098]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jan 29 02:45:45 volumio volumio[1098]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jan 29 02:45:45 volumio volumio[1098]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jan 29 02:45:45 volumio volumio[1098]: at Socket.emit (node:events:514:28) Jan 29 02:45:45 volumio volumio[1098]: at addChunk (node:internal/streams/readable:343:12) Jan 29 02:45:45 volumio volumio[1098]: at readableAddChunk (node:internal/streams/readable:312:11) Jan 29 02:45:45 volumio volumio[1098]: at Readable.push (node:internal/streams/readable:253:10) Jan 29 02:45:45 volumio volumio[1098]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jan 29 02:45:45 volumio volumio[1098]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 29 02:45:49 volumio sudo[2276]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-29 02:44' Jan 29 02:45:49 volumio sudo[2276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"