-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-10-16 07:26:13 CEST. -- Oct 16 07:25:01 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 07:25:05 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 07:25:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 07:25:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 16 07:25:09 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 16 07:25:11 volumio volumio[977]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L NAS.local. -m SMB3_11 Oct 16 07:25:11 volumio volumio[977]: info: Error browsing device NAS for shares: Command failed: smbclient --no-pass --debuglevel=0 -L NAS.local. -m SMB3_11 Oct 16 07:25:11 volumio volumio[977]: Unable to initialize messaging context Oct 16 07:25:13 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 07:25:17 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 07:25:21 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 07:25:25 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 07:25:29 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 07:25:33 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 07:25:37 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 07:25:41 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 07:25:45 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 07:25:49 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 07:25:53 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 07:25:54 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Oct 16 07:25:54 volumio volumio[977]: info: Adding a new share Oct 16 07:25:54 volumio volumio[977]: info: Checking key NasMounts.4dd607fa-c8e1-413f-9d0b-a82fa75543d8 Oct 16 07:25:54 volumio volumio[977]: info: Checking key NasMounts.68b377a8-12a6-4e18-aa91-7497f281d7cb Oct 16 07:25:54 volumio volumio[977]: info: Checking key NasMounts.d9b3314b-3bcb-40f2-8f34-a0d386254cfa Oct 16 07:25:54 volumio volumio[977]: info: Checking key NasMounts.fc30abe9-ed44-476a-b066-f19ec98c7fb2 Oct 16 07:25:54 volumio volumio[977]: info: Checking key NasMounts.b0e37b57-54b6-4a4e-8ce7-51ffc9ad2b77 Oct 16 07:25:54 volumio volumio[977]: info: Checking key NasMounts.8b659e07-4192-4c48-9c12-7cca162fbd52 Oct 16 07:25:54 volumio volumio[977]: info: Checking key NasMounts.19eafa7d-7f1f-477a-ac02-5347b75ec05c Oct 16 07:25:54 volumio volumio[977]: info: No correspondence found in configuration for share NAS_G on IP 192.168.1.11 Oct 16 07:25:54 volumio volumio[977]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.1.11 -N -m SMB3_11 Oct 16 07:25:54 volumio volumio[977]: info: Error browsing device NAS_G for shares: Command failed: smbclient --debuglevel=4 -L 192.168.1.11 -N -m SMB3_11 Oct 16 07:25:54 volumio volumio[977]: lp_load_ex: refreshing parameters Oct 16 07:25:54 volumio volumio[977]: Initialising global parameters Oct 16 07:25:54 volumio volumio[977]: Processing section "[global]" Oct 16 07:25:54 volumio volumio[977]: doing parameter netbios name = Volumio Oct 16 07:25:54 volumio volumio[977]: doing parameter server string = Volumio Audiophile Music Player Oct 16 07:25:54 volumio volumio[977]: doing parameter workgroup = WORKGROUP Oct 16 07:25:54 volumio volumio[977]: doing parameter security = user Oct 16 07:25:54 volumio volumio[977]: doing parameter map to guest = Bad User Oct 16 07:25:54 volumio volumio[977]: doing parameter encrypt passwords = yes Oct 16 07:25:54 volumio volumio[977]: doing parameter wins support = yes Oct 16 07:25:54 volumio volumio[977]: doing parameter local master = no Oct 16 07:25:54 volumio volumio[977]: doing parameter preferred master = no Oct 16 07:25:54 volumio volumio[977]: doing parameter os level = 30 Oct 16 07:25:54 volumio volumio[977]: doing parameter fruit:model = AirPort Oct 16 07:25:54 volumio volumio[977]: pm_process() returned Yes Oct 16 07:25:54 volumio volumio[977]: directory_create_or_exist_strict: invalid ownership on directory /var/run/samba/msg.lock Oct 16 07:25:54 volumio volumio[977]: cmdline_messaging_context: Unable to initialize messaging context. Oct 16 07:25:54 volumio volumio[977]: Unable to initialize messaging context Oct 16 07:25:54 volumio volumio[977]: lp_load_ex: refreshing parameters Oct 16 07:25:54 volumio volumio[977]: Initialising global parameters Oct 16 07:25:54 volumio volumio[977]: Processing section "[global]" Oct 16 07:25:54 volumio volumio[977]: doing parameter netbios name = Volumio Oct 16 07:25:54 volumio volumio[977]: doing parameter server string = Volumio Audiophile Music Player Oct 16 07:25:54 volumio volumio[977]: doing parameter workgroup = WORKGROUP Oct 16 07:25:54 volumio volumio[977]: doing parameter security = user Oct 16 07:25:54 volumio volumio[977]: doing parameter map to guest = Bad User Oct 16 07:25:54 volumio volumio[977]: doing parameter encrypt passwords = yes Oct 16 07:25:54 volumio volumio[977]: doing parameter wins support = yes Oct 16 07:25:54 volumio volumio[977]: doing parameter local master = no Oct 16 07:25:54 volumio volumio[977]: doing parameter preferred master = no Oct 16 07:25:54 volumio volumio[977]: doing parameter os level = 30 Oct 16 07:25:54 volumio volumio[977]: doing parameter fruit:model = AirPort Oct 16 07:25:54 volumio volumio[977]: pm_process() returned Yes Oct 16 07:25:54 volumio volumio[977]: added interface wlan0 ip=192.168.1.16 bcast=192.168.1.255 netmask=255.255.255.0 Oct 16 07:25:54 volumio volumio[977]: Client started (version 4.9.5-Debian). Oct 16 07:25:54 volumio volumio[977]: Connecting to 192.168.1.11 at port 445 Oct 16 07:25:54 volumio volumio[977]: session request ok Oct 16 07:25:54 volumio volumio[977]: negotiated dialect[SMB3_11] against server[192.168.1.11] Oct 16 07:25:54 volumio volumio[977]: got OID=1.3.6.1.4.1.311.2.2.10 Oct 16 07:25:54 volumio volumio[977]: GENSEC backend 'gssapi_spnego' registered Oct 16 07:25:54 volumio volumio[977]: GENSEC backend 'gssapi_krb5' registered Oct 16 07:25:54 volumio volumio[977]: GENSEC backend 'gssapi_krb5_sasl' registered Oct 16 07:25:54 volumio volumio[977]: GENSEC backend 'spnego' registered Oct 16 07:25:54 volumio volumio[977]: GENSEC backend 'schannel' registered Oct 16 07:25:54 volumio volumio[977]: GENSEC backend 'naclrpc_as_system' registered Oct 16 07:25:54 volumio volumio[977]: GENSEC backend 'sasl-EXTERNAL' registered Oct 16 07:25:54 volumio volumio[977]: GENSEC backend 'ntlmssp' registered Oct 16 07:25:54 volumio volumio[977]: GENSEC backend 'ntlmssp_resume_ccache' registered Oct 16 07:25:54 volumio volumio[977]: GENSEC backend 'http_basic' registered Oct 16 07:25:54 volumio volumio[977]: GENSEC backend 'http_ntlm' registered Oct 16 07:25:54 volumio volumio[977]: GENSEC backend 'http_negotiate' registered Oct 16 07:25:54 volumio volumio[977]: GENSEC backend 'krb5' registered Oct 16 07:25:54 volumio volumio[977]: GENSEC backend 'fake_gssapi_krb5' registered Oct 16 07:25:54 volumio volumio[977]: Got challenge flags: Oct 16 07:25:54 volumio volumio[977]: Got NTLMSSP neg_flags=0x628a8215 Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_UNICODE Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_REQUEST_TARGET Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_SIGN Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_NTLM Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_TARGET_TYPE_SERVER Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_TARGET_INFO Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_VERSION Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_128 Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_KEY_EXCH Oct 16 07:25:54 volumio volumio[977]: NTLMSSP: Set final flags: Oct 16 07:25:54 volumio volumio[977]: Got NTLMSSP neg_flags=0x62008215 Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_UNICODE Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_REQUEST_TARGET Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_SIGN Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_NTLM Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_VERSION Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_128 Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_KEY_EXCH Oct 16 07:25:54 volumio volumio[977]: NTLMSSP Sign/Seal - Initialising with flags: Oct 16 07:25:54 volumio volumio[977]: Got NTLMSSP neg_flags=0x62008215 Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_UNICODE Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_REQUEST_TARGET Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_SIGN Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_NTLM Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_VERSION Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_128 Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_KEY_EXCH Oct 16 07:25:54 volumio volumio[977]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. Oct 16 07:25:54 volumio volumio[977]: got OID=1.3.6.1.4.1.311.2.2.10 Oct 16 07:25:54 volumio volumio[977]: Got challenge flags: Oct 16 07:25:54 volumio volumio[977]: Got NTLMSSP neg_flags=0x628a8215 Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_UNICODE Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_REQUEST_TARGET Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_SIGN Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_NTLM Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_TARGET_TYPE_SERVER Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_TARGET_INFO Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_VERSION Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_128 Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_KEY_EXCH Oct 16 07:25:54 volumio volumio[977]: NTLMSSP: Set final flags: Oct 16 07:25:54 volumio volumio[977]: Got NTLMSSP neg_flags=0x62008a15 Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_UNICODE Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_REQUEST_TARGET Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_SIGN Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_NTLM Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_ANONYMOUS Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_VERSION Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_128 Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_KEY_EXCH Oct 16 07:25:54 volumio volumio[977]: NTLMSSP Sign/Seal - Initialising with flags: Oct 16 07:25:54 volumio volumio[977]: Got NTLMSSP neg_flags=0x62008a15 Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_UNICODE Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_REQUEST_TARGET Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_SIGN Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_NTLM Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_ANONYMOUS Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_VERSION Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_128 Oct 16 07:25:54 volumio volumio[977]: NTLMSSP_NEGOTIATE_KEY_EXCH Oct 16 07:25:54 volumio volumio[977]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. Oct 16 07:25:54 volumio volumio[977]: info: Detected device NAS_G with version null Oct 16 07:25:54 volumio sudo[3107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=gazda,password=@x4@g%d#R$$zdS4cdpYn!BQ7$!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.11/ostavi/Muzika/G /mnt/NAS/NAS_G Oct 16 07:25:54 volumio sudo[3107]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 07:25:54 volumio kernel: CIFS: Attempting to mount //192.168.1.11/ostavi/Muzika/G Oct 16 07:25:54 volumio sudo[3107]: pam_unix(sudo:session): session closed for user root Oct 16 07:25:54 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Oct 16 07:25:54 volumio volumio[977]: info: Oct 16 07:25:54 volumio volumio[977]: ---------------------------- MPD announces state update: update Oct 16 07:25:54 volumio volumio[977]: info: ControllerMpd::getState Oct 16 07:25:54 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Oct 16 07:25:54 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Oct 16 07:25:54 volumio volumio[977]: info: Oct 16 07:25:54 volumio volumio[977]: ---------------------------- MPD announces state update: update Oct 16 07:25:54 volumio volumio[977]: info: ControllerMpd::getState Oct 16 07:25:54 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Oct 16 07:25:54 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Oct 16 07:25:54 volumio volumio[977]: info: Oct 16 07:25:54 volumio volumio[977]: ---------------------------- MPD announces state update: update Oct 16 07:25:54 volumio volumio[977]: info: ControllerMpd::getState Oct 16 07:25:54 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Oct 16 07:25:54 volumio volumio[977]: info: sendMpdCommand status took 8 milliseconds Oct 16 07:25:54 volumio volumio[977]: info: sendMpdCommand status took 6 milliseconds Oct 16 07:25:54 volumio volumio[977]: info: sendMpdCommand status took 6 milliseconds Oct 16 07:25:54 volumio volumio[977]: info: sendMpdCommand status took 5 milliseconds Oct 16 07:25:54 volumio volumio[977]: info: sendMpdCommand status took 5 milliseconds Oct 16 07:25:54 volumio volumio[977]: info: sendMpdCommand status took 4 milliseconds Oct 16 07:25:54 volumio volumio[977]: verbose: ControllerMpd::parseState Oct 16 07:25:54 volumio volumio[977]: info: Command Router : Notfying DB Updatetrue Oct 16 07:25:54 volumio volumio[977]: verbose: ControllerMpd::parseState Oct 16 07:25:54 volumio volumio[977]: verbose: ControllerMpd::parseState Oct 16 07:25:54 volumio volumio[977]: info: Command Router : Notfying DB Updatetrue Oct 16 07:25:54 volumio volumio[977]: info: CoreCommandRouter::Close All Modals sent Oct 16 07:25:54 volumio volumio[977]: verbose: ControllerMpd::parseState Oct 16 07:25:54 volumio volumio[977]: verbose: ControllerMpd::parseState Oct 16 07:25:54 volumio volumio[977]: info: Command Router : Notfying DB Updatetrue Oct 16 07:25:54 volumio volumio[977]: info: CoreCommandRouter::Close All Modals sent Oct 16 07:25:54 volumio volumio[977]: verbose: ControllerMpd::parseState Oct 16 07:25:54 volumio volumio[977]: info: ControllerMpd::pushState Oct 16 07:25:54 volumio volumio[977]: info: CoreCommandRouter::servicePushState Oct 16 07:25:54 volumio volumio[977]: info: CoreStateMachine::pushState Oct 16 07:25:54 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Oct 16 07:25:54 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Oct 16 07:25:54 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Oct 16 07:25:54 volumio volumio[977]: 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} Oct 16 07:25:54 volumio volumio[977]: verbose: CURRENT POSITION 0 Oct 16 07:25:54 volumio volumio[977]: info: CoreStateMachine::syncState stateService stop Oct 16 07:25:54 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Oct 16 07:25:54 volumio volumio[977]: info: CoreStateMachine::pushState Oct 16 07:25:54 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Oct 16 07:25:54 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 07:25:54 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Oct 16 07:25:54 volumio volumio[977]: info: No code Oct 16 07:25:54 volumio volumio[977]: info: CoreStateMachine::pushState Oct 16 07:25:54 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Oct 16 07:25:54 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Oct 16 07:25:54 volumio volumio[977]: info: ControllerMpd::pushState Oct 16 07:25:54 volumio volumio[977]: info: CoreCommandRouter::servicePushState Oct 16 07:25:54 volumio volumio[977]: info: CoreStateMachine::pushState Oct 16 07:25:54 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Oct 16 07:25:54 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Oct 16 07:25:54 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Oct 16 07:25:54 volumio volumio[977]: 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} Oct 16 07:25:54 volumio volumio[977]: verbose: CURRENT POSITION 0 Oct 16 07:25:54 volumio volumio[977]: info: CoreStateMachine::syncState stateService stop Oct 16 07:25:54 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Oct 16 07:25:54 volumio volumio[977]: info: CoreStateMachine::pushState Oct 16 07:25:54 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Oct 16 07:25:54 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Oct 16 07:25:54 volumio volumio[977]: info: No code Oct 16 07:25:54 volumio volumio[977]: info: CoreStateMachine::pushState Oct 16 07:25:54 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Oct 16 07:25:54 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Oct 16 07:25:54 volumio volumio[977]: info: ControllerMpd::pushState Oct 16 07:25:54 volumio volumio[977]: info: CoreCommandRouter::servicePushState Oct 16 07:25:54 volumio volumio[977]: info: CoreStateMachine::pushState Oct 16 07:25:54 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Oct 16 07:25:54 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Oct 16 07:25:54 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Oct 16 07:25:54 volumio volumio[977]: 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} Oct 16 07:25:54 volumio volumio[977]: verbose: CURRENT POSITION 0 Oct 16 07:25:54 volumio volumio[977]: info: CoreStateMachine::syncState stateService stop Oct 16 07:25:54 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Oct 16 07:25:54 volumio volumio[977]: info: CoreStateMachine::pushState Oct 16 07:25:54 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Oct 16 07:25:54 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Oct 16 07:25:54 volumio volumio[977]: info: No code Oct 16 07:25:54 volumio volumio[977]: info: CoreStateMachine::pushState Oct 16 07:25:54 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Oct 16 07:25:54 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Oct 16 07:25:54 volumio volumio[977]: info: ------------------------------ 179ms Oct 16 07:25:54 volumio volumio[977]: info: ------------------------------ 178ms Oct 16 07:25:54 volumio volumio[977]: info: ------------------------------ 177ms Oct 16 07:25:54 volumio volumio[977]: info: [1760592354817] Database update started Oct 16 07:25:55 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 16 07:25:57 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 07:26:00 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 16 07:26:00 volumio volumio[977]: info: CURURI: artists:// Oct 16 07:26:00 volumio volumio[977]: info: CoreCommandRouter::volumioGetQueue Oct 16 07:26:00 volumio volumio[977]: info: CoreStateMachine::getQueue Oct 16 07:26:00 volumio volumio[977]: info: CorePlayQueue::getQueue Oct 16 07:26:00 volumio volumio[977]: info: Preload queue cleared Oct 16 07:26:03 volumio ntpd[726]: 80.203.110.169 local addr 192.168.1.16 -> Oct 16 07:26:10 volumio volumio[977]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Buster%20Brown/eb33949a-fb77-4bb1-be4e-64a211b35f14.jpg' Oct 16 07:26:12 volumio volumio[977]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 07:26:12 volumio volumio[977]: TypeError: Cannot read property 'slice' of null Oct 16 07:26:12 volumio volumio[977]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Oct 16 07:26:12 volumio volumio[977]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Oct 16 07:26:12 volumio volumio[977]: at Object.onceWrapper (events.js:422:26) Oct 16 07:26:12 volumio volumio[977]: at ClientRequest.emit (events.js:315:20) Oct 16 07:26:12 volumio volumio[977]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Oct 16 07:26:12 volumio volumio[977]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Oct 16 07:26:12 volumio volumio[977]: at TLSSocket.socketOnData (_http_client.js:509:22) Oct 16 07:26:12 volumio volumio[977]: at TLSSocket.emit (events.js:315:20) Oct 16 07:26:12 volumio volumio[977]: at addChunk (internal/streams/readable.js:309:12) Oct 16 07:26:12 volumio volumio[977]: at readableAddChunk (internal/streams/readable.js:284:9) Oct 16 07:26:12 volumio volumio[977]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Oct 16 07:26:12 volumio volumio[977]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Oct 16 07:26:12 volumio volumio[977]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 07:26:13 volumio sudo[3229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-16 07:25 Oct 16 07:26:13 volumio sudo[3229]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"