-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-07-05 14:54:51 CEST. -- Jul 05 14:53:00 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:53:04 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:53:08 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:53:12 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:53:16 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:53:20 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:53:24 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:53:28 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:53:32 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:53:36 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:37 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:37 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:40 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:43 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:43 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:43 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:43 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:43 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:43 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:43 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:53:43 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:43 volumio shairport-sync[2998]: {"time":1751719787024,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Jul 05 14:53:43 volumio shairport-sync[2998]: Dload Upload Total Spent Left Speed Jul 05 14:53:43 volumio volumio[932]: info: Jul 05 14:53:43 volumio volumio[932]: ---------------------------- Client requests Stop Airplay Playback Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Jul 05 14:53:43 volumio volumio[932]: info: Airplay playback stop Jul 05 14:53:43 volumio volumio[932]: info: Airplay Playback Stopped, clearing state Jul 05 14:53:43 volumio volumio[932]: info: CoreStateMachine::resetVolumioState Jul 05 14:53:43 volumio volumio[932]: info: CoreStateMachine::getcurrentVolume Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 14:53:43 volumio shairport-sync[2998]: [158B blob data] Jul 05 14:53:43 volumio volumio[932]: info: VolumeController:: Volume=76 Mute =false Jul 05 14:53:43 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:43 volumio volumio[932]: info: CoreStateMachine::updateTrackBlock Jul 05 14:53:43 volumio volumio[932]: info: CorePlayQueue::getTrackBlock Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 14:53:43 volumio volumio[932]: info: VolumeController:: Volume=76 Mute =false Jul 05 14:53:43 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:53:43 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:53:44 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:53:48 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:53:52 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:53:56 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:54:00 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:54:04 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:54:08 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:54:08 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Jul 05 14:54:08 volumio volumio[932]: info: Adding a new share Jul 05 14:54:08 volumio volumio[932]: info: No correspondence found in configuration for share Synology on IP synology.local Jul 05 14:54:08 volumio volumio[932]: info: Executing SMB command: smbclient --debuglevel=4 -L synology.local -N Jul 05 14:54:09 volumio volumio[932]: info: Error browsing device Synology for shares: Command failed: smbclient --debuglevel=4 -L synology.local -N Jul 05 14:54:09 volumio volumio[932]: lp_load_ex: refreshing parameters Jul 05 14:54:09 volumio volumio[932]: Initialising global parameters Jul 05 14:54:09 volumio volumio[932]: Processing section "[global]" Jul 05 14:54:09 volumio volumio[932]: doing parameter netbios name = Volumio Jul 05 14:54:09 volumio volumio[932]: doing parameter server string = Volumio Audiophile Music Player Jul 05 14:54:09 volumio volumio[932]: doing parameter workgroup = WORKGROUP Jul 05 14:54:09 volumio volumio[932]: doing parameter security = user Jul 05 14:54:09 volumio volumio[932]: doing parameter map to guest = Bad User Jul 05 14:54:09 volumio volumio[932]: doing parameter encrypt passwords = yes Jul 05 14:54:09 volumio volumio[932]: doing parameter wins support = yes Jul 05 14:54:09 volumio volumio[932]: doing parameter local master = no Jul 05 14:54:09 volumio volumio[932]: doing parameter preferred master = no Jul 05 14:54:09 volumio volumio[932]: doing parameter os level = 30 Jul 05 14:54:09 volumio volumio[932]: doing parameter fruit:model = AirPort Jul 05 14:54:09 volumio volumio[932]: pm_process() returned Yes Jul 05 14:54:09 volumio volumio[932]: directory_create_or_exist_strict: invalid ownership on directory /var/run/samba/msg.lock Jul 05 14:54:09 volumio volumio[932]: cmdline_messaging_context: Unable to initialize messaging context. Jul 05 14:54:09 volumio volumio[932]: Unable to initialize messaging context Jul 05 14:54:09 volumio volumio[932]: lp_load_ex: refreshing parameters Jul 05 14:54:09 volumio volumio[932]: Initialising global parameters Jul 05 14:54:09 volumio volumio[932]: Processing section "[global]" Jul 05 14:54:09 volumio volumio[932]: doing parameter netbios name = Volumio Jul 05 14:54:09 volumio volumio[932]: doing parameter server string = Volumio Audiophile Music Player Jul 05 14:54:09 volumio volumio[932]: doing parameter workgroup = WORKGROUP Jul 05 14:54:09 volumio volumio[932]: doing parameter security = user Jul 05 14:54:09 volumio volumio[932]: doing parameter map to guest = Bad User Jul 05 14:54:09 volumio volumio[932]: doing parameter encrypt passwords = yes Jul 05 14:54:09 volumio volumio[932]: doing parameter wins support = yes Jul 05 14:54:09 volumio volumio[932]: doing parameter local master = no Jul 05 14:54:09 volumio volumio[932]: doing parameter preferred master = no Jul 05 14:54:09 volumio volumio[932]: doing parameter os level = 30 Jul 05 14:54:09 volumio volumio[932]: doing parameter fruit:model = AirPort Jul 05 14:54:09 volumio volumio[932]: pm_process() returned Yes Jul 05 14:54:09 volumio volumio[932]: added interface eth0 ip=192.168.2.142 bcast=192.168.2.255 netmask=255.255.255.0 Jul 05 14:54:09 volumio volumio[932]: added interface wlan0 ip=192.168.2.162 bcast=192.168.2.255 netmask=255.255.255.0 Jul 05 14:54:09 volumio volumio[932]: Client started (version 4.9.5-Debian). Jul 05 14:54:09 volumio volumio[932]: tdb(/var/cache/samba/gencache.tdb): tdb_open_ex: could not open file /var/cache/samba/gencache.tdb: Permission denied Jul 05 14:54:09 volumio volumio[932]: resolve_hosts: Attempting host lookup for name synology.local<0x20> Jul 05 14:54:09 volumio volumio[932]: Connecting to 192.168.2.143 at port 445 Jul 05 14:54:09 volumio volumio[932]: Connecting to 192.168.2.143 at port 139 Jul 05 14:54:09 volumio volumio[932]: session request ok Jul 05 14:54:09 volumio volumio[932]: negotiated dialect[SMB3_11] against server[synology.local] Jul 05 14:54:09 volumio volumio[932]: got OID=1.3.6.1.4.1.311.2.2.10 Jul 05 14:54:09 volumio volumio[932]: GENSEC backend 'gssapi_spnego' registered Jul 05 14:54:09 volumio volumio[932]: GENSEC backend 'gssapi_krb5' registered Jul 05 14:54:09 volumio volumio[932]: GENSEC backend 'gssapi_krb5_sasl' registered Jul 05 14:54:09 volumio volumio[932]: GENSEC backend 'spnego' registered Jul 05 14:54:09 volumio volumio[932]: GENSEC backend 'schannel' registered Jul 05 14:54:09 volumio volumio[932]: GENSEC backend 'naclrpc_as_system' registered Jul 05 14:54:09 volumio volumio[932]: GENSEC backend 'sasl-EXTERNAL' registered Jul 05 14:54:09 volumio volumio[932]: GENSEC backend 'ntlmssp' registered Jul 05 14:54:09 volumio volumio[932]: GENSEC backend 'ntlmssp_resume_ccache' registered Jul 05 14:54:09 volumio volumio[932]: GENSEC backend 'http_basic' registered Jul 05 14:54:09 volumio volumio[932]: GENSEC backend 'http_ntlm' registered Jul 05 14:54:09 volumio volumio[932]: GENSEC backend 'http_negotiate' registered Jul 05 14:54:09 volumio volumio[932]: GENSEC backend 'krb5' registered Jul 05 14:54:09 volumio volumio[932]: GENSEC backend 'fake_gssapi_krb5' registered Jul 05 14:54:09 volumio volumio[932]: Got challenge flags: Jul 05 14:54:09 volumio volumio[932]: Got NTLMSSP neg_flags=0x628a8215 Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_UNICODE Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_REQUEST_TARGET Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_SIGN Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_NTLM Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_TARGET_TYPE_SERVER Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_TARGET_INFO Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_VERSION Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_128 Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_KEY_EXCH Jul 05 14:54:09 volumio volumio[932]: NTLMSSP: Set final flags: Jul 05 14:54:09 volumio volumio[932]: Got NTLMSSP neg_flags=0x62008215 Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_UNICODE Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_REQUEST_TARGET Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_SIGN Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_NTLM Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_VERSION Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_128 Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_KEY_EXCH Jul 05 14:54:09 volumio volumio[932]: NTLMSSP Sign/Seal - Initialising with flags: Jul 05 14:54:09 volumio volumio[932]: Got NTLMSSP neg_flags=0x62008215 Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_UNICODE Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_REQUEST_TARGET Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_SIGN Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_NTLM Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_VERSION Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_128 Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_KEY_EXCH Jul 05 14:54:09 volumio volumio[932]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. Jul 05 14:54:09 volumio volumio[932]: got OID=1.3.6.1.4.1.311.2.2.10 Jul 05 14:54:09 volumio volumio[932]: Got challenge flags: Jul 05 14:54:09 volumio volumio[932]: Got NTLMSSP neg_flags=0x628a8215 Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_UNICODE Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_REQUEST_TARGET Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_SIGN Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_NTLM Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_TARGET_TYPE_SERVER Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_TARGET_INFO Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_VERSION Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_128 Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_KEY_EXCH Jul 05 14:54:09 volumio volumio[932]: NTLMSSP: Set final flags: Jul 05 14:54:09 volumio volumio[932]: Got NTLMSSP neg_flags=0x62008a15 Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_UNICODE Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_REQUEST_TARGET Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_SIGN Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_NTLM Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_ANONYMOUS Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_VERSION Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_128 Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_KEY_EXCH Jul 05 14:54:09 volumio volumio[932]: NTLMSSP Sign/Seal - Initialising with flags: Jul 05 14:54:09 volumio volumio[932]: Got NTLMSSP neg_flags=0x62008a15 Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_UNICODE Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_REQUEST_TARGET Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_SIGN Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_NTLM Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_ANONYMOUS Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_VERSION Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_128 Jul 05 14:54:09 volumio volumio[932]: NTLMSSP_NEGOTIATE_KEY_EXCH Jul 05 14:54:09 volumio volumio[932]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. Jul 05 14:54:09 volumio volumio[932]: info: Detected device Synology with version null Jul 05 14:54:09 volumio sudo[3685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=artur,password=#DrYem!!cUxA3662y8jTBY5E,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //synology.local/music /mnt/NAS/Synology Jul 05 14:54:09 volumio sudo[3685]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:54:09 volumio kernel: Key type cifs.spnego registered Jul 05 14:54:09 volumio kernel: Key type cifs.idmap registered Jul 05 14:54:09 volumio 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. Jul 05 14:54:09 volumio kernel: CIFS: Attempting to mount //synology.local/music Jul 05 14:54:09 volumio kernel: cryptd: max_cpu_qlen set to 1000 Jul 05 14:54:09 volumio sudo[3685]: pam_unix(sudo:session): session closed for user root Jul 05 14:54:09 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:54:09 volumio volumio[932]: info: Jul 05 14:54:09 volumio volumio[932]: ---------------------------- MPD announces state update: update Jul 05 14:54:09 volumio volumio[932]: info: ControllerMpd::getState Jul 05 14:54:09 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:54:09 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:54:09 volumio volumio[932]: info: Jul 05 14:54:09 volumio volumio[932]: ---------------------------- MPD announces state update: update Jul 05 14:54:09 volumio volumio[932]: info: ControllerMpd::getState Jul 05 14:54:09 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:54:09 volumio volumio[932]: info: sendMpdCommand status took 4 milliseconds Jul 05 14:54:09 volumio volumio[932]: info: sendMpdCommand status took 4 milliseconds Jul 05 14:54:09 volumio volumio[932]: info: sendMpdCommand status took 3 milliseconds Jul 05 14:54:09 volumio volumio[932]: info: sendMpdCommand status took 2 milliseconds Jul 05 14:54:09 volumio volumio[932]: verbose: ControllerMpd::parseState Jul 05 14:54:09 volumio volumio[932]: info: Command Router : Notfying DB Updatetrue Jul 05 14:54:09 volumio volumio[932]: verbose: ControllerMpd::parseState Jul 05 14:54:09 volumio volumio[932]: verbose: ControllerMpd::parseState Jul 05 14:54:09 volumio volumio[932]: info: Command Router : Notfying DB Updatetrue Jul 05 14:54:09 volumio volumio[932]: info: CoreCommandRouter::Close All Modals sent Jul 05 14:54:09 volumio volumio[932]: verbose: ControllerMpd::parseState Jul 05 14:54:09 volumio volumio[932]: info: ControllerMpd::pushState Jul 05 14:54:09 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:54:09 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:54:09 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:54:09 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 05 14:54:09 volumio volumio[932]: 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} Jul 05 14:54:09 volumio volumio[932]: verbose: CURRENT POSITION 0 Jul 05 14:54:09 volumio volumio[932]: info: CoreStateMachine::syncState stateService stop Jul 05 14:54:09 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus stop Jul 05 14:54:09 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:54:09 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:54:09 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:54:09 volumio volumio[932]: info: No code Jul 05 14:54:09 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:54:09 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:54:09 volumio volumio[932]: info: ControllerMpd::pushState Jul 05 14:54:09 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:54:09 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:54:09 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:54:09 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 05 14:54:09 volumio volumio[932]: 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} Jul 05 14:54:09 volumio volumio[932]: verbose: CURRENT POSITION 0 Jul 05 14:54:09 volumio volumio[932]: info: CoreStateMachine::syncState stateService stop Jul 05 14:54:09 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus stop Jul 05 14:54:09 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:54:09 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:54:09 volumio volumio[932]: info: No code Jul 05 14:54:09 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:54:09 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:54:10 volumio volumio[932]: info: ------------------------------ 93ms Jul 05 14:54:10 volumio volumio[932]: info: ------------------------------ 92ms Jul 05 14:54:10 volumio volumio[932]: info: [1751720050005] Database update started Jul 05 14:54:10 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:54:10 volumio volumio[932]: info: Jul 05 14:54:10 volumio volumio[932]: ---------------------------- MPD announces state update: update Jul 05 14:54:10 volumio volumio[932]: info: ControllerMpd::getState Jul 05 14:54:10 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:54:10 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:54:10 volumio volumio[932]: info: Jul 05 14:54:10 volumio volumio[932]: ---------------------------- MPD announces state update: update Jul 05 14:54:10 volumio volumio[932]: info: ControllerMpd::getState Jul 05 14:54:10 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:54:10 volumio volumio[932]: info: sendMpdCommand status took 8 milliseconds Jul 05 14:54:10 volumio volumio[932]: info: sendMpdCommand status took 8 milliseconds Jul 05 14:54:10 volumio volumio[932]: info: sendMpdCommand status took 9 milliseconds Jul 05 14:54:10 volumio volumio[932]: info: sendMpdCommand status took 8 milliseconds Jul 05 14:54:10 volumio volumio[932]: verbose: ControllerMpd::parseState Jul 05 14:54:10 volumio volumio[932]: info: Command Router : Notfying DB Updatefalse Jul 05 14:54:10 volumio volumio[932]: info: CoreCommandRouter::Close All Modals sent Jul 05 14:54:10 volumio volumio[932]: verbose: ControllerMpd::parseState Jul 05 14:54:10 volumio volumio[932]: verbose: ControllerMpd::parseState Jul 05 14:54:10 volumio volumio[932]: info: Command Router : Notfying DB Updatefalse Jul 05 14:54:10 volumio volumio[932]: info: CoreCommandRouter::Close All Modals sent Jul 05 14:54:10 volumio volumio[932]: verbose: ControllerMpd::parseState Jul 05 14:54:10 volumio volumio[932]: info: ControllerMpd::pushState Jul 05 14:54:10 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:54:10 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:54:10 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:54:10 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 05 14:54:10 volumio volumio[932]: 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} Jul 05 14:54:10 volumio volumio[932]: verbose: CURRENT POSITION 0 Jul 05 14:54:10 volumio volumio[932]: info: CoreStateMachine::syncState stateService stop Jul 05 14:54:10 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus stop Jul 05 14:54:10 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:54:10 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:54:10 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:54:10 volumio volumio[932]: info: No code Jul 05 14:54:10 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:54:10 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:54:10 volumio volumio[932]: info: ControllerMpd::pushState Jul 05 14:54:10 volumio volumio[932]: info: CoreCommandRouter::servicePushState Jul 05 14:54:10 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:54:10 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:54:10 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Jul 05 14:54:10 volumio volumio[932]: 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} Jul 05 14:54:10 volumio volumio[932]: verbose: CURRENT POSITION 0 Jul 05 14:54:10 volumio volumio[932]: info: CoreStateMachine::syncState stateService stop Jul 05 14:54:10 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus stop Jul 05 14:54:10 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:54:10 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:54:10 volumio volumio[932]: info: No code Jul 05 14:54:10 volumio volumio[932]: info: CoreStateMachine::pushState Jul 05 14:54:10 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Jul 05 14:54:10 volumio volumio[932]: info: ------------------------------ 88ms Jul 05 14:54:10 volumio volumio[932]: info: ------------------------------ 88ms Jul 05 14:54:10 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 05 14:54:12 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:54:16 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:54:19 volumio ntpd[815]: 89.25.168.148 local addr 192.168.2.142 -> Jul 05 14:54:20 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:54:22 volumio volumio[932]: info: CoreCommandRouter::volumioGetState Jul 05 14:54:24 volumio volumio[932]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:54:24 volumio volumio[932]: info: CoreStateMachine::getQueue Jul 05 14:54:24 volumio volumio[932]: info: CorePlayQueue::getQueue Jul 05 14:54:27 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 05 14:54:27 volumio volumio[932]: info: CURURI: music-library Jul 05 14:54:27 volumio volumio[932]: info: Preload queue cleared Jul 05 14:54:31 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 05 14:54:31 volumio volumio[932]: info: CURURI: music-library/NAS Jul 05 14:54:31 volumio volumio[932]: info: Preload queue cleared Jul 05 14:54:33 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 05 14:54:33 volumio volumio[932]: info: CURURI: music-library/NAS/Synology Jul 05 14:54:33 volumio volumio[932]: error: Failed LSINFO: null Jul 05 14:54:33 volumio volumio[932]: info: Preload queue cleared Jul 05 14:54:45 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 05 14:54:45 volumio volumio[932]: info: Preload queue cleared Jul 05 14:54:46 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 05 14:54:46 volumio volumio[932]: info: Preload queue cleared Jul 05 14:54:47 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 05 14:54:47 volumio volumio[932]: info: Preload queue cleared Jul 05 14:54:50 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 05 14:54:50 volumio volumio[932]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 14:54:50 volumio volumio[932]: TypeError: Cannot read property 'length' of undefined Jul 05 14:54:50 volumio volumio[932]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 05 14:54:50 volumio volumio[932]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 05 14:54:50 volumio volumio[932]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 05 14:54:50 volumio volumio[932]: at Parser.emit (events.js:315:20) Jul 05 14:54:50 volumio volumio[932]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 05 14:54:50 volumio volumio[932]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 05 14:54:50 volumio volumio[932]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 05 14:54:50 volumio volumio[932]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 05 14:54:50 volumio volumio[932]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 05 14:54:50 volumio volumio[932]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 05 14:54:50 volumio volumio[932]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 05 14:54:50 volumio volumio[932]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 05 14:54:50 volumio volumio[932]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 05 14:54:50 volumio volumio[932]: at IncomingMessage.emit (events.js:327:22) Jul 05 14:54:50 volumio volumio[932]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 05 14:54:50 volumio volumio[932]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 05 14:54:50 volumio volumio[932]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 14:54:51 volumio sudo[3828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 14:53 Jul 05 14:54:51 volumio sudo[3828]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"