-- Logs begin at Thu 2019-02-14 19:11:58 JST, end at Mon 2025-12-08 21:16:35 JST. -- Dec 08 21:15:00 volumio volumio[1044]: info: Error browsing device MyMusicStorage for shares: Command failed: smbclient --debuglevel=4 -L 192.168.197.108 -N -m SMB3_11 Dec 08 21:15:00 volumio volumio[1044]: lp_load_ex: refreshing parameters Dec 08 21:15:00 volumio volumio[1044]: Initialising global parameters Dec 08 21:15:00 volumio volumio[1044]: Processing section "[global]" Dec 08 21:15:00 volumio volumio[1044]: doing parameter netbios name = Volumio Dec 08 21:15:00 volumio volumio[1044]: doing parameter server string = Volumio Audiophile Music Player Dec 08 21:15:00 volumio volumio[1044]: doing parameter workgroup = WORKGROUP Dec 08 21:15:00 volumio volumio[1044]: doing parameter security = user Dec 08 21:15:00 volumio volumio[1044]: doing parameter map to guest = Bad User Dec 08 21:15:00 volumio volumio[1044]: doing parameter encrypt passwords = yes Dec 08 21:15:00 volumio volumio[1044]: doing parameter wins support = yes Dec 08 21:15:00 volumio volumio[1044]: doing parameter local master = no Dec 08 21:15:00 volumio volumio[1044]: doing parameter preferred master = no Dec 08 21:15:00 volumio volumio[1044]: doing parameter os level = 30 Dec 08 21:15:00 volumio volumio[1044]: doing parameter fruit:model = AirPort Dec 08 21:15:00 volumio volumio[1044]: pm_process() returned Yes Dec 08 21:15:00 volumio volumio[1044]: directory_create_or_exist_strict: invalid ownership on directory /var/run/samba/msg.lock Dec 08 21:15:00 volumio volumio[1044]: cmdline_messaging_context: Unable to initialize messaging context. Dec 08 21:15:00 volumio volumio[1044]: Unable to initialize messaging context Dec 08 21:15:00 volumio volumio[1044]: lp_load_ex: refreshing parameters Dec 08 21:15:00 volumio volumio[1044]: Initialising global parameters Dec 08 21:15:00 volumio volumio[1044]: Processing section "[global]" Dec 08 21:15:00 volumio volumio[1044]: doing parameter netbios name = Volumio Dec 08 21:15:00 volumio volumio[1044]: doing parameter server string = Volumio Audiophile Music Player Dec 08 21:15:00 volumio volumio[1044]: doing parameter workgroup = WORKGROUP Dec 08 21:15:00 volumio volumio[1044]: doing parameter security = user Dec 08 21:15:00 volumio volumio[1044]: doing parameter map to guest = Bad User Dec 08 21:15:00 volumio volumio[1044]: doing parameter encrypt passwords = yes Dec 08 21:15:00 volumio volumio[1044]: doing parameter wins support = yes Dec 08 21:15:00 volumio volumio[1044]: doing parameter local master = no Dec 08 21:15:00 volumio volumio[1044]: doing parameter preferred master = no Dec 08 21:15:00 volumio volumio[1044]: doing parameter os level = 30 Dec 08 21:15:00 volumio volumio[1044]: doing parameter fruit:model = AirPort Dec 08 21:15:00 volumio volumio[1044]: pm_process() returned Yes Dec 08 21:15:00 volumio volumio[1044]: added interface wlan0 ip=192.168.20.105 bcast=192.168.20.255 netmask=255.255.255.0 Dec 08 21:15:00 volumio volumio[1044]: added interface eth0 ip=192.168.197.132 bcast=192.168.197.255 netmask=255.255.255.0 Dec 08 21:15:00 volumio volumio[1044]: Client started (version 4.9.5-Debian). Dec 08 21:15:00 volumio volumio[1044]: Connecting to 192.168.197.108 at port 445 Dec 08 21:15:00 volumio volumio[1044]: session request ok Dec 08 21:15:00 volumio volumio[1044]: negotiated dialect[SMB3_11] against server[192.168.197.108] Dec 08 21:15:00 volumio volumio[1044]: got OID=1.3.6.1.4.1.311.2.2.30 Dec 08 21:15:00 volumio volumio[1044]: got OID=1.3.6.1.4.1.311.2.2.10 Dec 08 21:15:00 volumio volumio[1044]: GENSEC backend 'gssapi_spnego' registered Dec 08 21:15:00 volumio volumio[1044]: GENSEC backend 'gssapi_krb5' registered Dec 08 21:15:00 volumio volumio[1044]: GENSEC backend 'gssapi_krb5_sasl' registered Dec 08 21:15:00 volumio volumio[1044]: GENSEC backend 'spnego' registered Dec 08 21:15:00 volumio volumio[1044]: GENSEC backend 'schannel' registered Dec 08 21:15:00 volumio volumio[1044]: GENSEC backend 'naclrpc_as_system' registered Dec 08 21:15:00 volumio volumio[1044]: GENSEC backend 'sasl-EXTERNAL' registered Dec 08 21:15:00 volumio volumio[1044]: GENSEC backend 'ntlmssp' registered Dec 08 21:15:00 volumio volumio[1044]: GENSEC backend 'ntlmssp_resume_ccache' registered Dec 08 21:15:00 volumio volumio[1044]: GENSEC backend 'http_basic' registered Dec 08 21:15:00 volumio volumio[1044]: GENSEC backend 'http_ntlm' registered Dec 08 21:15:00 volumio volumio[1044]: GENSEC backend 'http_negotiate' registered Dec 08 21:15:00 volumio volumio[1044]: GENSEC backend 'krb5' registered Dec 08 21:15:00 volumio volumio[1044]: GENSEC backend 'fake_gssapi_krb5' registered Dec 08 21:15:00 volumio volumio[1044]: Got challenge flags: Dec 08 21:15:00 volumio volumio[1044]: Got NTLMSSP neg_flags=0x628a8215 Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_UNICODE Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_REQUEST_TARGET Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_SIGN Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_NTLM Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_TARGET_TYPE_SERVER Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_TARGET_INFO Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_VERSION Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_128 Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_KEY_EXCH Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP: Set final flags: Dec 08 21:15:00 volumio volumio[1044]: Got NTLMSSP neg_flags=0x62008215 Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_UNICODE Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_REQUEST_TARGET Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_SIGN Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_NTLM Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_VERSION Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_128 Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_KEY_EXCH Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP Sign/Seal - Initialising with flags: Dec 08 21:15:00 volumio volumio[1044]: Got NTLMSSP neg_flags=0x62008215 Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_UNICODE Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_REQUEST_TARGET Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_SIGN Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_NTLM Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_VERSION Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_128 Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_KEY_EXCH Dec 08 21:15:00 volumio volumio[1044]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. Dec 08 21:15:00 volumio volumio[1044]: got OID=1.3.6.1.4.1.311.2.2.30 Dec 08 21:15:00 volumio volumio[1044]: got OID=1.3.6.1.4.1.311.2.2.10 Dec 08 21:15:00 volumio volumio[1044]: Got challenge flags: Dec 08 21:15:00 volumio volumio[1044]: Got NTLMSSP neg_flags=0x628a8215 Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_UNICODE Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_REQUEST_TARGET Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_SIGN Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_NTLM Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_TARGET_TYPE_SERVER Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_TARGET_INFO Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_VERSION Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_128 Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_KEY_EXCH Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP: Set final flags: Dec 08 21:15:00 volumio volumio[1044]: Got NTLMSSP neg_flags=0x62008a15 Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_UNICODE Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_REQUEST_TARGET Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_SIGN Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_NTLM Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_ANONYMOUS Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_VERSION Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_128 Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_KEY_EXCH Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP Sign/Seal - Initialising with flags: Dec 08 21:15:00 volumio volumio[1044]: Got NTLMSSP neg_flags=0x62008a15 Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_UNICODE Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_REQUEST_TARGET Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_SIGN Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_NTLM Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_ANONYMOUS Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_VERSION Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_128 Dec 08 21:15:00 volumio volumio[1044]: NTLMSSP_NEGOTIATE_KEY_EXCH Dec 08 21:15:00 volumio volumio[1044]: SPNEGO login failed: {Access Denied} A process has requested access to an object but has not been granted those access rights. Dec 08 21:15:00 volumio volumio[1044]: info: Detected device MyMusicStorage with version null Dec 08 21:15:00 volumio sudo[2093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=@rs1174001122,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.197.108/music /mnt/NAS/MyMusicStorage Dec 08 21:15:00 volumio sudo[2093]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 08 21:15:00 volumio kernel: CIFS: Attempting to mount //192.168.197.108/music Dec 08 21:15:00 volumio sudo[2093]: pam_unix(sudo:session): session closed for user root Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:00 volumio volumio[1044]: info: Dec 08 21:15:00 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:00 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:00 volumio volumio[1044]: info: Dec 08 21:15:00 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:00 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:00 volumio volumio[1044]: info: Dec 08 21:15:00 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:00 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:00 volumio volumio[1044]: info: sendMpdCommand status took 10 milliseconds Dec 08 21:15:00 volumio volumio[1044]: info: sendMpdCommand status took 7 milliseconds Dec 08 21:15:00 volumio volumio[1044]: info: sendMpdCommand status took 7 milliseconds Dec 08 21:15:00 volumio volumio[1044]: info: sendMpdCommand status took 6 milliseconds Dec 08 21:15:00 volumio volumio[1044]: info: sendMpdCommand status took 5 milliseconds Dec 08 21:15:00 volumio volumio[1044]: info: sendMpdCommand status took 4 milliseconds Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:00 volumio volumio[1044]: info: Command Router : Notfying DB Updatetrue Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:00 volumio volumio[1044]: info: Command Router : Notfying DB Updatetrue Dec 08 21:15:00 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:00 volumio volumio[1044]: info: Command Router : Notfying DB Updatetrue Dec 08 21:15:00 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:00 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:00 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:00 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:00 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:00 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:00 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:00 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:00 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:00 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:00 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:00 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:00 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:00 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:00 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:00 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:00 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:00 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:00 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:00 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:00 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:00 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:00 volumio volumio[1044]: info: ------------------------------ 106ms Dec 08 21:15:00 volumio volumio[1044]: info: ------------------------------ 104ms Dec 08 21:15:00 volumio volumio[1044]: info: ------------------------------ 102ms Dec 08 21:15:00 volumio volumio[1044]: info: [1765196100732] Database update started Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:00 volumio volumio[1044]: info: Dec 08 21:15:00 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:00 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:00 volumio volumio[1044]: info: Dec 08 21:15:00 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:00 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:00 volumio volumio[1044]: info: Dec 08 21:15:00 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:00 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:00 volumio volumio[1044]: info: sendMpdCommand status took 9 milliseconds Dec 08 21:15:00 volumio volumio[1044]: info: sendMpdCommand status took 8 milliseconds Dec 08 21:15:00 volumio volumio[1044]: info: sendMpdCommand status took 8 milliseconds Dec 08 21:15:00 volumio volumio[1044]: info: sendMpdCommand status took 8 milliseconds Dec 08 21:15:00 volumio volumio[1044]: info: sendMpdCommand status took 7 milliseconds Dec 08 21:15:00 volumio volumio[1044]: info: sendMpdCommand status took 7 milliseconds Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:00 volumio volumio[1044]: info: Command Router : Notfying DB Updatefalse Dec 08 21:15:00 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:00 volumio volumio[1044]: info: Command Router : Notfying DB Updatefalse Dec 08 21:15:00 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:00 volumio volumio[1044]: info: Command Router : Notfying DB Updatefalse Dec 08 21:15:00 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:00 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:00 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:00 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:00 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:00 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:00 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:00 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:00 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:00 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:00 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:00 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:00 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:00 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:00 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:00 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:00 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:00 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:00 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:00 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:00 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:00 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:00 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:00 volumio volumio[1044]: info: ------------------------------ 89ms Dec 08 21:15:00 volumio volumio[1044]: info: ------------------------------ 89ms Dec 08 21:15:00 volumio volumio[1044]: info: ------------------------------ 87ms Dec 08 21:15:01 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 08 21:15:02 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:15:06 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:15:10 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand rescan Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:13 volumio volumio[1044]: info: Dec 08 21:15:13 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:13 volumio volumio[1044]: info: sendMpdCommand rescan took 3 milliseconds Dec 08 21:15:13 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:13 volumio volumio[1044]: info: Dec 08 21:15:13 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:13 volumio volumio[1044]: info: sendMpdCommand status took 4 milliseconds Dec 08 21:15:13 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:13 volumio volumio[1044]: info: sendMpdCommand status took 2 milliseconds Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:13 volumio volumio[1044]: info: Command Router : Notfying DB Updatetrue Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:13 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:13 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:13 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:13 volumio volumio[1044]: info: ------------------------------ 43ms Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:13 volumio volumio[1044]: info: Dec 08 21:15:13 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:13 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:13 volumio volumio[1044]: info: Dec 08 21:15:13 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:13 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:13 volumio volumio[1044]: info: sendMpdCommand status took 45 milliseconds Dec 08 21:15:13 volumio volumio[1044]: info: sendMpdCommand status took 43 milliseconds Dec 08 21:15:13 volumio volumio[1044]: info: sendMpdCommand status took 4 milliseconds Dec 08 21:15:13 volumio volumio[1044]: info: sendMpdCommand status took 3 milliseconds Dec 08 21:15:13 volumio volumio[1044]: info: sendMpdCommand status took 3 milliseconds Dec 08 21:15:13 volumio volumio[1044]: info: sendMpdCommand status took 2 milliseconds Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:13 volumio volumio[1044]: info: Command Router : Notfying DB Updatefalse Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:13 volumio volumio[1044]: info: Command Router : Notfying DB Updatefalse Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:13 volumio volumio[1044]: info: Command Router : Notfying DB Updatefalse Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:13 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:13 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:13 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:13 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:13 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:13 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:13 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:13 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:13 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:13 volumio volumio[1044]: info: ------------------------------ 130ms Dec 08 21:15:13 volumio volumio[1044]: info: ------------------------------ 89ms Dec 08 21:15:13 volumio volumio[1044]: info: ------------------------------ 89ms Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:13 volumio volumio[1044]: info: Dec 08 21:15:13 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:13 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:13 volumio volumio[1044]: info: Dec 08 21:15:13 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:13 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:13 volumio volumio[1044]: info: sendMpdCommand status took 9 milliseconds Dec 08 21:15:13 volumio volumio[1044]: info: sendMpdCommand status took 8 milliseconds Dec 08 21:15:13 volumio volumio[1044]: info: sendMpdCommand status took 7 milliseconds Dec 08 21:15:13 volumio volumio[1044]: info: sendMpdCommand status took 6 milliseconds Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:13 volumio volumio[1044]: info: Command Router : Notfying DB Updatefalse Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:13 volumio volumio[1044]: info: Command Router : Notfying DB Updatefalse Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:13 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:13 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:13 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:13 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:13 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:13 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:13 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:13 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:13 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:13 volumio volumio[1044]: info: ------------------------------ 65ms Dec 08 21:15:13 volumio volumio[1044]: info: ------------------------------ 63ms Dec 08 21:15:14 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:15:18 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:15:22 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:15:26 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:15:30 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:15:34 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand update Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:38 volumio volumio[1044]: info: Dec 08 21:15:38 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:38 volumio volumio[1044]: info: sendMpdCommand update took 2 milliseconds Dec 08 21:15:38 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:38 volumio volumio[1044]: info: Dec 08 21:15:38 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:38 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:38 volumio volumio[1044]: info: Dec 08 21:15:38 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:38 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:38 volumio volumio[1044]: info: Dec 08 21:15:38 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:38 volumio volumio[1044]: info: sendMpdCommand status took 11 milliseconds Dec 08 21:15:38 volumio volumio[1044]: info: sendMpdCommand status took 11 milliseconds Dec 08 21:15:38 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:38 volumio volumio[1044]: info: sendMpdCommand status took 8 milliseconds Dec 08 21:15:38 volumio volumio[1044]: info: sendMpdCommand status took 7 milliseconds Dec 08 21:15:38 volumio volumio[1044]: info: sendMpdCommand status took 6 milliseconds Dec 08 21:15:38 volumio volumio[1044]: info: sendMpdCommand status took 4 milliseconds Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:38 volumio volumio[1044]: info: Command Router : Notfying DB Updatetrue Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:38 volumio volumio[1044]: info: Command Router : Notfying DB Updatefalse Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:38 volumio volumio[1044]: info: Command Router : Notfying DB Updatefalse Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:38 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:38 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:38 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:38 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:38 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:38 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:38 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:38 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:38 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:38 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:38 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:38 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:38 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:38 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:38 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:38 volumio volumio[1044]: info: ------------------------------ 94ms Dec 08 21:15:38 volumio volumio[1044]: info: ------------------------------ 90ms Dec 08 21:15:38 volumio volumio[1044]: info: ------------------------------ 87ms Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:38 volumio volumio[1044]: info: Dec 08 21:15:38 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:38 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:38 volumio volumio[1044]: info: Dec 08 21:15:38 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:38 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:38 volumio volumio[1044]: info: sendMpdCommand status took 90 milliseconds Dec 08 21:15:38 volumio volumio[1044]: info: sendMpdCommand status took 89 milliseconds Dec 08 21:15:38 volumio volumio[1044]: info: sendMpdCommand status took 5 milliseconds Dec 08 21:15:38 volumio volumio[1044]: info: sendMpdCommand status took 3 milliseconds Dec 08 21:15:38 volumio volumio[1044]: info: sendMpdCommand status took 3 milliseconds Dec 08 21:15:38 volumio volumio[1044]: info: sendMpdCommand status took 2 milliseconds Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:38 volumio volumio[1044]: info: Command Router : Notfying DB Updatefalse Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:38 volumio volumio[1044]: info: Command Router : Notfying DB Updatefalse Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:38 volumio volumio[1044]: info: Command Router : Notfying DB Updatefalse Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:38 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:38 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:38 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:38 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:38 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:38 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:38 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:38 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:38 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:38 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:38 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:38 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:38 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:38 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:38 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:38 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:38 volumio volumio[1044]: info: ------------------------------ 174ms Dec 08 21:15:38 volumio volumio[1044]: info: ------------------------------ 90ms Dec 08 21:15:38 volumio volumio[1044]: info: ------------------------------ 87ms Dec 08 21:15:38 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand rescan Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:40 volumio volumio[1044]: info: Dec 08 21:15:40 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:40 volumio volumio[1044]: info: sendMpdCommand rescan took 2 milliseconds Dec 08 21:15:40 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:40 volumio volumio[1044]: info: Dec 08 21:15:40 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:40 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:40 volumio volumio[1044]: info: Dec 08 21:15:40 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:40 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:40 volumio volumio[1044]: info: Dec 08 21:15:40 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:40 volumio volumio[1044]: info: sendMpdCommand status took 5 milliseconds Dec 08 21:15:40 volumio volumio[1044]: info: sendMpdCommand status took 4 milliseconds Dec 08 21:15:40 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:40 volumio volumio[1044]: info: sendMpdCommand status took 4 milliseconds Dec 08 21:15:40 volumio volumio[1044]: info: sendMpdCommand status took 3 milliseconds Dec 08 21:15:40 volumio volumio[1044]: info: sendMpdCommand status took 3 milliseconds Dec 08 21:15:40 volumio volumio[1044]: info: sendMpdCommand status took 2 milliseconds Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:40 volumio volumio[1044]: info: Command Router : Notfying DB Updatetrue Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:40 volumio volumio[1044]: info: Command Router : Notfying DB Updatefalse Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:40 volumio volumio[1044]: info: Command Router : Notfying DB Updatefalse Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:40 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:40 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:40 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:40 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:40 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:40 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:40 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:40 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:40 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:40 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:40 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:40 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:40 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:40 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:40 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:40 volumio volumio[1044]: info: ------------------------------ 86ms Dec 08 21:15:40 volumio volumio[1044]: info: ------------------------------ 85ms Dec 08 21:15:40 volumio volumio[1044]: info: ------------------------------ 84ms Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:40 volumio volumio[1044]: info: Dec 08 21:15:40 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:40 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:40 volumio volumio[1044]: info: Dec 08 21:15:40 volumio volumio[1044]: ---------------------------- MPD announces state update: update Dec 08 21:15:40 volumio volumio[1044]: info: ControllerMpd::getState Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:15:40 volumio volumio[1044]: info: sendMpdCommand status took 86 milliseconds Dec 08 21:15:40 volumio volumio[1044]: info: sendMpdCommand status took 85 milliseconds Dec 08 21:15:40 volumio volumio[1044]: info: sendMpdCommand status took 3 milliseconds Dec 08 21:15:40 volumio volumio[1044]: info: sendMpdCommand status took 2 milliseconds Dec 08 21:15:40 volumio volumio[1044]: info: sendMpdCommand status took 3 milliseconds Dec 08 21:15:40 volumio volumio[1044]: info: sendMpdCommand status took 2 milliseconds Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:40 volumio volumio[1044]: info: Command Router : Notfying DB Updatefalse Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:40 volumio volumio[1044]: info: Command Router : Notfying DB Updatefalse Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:40 volumio volumio[1044]: info: Command Router : Notfying DB Updatefalse Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::Close All Modals sent Dec 08 21:15:40 volumio volumio[1044]: verbose: ControllerMpd::parseState Dec 08 21:15:40 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:40 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:40 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:40 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:40 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:40 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:40 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:40 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:40 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:40 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:40 volumio volumio[1044]: info: ControllerMpd::pushState Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::servicePushState Dec 08 21:15:40 volumio volumio[1044]: info: CoreStateMachine::pushState Dec 08 21:15:40 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:40 volumio volumio[1044]: info: CoreCommandRouter::volumioPushState Dec 08 21:15:40 volumio volumio[1044]: info: CorePlayQueue::getTrack 0 Dec 08 21:15:40 volumio volumio[1044]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 08 21:15:40 volumio volumio[1044]: info: ------------------------------ 162ms Dec 08 21:15:40 volumio volumio[1044]: info: ------------------------------ 80ms Dec 08 21:15:40 volumio volumio[1044]: info: ------------------------------ 79ms Dec 08 21:15:42 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:15:46 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:15:50 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:15:54 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:15:58 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:16:02 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:16:06 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:16:11 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:16:15 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:16:19 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:16:19 volumio ntpd[732]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 08 21:16:23 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:16:27 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:16:30 volumio sudo[1788]: pam_unix(sudo:session): session closed for user root Dec 08 21:16:30 volumio volumio[1044]: info: Cannot mount NAS MyMusicStorage at system boot, trial number 2 ,retrying in 5 seconds Dec 08 21:16:31 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:16:35 volumio volumio[1044]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 08 21:16:35 volumio volumio[1044]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 08 21:16:35 volumio volumio[1044]: TypeError: Cannot read property 'replace' of undefined Dec 08 21:16:35 volumio volumio[1044]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Dec 08 21:16:35 volumio volumio[1044]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Dec 08 21:16:35 volumio volumio[1044]: at listOnTimeout (internal/timers.js:554:17) Dec 08 21:16:35 volumio volumio[1044]: at processTimers (internal/timers.js:497:7) Dec 08 21:16:35 volumio volumio[1044]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 08 21:16:35 volumio sudo[2364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-08 21:15 Dec 08 21:16:35 volumio sudo[2364]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"