-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2025-09-01 17:49:50 CEST. -- Sep 01 17:48:00 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:48:04 saragonimusic ntpd[819]: Soliciting pool server 212.6.50.243 Sep 01 17:48:04 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:48:05 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Sep 01 17:48:05 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Sep 01 17:48:06 saragonimusic volumio[922]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L ManuCloud.local. -m SMB3_11 Sep 01 17:48:08 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:48:13 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:48:14 saragonimusic volumio[922]: info: Error browsing device ManuCloud for shares: Command failed: smbclient --no-pass --debuglevel=0 -L ManuCloud.local. -m SMB3_11 Sep 01 17:48:14 saragonimusic volumio[922]: Unable to initialize messaging context Sep 01 17:48:17 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:48:21 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:48:25 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:48:29 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:48:32 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Sep 01 17:48:32 saragonimusic volumio[922]: info: Adding a new share Sep 01 17:48:32 saragonimusic volumio[922]: info: No correspondence found in configuration for share ManuCloud on IP 192.168.178.199 Sep 01 17:48:32 saragonimusic volumio[922]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.178.199 -N -m SMB3_11 Sep 01 17:48:33 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:48:35 saragonimusic volumio[922]: info: Error browsing device ManuCloud for shares: Command failed: smbclient --debuglevel=4 -L 192.168.178.199 -N -m SMB3_11 Sep 01 17:48:35 saragonimusic volumio[922]: lp_load_ex: refreshing parameters Sep 01 17:48:35 saragonimusic volumio[922]: Initialising global parameters Sep 01 17:48:35 saragonimusic volumio[922]: Processing section "[global]" Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter netbios name = SaragoniMusic Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter server string = Audiophile Music Player Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter workgroup = WORKGROUP Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter security = user Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter map to guest = Bad User Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter encrypt passwords = yes Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter wins support = yes Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter local master = no Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter preferred master = no Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter os level = 30 Sep 01 17:48:35 saragonimusic volumio[922]: pm_process() returned Yes Sep 01 17:48:35 saragonimusic volumio[922]: directory_create_or_exist_strict: invalid ownership on directory /var/run/samba/msg.lock Sep 01 17:48:35 saragonimusic volumio[922]: cmdline_messaging_context: Unable to initialize messaging context. Sep 01 17:48:35 saragonimusic volumio[922]: Unable to initialize messaging context Sep 01 17:48:35 saragonimusic volumio[922]: lp_load_ex: refreshing parameters Sep 01 17:48:35 saragonimusic volumio[922]: Initialising global parameters Sep 01 17:48:35 saragonimusic volumio[922]: Processing section "[global]" Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter netbios name = SaragoniMusic Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter server string = Audiophile Music Player Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter workgroup = WORKGROUP Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter security = user Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter map to guest = Bad User Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter encrypt passwords = yes Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter wins support = yes Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter local master = no Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter preferred master = no Sep 01 17:48:35 saragonimusic volumio[922]: doing parameter os level = 30 Sep 01 17:48:35 saragonimusic volumio[922]: pm_process() returned Yes Sep 01 17:48:35 saragonimusic volumio[922]: added interface wlan0 ip=192.168.178.29 bcast=192.168.178.255 netmask=255.255.255.0 Sep 01 17:48:35 saragonimusic volumio[922]: Client started (version 4.9.5-Debian). Sep 01 17:48:35 saragonimusic volumio[922]: Connecting to 192.168.178.199 at port 445 Sep 01 17:48:35 saragonimusic volumio[922]: Connecting to 192.168.178.199 at port 139 Sep 01 17:48:35 saragonimusic volumio[922]: session request ok Sep 01 17:48:35 saragonimusic volumio[922]: negotiated dialect[SMB3_11] against server[192.168.178.199] Sep 01 17:48:35 saragonimusic volumio[922]: got OID=1.3.6.1.4.1.311.2.2.10 Sep 01 17:48:35 saragonimusic volumio[922]: GENSEC backend 'gssapi_spnego' registered Sep 01 17:48:35 saragonimusic volumio[922]: GENSEC backend 'gssapi_krb5' registered Sep 01 17:48:35 saragonimusic volumio[922]: GENSEC backend 'gssapi_krb5_sasl' registered Sep 01 17:48:35 saragonimusic volumio[922]: GENSEC backend 'spnego' registered Sep 01 17:48:35 saragonimusic volumio[922]: GENSEC backend 'schannel' registered Sep 01 17:48:35 saragonimusic volumio[922]: GENSEC backend 'naclrpc_as_system' registered Sep 01 17:48:35 saragonimusic volumio[922]: GENSEC backend 'sasl-EXTERNAL' registered Sep 01 17:48:35 saragonimusic volumio[922]: GENSEC backend 'ntlmssp' registered Sep 01 17:48:35 saragonimusic volumio[922]: GENSEC backend 'ntlmssp_resume_ccache' registered Sep 01 17:48:35 saragonimusic volumio[922]: GENSEC backend 'http_basic' registered Sep 01 17:48:35 saragonimusic volumio[922]: GENSEC backend 'http_ntlm' registered Sep 01 17:48:35 saragonimusic volumio[922]: GENSEC backend 'http_negotiate' registered Sep 01 17:48:35 saragonimusic volumio[922]: GENSEC backend 'krb5' registered Sep 01 17:48:35 saragonimusic volumio[922]: GENSEC backend 'fake_gssapi_krb5' registered Sep 01 17:48:35 saragonimusic volumio[922]: Got challenge flags: Sep 01 17:48:35 saragonimusic volumio[922]: Got NTLMSSP neg_flags=0x628a8215 Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_UNICODE Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_REQUEST_TARGET Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_SIGN Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_NTLM Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_TARGET_TYPE_SERVER Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_TARGET_INFO Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_VERSION Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_128 Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_KEY_EXCH Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP: Set final flags: Sep 01 17:48:35 saragonimusic volumio[922]: Got NTLMSSP neg_flags=0x62008215 Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_UNICODE Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_REQUEST_TARGET Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_SIGN Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_NTLM Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_VERSION Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_128 Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_KEY_EXCH Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP Sign/Seal - Initialising with flags: Sep 01 17:48:35 saragonimusic volumio[922]: Got NTLMSSP neg_flags=0x62008215 Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_UNICODE Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_REQUEST_TARGET Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_SIGN Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_NTLM Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_VERSION Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_128 Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_KEY_EXCH Sep 01 17:48:35 saragonimusic volumio[922]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. Sep 01 17:48:35 saragonimusic volumio[922]: got OID=1.3.6.1.4.1.311.2.2.10 Sep 01 17:48:35 saragonimusic volumio[922]: Got challenge flags: Sep 01 17:48:35 saragonimusic volumio[922]: Got NTLMSSP neg_flags=0x628a8215 Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_UNICODE Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_REQUEST_TARGET Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_SIGN Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_NTLM Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_TARGET_TYPE_SERVER Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_TARGET_INFO Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_VERSION Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_128 Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_KEY_EXCH Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP: Set final flags: Sep 01 17:48:35 saragonimusic volumio[922]: Got NTLMSSP neg_flags=0x62008a15 Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_UNICODE Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_REQUEST_TARGET Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_SIGN Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_NTLM Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_ANONYMOUS Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_VERSION Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_128 Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_KEY_EXCH Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP Sign/Seal - Initialising with flags: Sep 01 17:48:35 saragonimusic volumio[922]: Got NTLMSSP neg_flags=0x62008a15 Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_UNICODE Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_REQUEST_TARGET Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_SIGN Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_NTLM Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_ANONYMOUS Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_VERSION Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_128 Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_KEY_EXCH Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP Sign/Seal - Initialising with flags: Sep 01 17:48:35 saragonimusic volumio[922]: Got NTLMSSP neg_flags=0x62008a15 Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_UNICODE Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_REQUEST_TARGET Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_SIGN Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_NTLM Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_ANONYMOUS Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_VERSION Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_128 Sep 01 17:48:35 saragonimusic volumio[922]: NTLMSSP_NEGOTIATE_KEY_EXCH Sep 01 17:48:35 saragonimusic volumio[922]: session setup ok Sep 01 17:48:35 saragonimusic volumio[922]: info: Detected device ManuCloud with version null Sep 01 17:48:35 saragonimusic sudo[3811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.199/Multimedia /mnt/NAS/ManuCloud Sep 01 17:48:35 saragonimusic sudo[3811]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 17:48:35 saragonimusic kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Sep 01 17:48:35 saragonimusic kernel: CIFS: Attempting to mount //192.168.178.199/Multimedia Sep 01 17:48:35 saragonimusic kernel: cryptd: max_cpu_qlen set to 1000 Sep 01 17:48:36 saragonimusic kernel: CIFS: VFS: \\192.168.178.199 failed to connect to IPC (rc=-13) Sep 01 17:48:36 saragonimusic kernel: CIFS: VFS: session b530bcba has no tcon available for a dfs referral request Sep 01 17:48:36 saragonimusic kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Sep 01 17:48:36 saragonimusic sudo[3811]: pam_unix(sudo:session): session closed for user root Sep 01 17:48:36 saragonimusic volumio[922]: info: Permission denied for ManuCloud on IP 192.168.178.199 Sep 01 17:48:37 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:48:37 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 01 17:48:41 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:48:45 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:48:49 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:48:53 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:48:55 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Sep 01 17:48:55 saragonimusic volumio[922]: info: Share ManuCloud successfully unmounted Sep 01 17:48:55 saragonimusic sudo[3877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=28082002Manuel$,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.199/Multimedia /mnt/NAS/ManuCloud Sep 01 17:48:55 saragonimusic sudo[3877]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 17:48:55 saragonimusic kernel: CIFS: Attempting to mount //192.168.178.199/Multimedia Sep 01 17:48:56 saragonimusic sudo[3877]: pam_unix(sudo:session): session closed for user root Sep 01 17:48:56 saragonimusic volumio[922]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:48:56 saragonimusic volumio[922]: info: Sep 01 17:48:56 saragonimusic volumio[922]: ---------------------------- MPD announces state update: update Sep 01 17:48:56 saragonimusic volumio[922]: info: ControllerMpd::getState Sep 01 17:48:56 saragonimusic volumio[922]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:48:56 saragonimusic volumio[922]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:48:56 saragonimusic volumio[922]: info: Sep 01 17:48:56 saragonimusic volumio[922]: ---------------------------- MPD announces state update: update Sep 01 17:48:56 saragonimusic volumio[922]: info: ControllerMpd::getState Sep 01 17:48:56 saragonimusic volumio[922]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:48:56 saragonimusic volumio[922]: info: sendMpdCommand status took 18 milliseconds Sep 01 17:48:56 saragonimusic volumio[922]: info: sendMpdCommand status took 12 milliseconds Sep 01 17:48:56 saragonimusic volumio[922]: verbose: ControllerMpd::parseState Sep 01 17:48:56 saragonimusic volumio[922]: info: Command Router : Notfying DB Updatetrue Sep 01 17:48:56 saragonimusic volumio[922]: verbose: ControllerMpd::parseState Sep 01 17:48:56 saragonimusic volumio[922]: info: ControllerMpd::pushState Sep 01 17:48:56 saragonimusic volumio[922]: info: CoreCommandRouter::servicePushState Sep 01 17:48:56 saragonimusic volumio[922]: info: CoreStateMachine::pushState Sep 01 17:48:56 saragonimusic volumio[922]: info: CorePlayQueue::getTrack 0 Sep 01 17:48:56 saragonimusic volumio[922]: info: CoreCommandRouter::volumioPushState Sep 01 17:48:56 saragonimusic volumio[922]: info: CorePlayQueue::getTrack 0 Sep 01 17:48:56 saragonimusic volumio[922]: 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} Sep 01 17:48:56 saragonimusic volumio[922]: verbose: CURRENT POSITION 0 Sep 01 17:48:56 saragonimusic volumio[922]: info: CoreStateMachine::syncState stateService stop Sep 01 17:48:56 saragonimusic volumio[922]: info: CoreStateMachine::syncState currentStatus stop Sep 01 17:48:56 saragonimusic volumio[922]: info: CoreStateMachine::pushState Sep 01 17:48:56 saragonimusic volumio[922]: info: CorePlayQueue::getTrack 0 Sep 01 17:48:56 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:48:56 saragonimusic volumio[922]: info: CoreCommandRouter::volumioPushState Sep 01 17:48:56 saragonimusic volumio[922]: info: No code Sep 01 17:48:56 saragonimusic volumio[922]: info: CoreStateMachine::pushState Sep 01 17:48:56 saragonimusic volumio[922]: info: CorePlayQueue::getTrack 0 Sep 01 17:48:56 saragonimusic volumio[922]: info: CoreCommandRouter::volumioPushState Sep 01 17:48:57 saragonimusic volumio[922]: info: ------------------------------ 191ms Sep 01 17:48:57 saragonimusic volumio[922]: info: [1756741737013] Database update started Sep 01 17:48:57 saragonimusic volumio[922]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:48:57 saragonimusic volumio[922]: info: Sep 01 17:48:57 saragonimusic volumio[922]: ---------------------------- MPD announces state update: update Sep 01 17:48:57 saragonimusic volumio[922]: info: sendMpdCommand status took 192 milliseconds Sep 01 17:48:57 saragonimusic volumio[922]: info: sendMpdCommand status took 189 milliseconds Sep 01 17:48:57 saragonimusic volumio[922]: info: ControllerMpd::getState Sep 01 17:48:57 saragonimusic volumio[922]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:48:57 saragonimusic volumio[922]: verbose: ControllerMpd::parseState Sep 01 17:48:57 saragonimusic volumio[922]: info: Command Router : Notfying DB Updatetrue Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:48:57 saragonimusic volumio[922]: verbose: ControllerMpd::parseState Sep 01 17:48:57 saragonimusic volumio[922]: info: ControllerMpd::pushState Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreCommandRouter::servicePushState Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreStateMachine::pushState Sep 01 17:48:57 saragonimusic volumio[922]: info: CorePlayQueue::getTrack 0 Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreCommandRouter::volumioPushState Sep 01 17:48:57 saragonimusic volumio[922]: info: CorePlayQueue::getTrack 0 Sep 01 17:48:57 saragonimusic volumio[922]: 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} Sep 01 17:48:57 saragonimusic volumio[922]: verbose: CURRENT POSITION 0 Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreStateMachine::syncState stateService stop Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreStateMachine::syncState currentStatus stop Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreStateMachine::pushState Sep 01 17:48:57 saragonimusic volumio[922]: info: CorePlayQueue::getTrack 0 Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreCommandRouter::volumioPushState Sep 01 17:48:57 saragonimusic volumio[922]: info: No code Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreStateMachine::pushState Sep 01 17:48:57 saragonimusic volumio[922]: info: CorePlayQueue::getTrack 0 Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreCommandRouter::volumioPushState Sep 01 17:48:57 saragonimusic volumio[922]: info: ------------------------------ 260ms Sep 01 17:48:57 saragonimusic volumio[922]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:48:57 saragonimusic volumio[922]: info: Sep 01 17:48:57 saragonimusic volumio[922]: ---------------------------- MPD announces state update: update Sep 01 17:48:57 saragonimusic volumio[922]: info: ControllerMpd::getState Sep 01 17:48:57 saragonimusic volumio[922]: verbose: ControllerMpd::sendMpdCommand status Sep 01 17:48:57 saragonimusic volumio[922]: info: sendMpdCommand status took 83 milliseconds Sep 01 17:48:57 saragonimusic volumio[922]: info: sendMpdCommand status took 82 milliseconds Sep 01 17:48:57 saragonimusic volumio[922]: info: sendMpdCommand status took 13 milliseconds Sep 01 17:48:57 saragonimusic volumio[922]: info: sendMpdCommand status took 12 milliseconds Sep 01 17:48:57 saragonimusic volumio[922]: verbose: ControllerMpd::parseState Sep 01 17:48:57 saragonimusic volumio[922]: info: Command Router : Notfying DB Updatefalse Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:48:57 saragonimusic volumio[922]: verbose: ControllerMpd::parseState Sep 01 17:48:57 saragonimusic volumio[922]: verbose: ControllerMpd::parseState Sep 01 17:48:57 saragonimusic volumio[922]: info: Command Router : Notfying DB Updatefalse Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreCommandRouter::Close All Modals sent Sep 01 17:48:57 saragonimusic volumio[922]: verbose: ControllerMpd::parseState Sep 01 17:48:57 saragonimusic volumio[922]: info: ControllerMpd::pushState Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreCommandRouter::servicePushState Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreStateMachine::pushState Sep 01 17:48:57 saragonimusic volumio[922]: info: CorePlayQueue::getTrack 0 Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreCommandRouter::volumioPushState Sep 01 17:48:57 saragonimusic volumio[922]: info: CorePlayQueue::getTrack 0 Sep 01 17:48:57 saragonimusic volumio[922]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 17:48:57 saragonimusic volumio[922]: verbose: CURRENT POSITION 0 Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreStateMachine::syncState stateService stop Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreStateMachine::syncState currentStatus stop Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreStateMachine::pushState Sep 01 17:48:57 saragonimusic volumio[922]: info: CorePlayQueue::getTrack 0 Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreCommandRouter::volumioPushState Sep 01 17:48:57 saragonimusic volumio[922]: info: No code Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreStateMachine::pushState Sep 01 17:48:57 saragonimusic volumio[922]: info: CorePlayQueue::getTrack 0 Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreCommandRouter::volumioPushState Sep 01 17:48:57 saragonimusic volumio[922]: info: ControllerMpd::pushState Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreCommandRouter::servicePushState Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreStateMachine::pushState Sep 01 17:48:57 saragonimusic volumio[922]: info: CorePlayQueue::getTrack 0 Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreCommandRouter::volumioPushState Sep 01 17:48:57 saragonimusic volumio[922]: info: CorePlayQueue::getTrack 0 Sep 01 17:48:57 saragonimusic volumio[922]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 17:48:57 saragonimusic volumio[922]: verbose: CURRENT POSITION 0 Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreStateMachine::syncState stateService stop Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreStateMachine::syncState currentStatus stop Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreStateMachine::pushState Sep 01 17:48:57 saragonimusic volumio[922]: info: CorePlayQueue::getTrack 0 Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreCommandRouter::volumioPushState Sep 01 17:48:57 saragonimusic volumio[922]: info: No code Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreStateMachine::pushState Sep 01 17:48:57 saragonimusic volumio[922]: info: CorePlayQueue::getTrack 0 Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreCommandRouter::volumioPushState Sep 01 17:48:57 saragonimusic volumio[922]: info: ------------------------------ 194ms Sep 01 17:48:57 saragonimusic volumio[922]: info: ------------------------------ 124ms Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:48:57 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 01 17:49:00 saragonimusic ntpd[819]: Soliciting pool server 81.56.230.156 Sep 01 17:49:01 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:49:06 saragonimusic ntpd[819]: Soliciting pool server 80.211.238.32 Sep 01 17:49:06 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:49:10 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:49:12 saragonimusic ntpd[819]: Soliciting pool server 162.159.200.1 Sep 01 17:49:13 saragonimusic ntpd[819]: Soliciting pool server 93.94.88.50 Sep 01 17:49:14 saragonimusic ntpd[819]: Soliciting pool server 31.14.133.122 Sep 01 17:49:14 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:49:18 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:49:22 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:49:26 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 01 17:49:31 saragonimusic volumio[922]: info: CoreCommandRouter::volumioGetState Sep 01 17:49:31 saragonimusic volumio[922]: info: CorePlayQueue::getTrack 0 Sep 01 17:49:33 saragonimusic volumio[922]: info: CoreCommandRouter::volumioGetQueue Sep 01 17:49:33 saragonimusic volumio[922]: info: CoreStateMachine::getQueue Sep 01 17:49:33 saragonimusic volumio[922]: info: CorePlayQueue::getQueue Sep 01 17:49:38 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 01 17:49:38 saragonimusic volumio[922]: info: Preload queue cleared Sep 01 17:49:39 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 01 17:49:41 saragonimusic volumio[922]: info: Preload queue cleared Sep 01 17:49:43 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 01 17:49:45 saragonimusic volumio[922]: info: Preload queue cleared Sep 01 17:49:50 saragonimusic volumio[922]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 01 17:49:50 saragonimusic volumio[922]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 17:49:50 saragonimusic volumio[922]: TypeError: Cannot read property 'length' of undefined Sep 01 17:49:50 saragonimusic volumio[922]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Sep 01 17:49:50 saragonimusic volumio[922]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Sep 01 17:49:50 saragonimusic volumio[922]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Sep 01 17:49:50 saragonimusic volumio[922]: at Parser.emit (events.js:315:20) Sep 01 17:49:50 saragonimusic volumio[922]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Sep 01 17:49:50 saragonimusic volumio[922]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Sep 01 17:49:50 saragonimusic volumio[922]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Sep 01 17:49:50 saragonimusic volumio[922]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Sep 01 17:49:50 saragonimusic volumio[922]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Sep 01 17:49:50 saragonimusic volumio[922]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Sep 01 17:49:50 saragonimusic volumio[922]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Sep 01 17:49:50 saragonimusic volumio[922]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Sep 01 17:49:50 saragonimusic volumio[922]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Sep 01 17:49:50 saragonimusic volumio[922]: at IncomingMessage.emit (events.js:327:22) Sep 01 17:49:50 saragonimusic volumio[922]: at endReadableNT (internal/streams/readable.js:1327:12) Sep 01 17:49:50 saragonimusic volumio[922]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 01 17:49:50 saragonimusic volumio[922]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 17:49:50 saragonimusic sudo[4058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-01 17:48 Sep 01 17:49:50 saragonimusic sudo[4058]: 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"