Feb 08 20:12:01 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:12:03 volumio volumio[1159]: info: MyVolumio login type: Token Feb 08 20:12:04 volumio volumio[1159]: (node:1159) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Feb 08 20:12:05 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:12:09 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:12:13 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Feb 08 20:12:13 volumio volumio[1159]: info: Share mp3 successfully unmounted Feb 08 20:12:13 volumio sudo[3014]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=yousun,password=yousun,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.3.33/volume1\\mp3 /mnt/NAS/mp3 Feb 08 20:12:13 volumio sudo[3014]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 20:12:13 volumio kernel: CIFS: Attempting to mount //192.168.3.33/volume1/mp3 Feb 08 20:12:13 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:12:13 volumio sudo[3014]: pam_unix(sudo:session): session closed for user root Feb 08 20:12:13 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -2 Feb 08 20:12:13 volumio volumio[1159]: info: An error occurred mounting the new share. Rolling back configuration Feb 08 20:12:14 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 08 20:12:17 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:12:18 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Feb 08 20:12:19 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 08 20:12:20 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Feb 08 20:12:20 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Feb 08 20:12:21 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:12:25 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:12:29 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:12:32 volumio ntpd[976]: PROTO: 117.80.231.60 unlink local addr 192.168.3.15 -> Feb 08 20:12:33 volumio volumio[1159]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 08 20:12:33 volumio volumio[1159]: info: Retrying Login Due to Network Error Feb 08 20:12:33 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:12:33 volumio ntpd[976]: PROTO: 193.182.111.14 unlink local addr 192.168.3.15 -> Feb 08 20:12:37 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:12:41 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:12:43 volumio volumio[1159]: info: MyVolumio login type: Token Feb 08 20:12:44 volumio volumio[1159]: (node:1159) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Feb 08 20:12:45 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:12:49 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:12:51 volumio systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Feb 08 20:12:51 volumio systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Feb 08 20:12:51 volumio systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Feb 08 20:12:51 volumio systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. Feb 08 20:12:53 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:12:57 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:13:01 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:13:05 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:13:07 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Feb 08 20:13:07 volumio volumio[1159]: info: Adding a new share Feb 08 20:13:07 volumio volumio[1159]: info: No correspondence found in configuration for share mp3 on IP 192.168.3.33 Feb 08 20:13:07 volumio volumio[1159]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.3.33 -N -m SMB3_11 Feb 08 20:13:07 volumio volumio[1159]: info: Error browsing device mp3 for shares: Command failed: smbclient --debuglevel=4 -L 192.168.3.33 -N -m SMB3_11 Feb 08 20:13:07 volumio volumio[1159]: lp_load_ex: refreshing parameters Feb 08 20:13:07 volumio volumio[1159]: Initialising global parameters Feb 08 20:13:07 volumio volumio[1159]: Processing section "[global]" Feb 08 20:13:07 volumio volumio[1159]: doing parameter netbios name = Volumio Feb 08 20:13:07 volumio volumio[1159]: doing parameter server string = Volumio Audiophile Music Player Feb 08 20:13:07 volumio volumio[1159]: doing parameter workgroup = WORKGROUP Feb 08 20:13:07 volumio volumio[1159]: doing parameter security = user Feb 08 20:13:07 volumio volumio[1159]: doing parameter map to guest = Bad User Feb 08 20:13:07 volumio volumio[1159]: doing parameter encrypt passwords = yes Feb 08 20:13:07 volumio volumio[1159]: lpcfg_do_global_parameter: WARNING: The "encrypt passwords" option is deprecated Feb 08 20:13:07 volumio volumio[1159]: doing parameter wins support = yes Feb 08 20:13:07 volumio volumio[1159]: doing parameter local master = no Feb 08 20:13:07 volumio volumio[1159]: doing parameter preferred master = no Feb 08 20:13:07 volumio volumio[1159]: doing parameter os level = 30 Feb 08 20:13:07 volumio volumio[1159]: doing parameter fruit:model = AirPort Feb 08 20:13:07 volumio volumio[1159]: pm_process() returned Yes Feb 08 20:13:07 volumio volumio[1159]: added interface wlan0 ip=192.168.3.15 bcast=192.168.3.255 netmask=255.255.255.0 Feb 08 20:13:07 volumio volumio[1159]: Client started (version 4.17.8-Raspbian). Feb 08 20:13:07 volumio volumio[1159]: Connecting to 192.168.3.33 at port 445 Feb 08 20:13:07 volumio volumio[1159]: Connecting to 192.168.3.33 at port 139 Feb 08 20:13:07 volumio volumio[1159]: session request ok Feb 08 20:13:07 volumio volumio[1159]: negotiated dialect[SMB3_11] against server[192.168.3.33] Feb 08 20:13:07 volumio volumio[1159]: GENSEC backend 'gssapi_spnego' registered Feb 08 20:13:07 volumio volumio[1159]: GENSEC backend 'gssapi_krb5' registered Feb 08 20:13:07 volumio volumio[1159]: GENSEC backend 'gssapi_krb5_sasl' registered Feb 08 20:13:07 volumio volumio[1159]: GENSEC backend 'spnego' registered Feb 08 20:13:07 volumio volumio[1159]: GENSEC backend 'schannel' registered Feb 08 20:13:07 volumio volumio[1159]: GENSEC backend 'ncalrpc_as_system' registered Feb 08 20:13:07 volumio volumio[1159]: GENSEC backend 'sasl-EXTERNAL' registered Feb 08 20:13:07 volumio volumio[1159]: GENSEC backend 'ntlmssp' registered Feb 08 20:13:07 volumio volumio[1159]: GENSEC backend 'ntlmssp_resume_ccache' registered Feb 08 20:13:07 volumio volumio[1159]: GENSEC backend 'http_basic' registered Feb 08 20:13:07 volumio volumio[1159]: GENSEC backend 'http_ntlm' registered Feb 08 20:13:07 volumio volumio[1159]: GENSEC backend 'http_negotiate' registered Feb 08 20:13:07 volumio volumio[1159]: GENSEC backend 'krb5' registered Feb 08 20:13:07 volumio volumio[1159]: GENSEC backend 'fake_gssapi_krb5' registered Feb 08 20:13:07 volumio volumio[1159]: Cannot do GSE to an IP address Feb 08 20:13:07 volumio volumio[1159]: Failed to start GENSEC client mech gse_krb5: NT_STATUS_INVALID_PARAMETER Feb 08 20:13:07 volumio volumio[1159]: Got challenge flags: Feb 08 20:13:07 volumio volumio[1159]: Got NTLMSSP neg_flags=0x628a8215 Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_UNICODE Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_REQUEST_TARGET Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_SIGN Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_NTLM Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_TARGET_TYPE_SERVER Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_TARGET_INFO Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_VERSION Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_128 Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_KEY_EXCH Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP: Set final flags: Feb 08 20:13:07 volumio volumio[1159]: Got NTLMSSP neg_flags=0x62008215 Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_UNICODE Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_REQUEST_TARGET Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_SIGN Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_NTLM Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_VERSION Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_128 Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_KEY_EXCH Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP Sign/Seal - Initialising with flags: Feb 08 20:13:07 volumio volumio[1159]: Got NTLMSSP neg_flags=0x62008215 Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_UNICODE Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_REQUEST_TARGET Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_SIGN Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_NTLM Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_VERSION Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_128 Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_KEY_EXCH Feb 08 20:13:07 volumio volumio[1159]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. Feb 08 20:13:07 volumio volumio[1159]: Got challenge flags: Feb 08 20:13:07 volumio volumio[1159]: Got NTLMSSP neg_flags=0x628a8215 Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_UNICODE Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_REQUEST_TARGET Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_SIGN Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_NTLM Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_TARGET_TYPE_SERVER Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_TARGET_INFO Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_VERSION Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_128 Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_KEY_EXCH Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP: Set final flags: Feb 08 20:13:07 volumio volumio[1159]: Got NTLMSSP neg_flags=0x62008a15 Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_UNICODE Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_REQUEST_TARGET Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_SIGN Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_NTLM Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_ANONYMOUS Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_VERSION Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_128 Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_KEY_EXCH Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP Sign/Seal - Initialising with flags: Feb 08 20:13:07 volumio volumio[1159]: Got NTLMSSP neg_flags=0x62008a15 Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_UNICODE Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_REQUEST_TARGET Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_SIGN Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_NTLM Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_ANONYMOUS Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_VERSION Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_128 Feb 08 20:13:07 volumio volumio[1159]: NTLMSSP_NEGOTIATE_KEY_EXCH Feb 08 20:13:07 volumio volumio[1159]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. Feb 08 20:13:07 volumio volumio[1159]: info: Detected device mp3 with version null Feb 08 20:13:07 volumio sudo[3126]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=yousun,password=yousun,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.3.33/volume1/mp3 /mnt/NAS/mp3 Feb 08 20:13:07 volumio sudo[3126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 08 20:13:07 volumio kernel: CIFS: Attempting to mount //192.168.3.33/volume1/mp3 Feb 08 20:13:07 volumio sudo[3126]: pam_unix(sudo:session): session closed for user root Feb 08 20:13:07 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -2 Feb 08 20:13:07 volumio volumio[1159]: info: Error mounting mp3 on IP 192.168.3.33 : (2): No such file or directory Feb 08 20:13:07 volumio volumio[1159]: Refer to the mount.cifs(8) manual page (e.g. man mount.cifs) and kernel log messages (dmesg) Feb 08 20:13:08 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 08 20:13:09 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:13:13 volumio volumio[1159]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 08 20:13:13 volumio volumio[1159]: info: Retrying Login Due to Network Error Feb 08 20:13:13 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:13:17 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:13:21 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:13:23 volumio volumio[1159]: info: MyVolumio login type: Token Feb 08 20:13:24 volumio volumio[1159]: (node:1159) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Feb 08 20:13:25 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:13:26 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Feb 08 20:13:27 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 08 20:13:29 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand update Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:30 volumio volumio[1159]: info: Feb 08 20:13:30 volumio volumio[1159]: ---------------------------- MPD announces state update: update Feb 08 20:13:30 volumio volumio[1159]: info: sendMpdCommand update took 9 milliseconds Feb 08 20:13:30 volumio volumio[1159]: info: ControllerMpd::getState Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:30 volumio volumio[1159]: info: Feb 08 20:13:30 volumio volumio[1159]: ---------------------------- MPD announces state update: update Feb 08 20:13:30 volumio volumio[1159]: info: ControllerMpd::getState Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:30 volumio volumio[1159]: info: Feb 08 20:13:30 volumio volumio[1159]: ---------------------------- MPD announces state update: update Feb 08 20:13:30 volumio volumio[1159]: info: ControllerMpd::getState Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:30 volumio volumio[1159]: info: Feb 08 20:13:30 volumio volumio[1159]: ---------------------------- MPD announces state update: update Feb 08 20:13:30 volumio volumio[1159]: info: ControllerMpd::getState Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:30 volumio volumio[1159]: info: sendMpdCommand status took 14 milliseconds Feb 08 20:13:30 volumio volumio[1159]: info: sendMpdCommand status took 10 milliseconds Feb 08 20:13:30 volumio volumio[1159]: info: sendMpdCommand status took 10 milliseconds Feb 08 20:13:30 volumio volumio[1159]: info: sendMpdCommand status took 8 milliseconds Feb 08 20:13:30 volumio volumio[1159]: info: sendMpdCommand status took 7 milliseconds Feb 08 20:13:30 volumio volumio[1159]: info: sendMpdCommand status took 7 milliseconds Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:30 volumio volumio[1159]: info: Command Router : Notfying DB Updatefalse Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::Close All Modals sent Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:30 volumio volumio[1159]: info: Command Router : Notfying DB Updatefalse Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::Close All Modals sent Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:30 volumio volumio[1159]: info: Command Router : Notfying DB Updatefalse Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::Close All Modals sent Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:30 volumio volumio[1159]: info: ControllerMpd::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: 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} Feb 08 20:13:30 volumio volumio[1159]: verbose: CURRENT POSITION 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::syncState stateService stop Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus stop Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:30 volumio volumio[1159]: info: No code Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:30 volumio volumio[1159]: info: ControllerMpd::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: 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} Feb 08 20:13:30 volumio volumio[1159]: verbose: CURRENT POSITION 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::syncState stateService stop Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus stop Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:30 volumio volumio[1159]: info: No code Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:30 volumio volumio[1159]: info: ControllerMpd::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: 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} Feb 08 20:13:30 volumio volumio[1159]: verbose: CURRENT POSITION 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::syncState stateService stop Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus stop Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:30 volumio volumio[1159]: info: No code Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:30 volumio volumio[1159]: info: ------------------------------ 211ms Feb 08 20:13:30 volumio volumio[1159]: info: ------------------------------ 205ms Feb 08 20:13:30 volumio volumio[1159]: info: ------------------------------ 204ms Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:30 volumio volumio[1159]: info: Feb 08 20:13:30 volumio volumio[1159]: ---------------------------- MPD announces state update: update Feb 08 20:13:30 volumio volumio[1159]: info: ControllerMpd::getState Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:30 volumio volumio[1159]: info: Feb 08 20:13:30 volumio volumio[1159]: ---------------------------- MPD announces state update: update Feb 08 20:13:30 volumio volumio[1159]: info: ControllerMpd::getState Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:30 volumio volumio[1159]: info: sendMpdCommand status took 215 milliseconds Feb 08 20:13:30 volumio volumio[1159]: info: sendMpdCommand status took 214 milliseconds Feb 08 20:13:30 volumio volumio[1159]: info: sendMpdCommand status took 11 milliseconds Feb 08 20:13:30 volumio volumio[1159]: info: sendMpdCommand status took 9 milliseconds Feb 08 20:13:30 volumio volumio[1159]: info: sendMpdCommand status took 6 milliseconds Feb 08 20:13:30 volumio volumio[1159]: info: sendMpdCommand status took 4 milliseconds Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:30 volumio volumio[1159]: info: Command Router : Notfying DB Updatefalse Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::Close All Modals sent Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:30 volumio volumio[1159]: info: Command Router : Notfying DB Updatefalse Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::Close All Modals sent Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:30 volumio volumio[1159]: info: Command Router : Notfying DB Updatefalse Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::Close All Modals sent Feb 08 20:13:30 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:30 volumio volumio[1159]: info: ControllerMpd::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: 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} Feb 08 20:13:30 volumio volumio[1159]: verbose: CURRENT POSITION 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::syncState stateService stop Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus stop Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:30 volumio volumio[1159]: info: No code Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:30 volumio volumio[1159]: info: ControllerMpd::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: 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} Feb 08 20:13:30 volumio volumio[1159]: verbose: CURRENT POSITION 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::syncState stateService stop Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus stop Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:30 volumio volumio[1159]: info: No code Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:30 volumio volumio[1159]: info: ControllerMpd::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: 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} Feb 08 20:13:30 volumio volumio[1159]: verbose: CURRENT POSITION 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::syncState stateService stop Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus stop Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:30 volumio volumio[1159]: info: No code Feb 08 20:13:30 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:30 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:30 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:30 volumio volumio[1159]: info: ------------------------------ 372ms Feb 08 20:13:30 volumio volumio[1159]: info: ------------------------------ 169ms Feb 08 20:13:30 volumio volumio[1159]: info: ------------------------------ 164ms Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 08 20:13:31 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand rescan Feb 08 20:13:31 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:31 volumio volumio[1159]: info: Feb 08 20:13:31 volumio volumio[1159]: ---------------------------- MPD announces state update: update Feb 08 20:13:31 volumio volumio[1159]: info: sendMpdCommand rescan took 5 milliseconds Feb 08 20:13:31 volumio volumio[1159]: info: ControllerMpd::getState Feb 08 20:13:31 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:31 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:31 volumio volumio[1159]: info: Feb 08 20:13:31 volumio volumio[1159]: ---------------------------- MPD announces state update: update Feb 08 20:13:31 volumio volumio[1159]: info: ControllerMpd::getState Feb 08 20:13:31 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:31 volumio volumio[1159]: info: sendMpdCommand status took 5 milliseconds Feb 08 20:13:31 volumio volumio[1159]: info: sendMpdCommand status took 4 milliseconds Feb 08 20:13:31 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:31 volumio volumio[1159]: info: Command Router : Notfying DB Updatefalse Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::Close All Modals sent Feb 08 20:13:31 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:31 volumio volumio[1159]: info: ControllerMpd::pushState Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:31 volumio volumio[1159]: 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} Feb 08 20:13:31 volumio volumio[1159]: verbose: CURRENT POSITION 0 Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::syncState stateService stop Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus stop Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:31 volumio volumio[1159]: info: No code Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:31 volumio volumio[1159]: info: ------------------------------ 68ms Feb 08 20:13:31 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:31 volumio volumio[1159]: info: Feb 08 20:13:31 volumio volumio[1159]: ---------------------------- MPD announces state update: update Feb 08 20:13:31 volumio volumio[1159]: info: ControllerMpd::getState Feb 08 20:13:31 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:31 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:31 volumio volumio[1159]: info: Feb 08 20:13:31 volumio volumio[1159]: ---------------------------- MPD announces state update: update Feb 08 20:13:31 volumio volumio[1159]: info: ControllerMpd::getState Feb 08 20:13:31 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:31 volumio volumio[1159]: info: sendMpdCommand status took 79 milliseconds Feb 08 20:13:31 volumio volumio[1159]: info: sendMpdCommand status took 78 milliseconds Feb 08 20:13:31 volumio volumio[1159]: info: sendMpdCommand status took 14 milliseconds Feb 08 20:13:31 volumio volumio[1159]: info: sendMpdCommand status took 11 milliseconds Feb 08 20:13:31 volumio volumio[1159]: info: sendMpdCommand status took 10 milliseconds Feb 08 20:13:31 volumio volumio[1159]: info: sendMpdCommand status took 7 milliseconds Feb 08 20:13:31 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:31 volumio volumio[1159]: info: Command Router : Notfying DB Updatefalse Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::Close All Modals sent Feb 08 20:13:31 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:31 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:31 volumio volumio[1159]: info: Command Router : Notfying DB Updatefalse Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::Close All Modals sent Feb 08 20:13:31 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:31 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:31 volumio volumio[1159]: info: Command Router : Notfying DB Updatefalse Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::Close All Modals sent Feb 08 20:13:31 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:31 volumio volumio[1159]: info: ControllerMpd::pushState Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:31 volumio volumio[1159]: 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} Feb 08 20:13:31 volumio volumio[1159]: verbose: CURRENT POSITION 0 Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::syncState stateService stop Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus stop Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:31 volumio volumio[1159]: info: No code Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:31 volumio volumio[1159]: info: ControllerMpd::pushState Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:31 volumio volumio[1159]: 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} Feb 08 20:13:31 volumio volumio[1159]: verbose: CURRENT POSITION 0 Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::syncState stateService stop Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus stop Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:31 volumio volumio[1159]: info: No code Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:31 volumio volumio[1159]: info: ControllerMpd::pushState Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:31 volumio volumio[1159]: 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} Feb 08 20:13:31 volumio volumio[1159]: verbose: CURRENT POSITION 0 Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::syncState stateService stop Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus stop Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:31 volumio volumio[1159]: info: No code Feb 08 20:13:31 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:31 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:31 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:32 volumio volumio[1159]: info: ------------------------------ 252ms Feb 08 20:13:32 volumio volumio[1159]: info: ------------------------------ 188ms Feb 08 20:13:32 volumio volumio[1159]: info: ------------------------------ 184ms Feb 08 20:13:32 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:32 volumio volumio[1159]: info: Feb 08 20:13:32 volumio volumio[1159]: ---------------------------- MPD announces state update: update Feb 08 20:13:32 volumio volumio[1159]: info: ControllerMpd::getState Feb 08 20:13:32 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:32 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:32 volumio volumio[1159]: info: Feb 08 20:13:32 volumio volumio[1159]: ---------------------------- MPD announces state update: update Feb 08 20:13:32 volumio volumio[1159]: info: ControllerMpd::getState Feb 08 20:13:32 volumio volumio[1159]: verbose: ControllerMpd::sendMpdCommand status Feb 08 20:13:32 volumio volumio[1159]: info: sendMpdCommand status took 52 milliseconds Feb 08 20:13:32 volumio volumio[1159]: info: sendMpdCommand status took 51 milliseconds Feb 08 20:13:32 volumio volumio[1159]: info: sendMpdCommand status took 51 milliseconds Feb 08 20:13:32 volumio volumio[1159]: info: sendMpdCommand status took 51 milliseconds Feb 08 20:13:32 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:32 volumio volumio[1159]: info: Command Router : Notfying DB Updatefalse Feb 08 20:13:32 volumio volumio[1159]: info: CoreCommandRouter::Close All Modals sent Feb 08 20:13:32 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:32 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:32 volumio volumio[1159]: info: Command Router : Notfying DB Updatefalse Feb 08 20:13:32 volumio volumio[1159]: info: CoreCommandRouter::Close All Modals sent Feb 08 20:13:32 volumio volumio[1159]: verbose: ControllerMpd::parseState Feb 08 20:13:32 volumio volumio[1159]: info: ControllerMpd::pushState Feb 08 20:13:32 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Feb 08 20:13:32 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:32 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:32 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:32 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:32 volumio volumio[1159]: 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} Feb 08 20:13:32 volumio volumio[1159]: verbose: CURRENT POSITION 0 Feb 08 20:13:32 volumio volumio[1159]: info: CoreStateMachine::syncState stateService stop Feb 08 20:13:32 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus stop Feb 08 20:13:32 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:32 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:32 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:32 volumio volumio[1159]: info: No code Feb 08 20:13:32 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:32 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:32 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:32 volumio volumio[1159]: info: ControllerMpd::pushState Feb 08 20:13:32 volumio volumio[1159]: info: CoreCommandRouter::servicePushState Feb 08 20:13:32 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:32 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:32 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:32 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:32 volumio volumio[1159]: 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} Feb 08 20:13:32 volumio volumio[1159]: verbose: CURRENT POSITION 0 Feb 08 20:13:32 volumio volumio[1159]: info: CoreStateMachine::syncState stateService stop Feb 08 20:13:32 volumio volumio[1159]: info: CoreStateMachine::syncState currentStatus stop Feb 08 20:13:32 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:32 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:32 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:32 volumio volumio[1159]: info: No code Feb 08 20:13:32 volumio volumio[1159]: info: CoreStateMachine::pushState Feb 08 20:13:32 volumio volumio[1159]: info: CorePlayQueue::getTrack 0 Feb 08 20:13:32 volumio volumio[1159]: info: CoreCommandRouter::volumioPushState Feb 08 20:13:32 volumio volumio[1159]: info: ------------------------------ 165ms Feb 08 20:13:32 volumio volumio[1159]: info: ------------------------------ 164ms Feb 08 20:13:33 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Feb 08 20:13:33 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Feb 08 20:13:33 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:13:37 volumio volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 08 20:13:38 volumio volumio[1159]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 20:13:38 volumio volumio[1159]: Error: dns service error: unknown Feb 08 20:13:38 volumio volumio[1159]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Feb 08 20:13:38 volumio volumio[1159]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Feb 08 20:13:38 volumio volumio[1159]: errorCode: -65537 Feb 08 20:13:38 volumio volumio[1159]: } Feb 08 20:13:38 volumio volumio[1159]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 08 20:13:38 volumio sudo[3248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-08 20:12' Feb 08 20:13:38 volumio sudo[3248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"