-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-06-01 17:56:09 CEST. -- Jun 01 17:55:03 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 17:55:07 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 17:55:11 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 17:55:15 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 17:55:19 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 17:55:23 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 17:55:24 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Jun 01 17:55:24 volumio4 volumio[1032]: info: Adding a new share Jun 01 17:55:24 volumio4 volumio[1032]: info: No correspondence found in configuration for share piwnica on IP 192.168.1.20 Jun 01 17:55:24 volumio4 volumio[1032]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.1.20 -N Jun 01 17:55:27 volumio4 volumio[1032]: info: Error browsing device piwnica for shares: Command failed: smbclient --debuglevel=4 -L 192.168.1.20 -N Jun 01 17:55:27 volumio4 volumio[1032]: lp_load_ex: refreshing parameters Jun 01 17:55:27 volumio4 volumio[1032]: Initialising global parameters Jun 01 17:55:27 volumio4 volumio[1032]: Processing section "[global]" Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter netbios name = Volumio4 Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter server string = Audiophile Music Player Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter workgroup = WORKGROUP Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter security = user Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter map to guest = Bad User Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter encrypt passwords = yes Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter wins support = yes Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter local master = no Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter preferred master = no Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter os level = 30 Jun 01 17:55:27 volumio4 volumio[1032]: pm_process() returned Yes Jun 01 17:55:27 volumio4 volumio[1032]: directory_create_or_exist_strict: invalid ownership on directory /var/run/samba/msg.lock Jun 01 17:55:27 volumio4 volumio[1032]: cmdline_messaging_context: Unable to initialize messaging context. Jun 01 17:55:27 volumio4 volumio[1032]: Unable to initialize messaging context Jun 01 17:55:27 volumio4 volumio[1032]: lp_load_ex: refreshing parameters Jun 01 17:55:27 volumio4 volumio[1032]: Initialising global parameters Jun 01 17:55:27 volumio4 volumio[1032]: Processing section "[global]" Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter netbios name = Volumio4 Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter server string = Audiophile Music Player Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter workgroup = WORKGROUP Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter security = user Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter map to guest = Bad User Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter encrypt passwords = yes Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter wins support = yes Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter local master = no Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter preferred master = no Jun 01 17:55:27 volumio4 volumio[1032]: doing parameter os level = 30 Jun 01 17:55:27 volumio4 volumio[1032]: pm_process() returned Yes Jun 01 17:55:27 volumio4 volumio[1032]: added interface wlan0 ip=192.168.1.204 bcast=192.168.1.255 netmask=255.255.255.0 Jun 01 17:55:27 volumio4 volumio[1032]: Client started (version 4.9.5-Debian). Jun 01 17:55:27 volumio4 volumio[1032]: Connecting to 192.168.1.20 at port 445 Jun 01 17:55:27 volumio4 volumio[1032]: Connecting to 192.168.1.20 at port 139 Jun 01 17:55:27 volumio4 volumio[1032]: session request ok Jun 01 17:55:27 volumio4 volumio[1032]: negotiated dialect[SMB3_11] against server[192.168.1.20] Jun 01 17:55:27 volumio4 volumio[1032]: got OID=1.3.6.1.4.1.311.2.2.30 Jun 01 17:55:27 volumio4 volumio[1032]: got OID=1.3.6.1.4.1.311.2.2.10 Jun 01 17:55:27 volumio4 volumio[1032]: GENSEC backend 'gssapi_spnego' registered Jun 01 17:55:27 volumio4 volumio[1032]: GENSEC backend 'gssapi_krb5' registered Jun 01 17:55:27 volumio4 volumio[1032]: GENSEC backend 'gssapi_krb5_sasl' registered Jun 01 17:55:27 volumio4 volumio[1032]: GENSEC backend 'spnego' registered Jun 01 17:55:27 volumio4 volumio[1032]: GENSEC backend 'schannel' registered Jun 01 17:55:27 volumio4 volumio[1032]: GENSEC backend 'naclrpc_as_system' registered Jun 01 17:55:27 volumio4 volumio[1032]: GENSEC backend 'sasl-EXTERNAL' registered Jun 01 17:55:27 volumio4 volumio[1032]: GENSEC backend 'ntlmssp' registered Jun 01 17:55:27 volumio4 volumio[1032]: GENSEC backend 'ntlmssp_resume_ccache' registered Jun 01 17:55:27 volumio4 volumio[1032]: GENSEC backend 'http_basic' registered Jun 01 17:55:27 volumio4 volumio[1032]: GENSEC backend 'http_ntlm' registered Jun 01 17:55:27 volumio4 volumio[1032]: GENSEC backend 'http_negotiate' registered Jun 01 17:55:27 volumio4 volumio[1032]: GENSEC backend 'krb5' registered Jun 01 17:55:27 volumio4 volumio[1032]: GENSEC backend 'fake_gssapi_krb5' registered Jun 01 17:55:27 volumio4 volumio[1032]: Got challenge flags: Jun 01 17:55:27 volumio4 volumio[1032]: Got NTLMSSP neg_flags=0x628a8215 Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_UNICODE Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_REQUEST_TARGET Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_SIGN Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_NTLM Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_TARGET_TYPE_SERVER Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_TARGET_INFO Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_VERSION Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_128 Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_KEY_EXCH Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP: Set final flags: Jun 01 17:55:27 volumio4 volumio[1032]: Got NTLMSSP neg_flags=0x62008215 Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_UNICODE Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_REQUEST_TARGET Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_SIGN Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_NTLM Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_VERSION Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_128 Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_KEY_EXCH Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP Sign/Seal - Initialising with flags: Jun 01 17:55:27 volumio4 volumio[1032]: Got NTLMSSP neg_flags=0x62008215 Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_UNICODE Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_REQUEST_TARGET Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_SIGN Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_NTLM Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_VERSION Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_128 Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_KEY_EXCH Jun 01 17:55:27 volumio4 volumio[1032]: SPNEGO login failed: The attempted logon is invalid. This is either due to a bad username or authentication information. Jun 01 17:55:27 volumio4 volumio[1032]: got OID=1.3.6.1.4.1.311.2.2.30 Jun 01 17:55:27 volumio4 volumio[1032]: got OID=1.3.6.1.4.1.311.2.2.10 Jun 01 17:55:27 volumio4 volumio[1032]: Got challenge flags: Jun 01 17:55:27 volumio4 volumio[1032]: Got NTLMSSP neg_flags=0x628a8215 Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_UNICODE Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_REQUEST_TARGET Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_SIGN Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_NTLM Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_TARGET_TYPE_SERVER Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_TARGET_INFO Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_VERSION Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_128 Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_KEY_EXCH Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP: Set final flags: Jun 01 17:55:27 volumio4 volumio[1032]: Got NTLMSSP neg_flags=0x62008a15 Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_UNICODE Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_REQUEST_TARGET Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_SIGN Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_NTLM Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_ANONYMOUS Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_VERSION Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_128 Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_KEY_EXCH Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP Sign/Seal - Initialising with flags: Jun 01 17:55:27 volumio4 volumio[1032]: Got NTLMSSP neg_flags=0x62008a15 Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_UNICODE Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_REQUEST_TARGET Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_SIGN Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_NTLM Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_ANONYMOUS Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_ALWAYS_SIGN Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_VERSION Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_128 Jun 01 17:55:27 volumio4 volumio[1032]: NTLMSSP_NEGOTIATE_KEY_EXCH Jun 01 17:55:27 volumio4 volumio[1032]: SPNEGO login failed: {Access Denied} A process has requested access to an object but has not been granted those access rights. Jun 01 17:55:27 volumio4 volumio[1032]: info: Detected device piwnica with version null Jun 01 17:55:27 volumio4 sudo[2414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=volumio,password=nowehaslo,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.20/muzyka /mnt/NAS/piwnica Jun 01 17:55:27 volumio4 sudo[2414]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 17:55:27 volumio4 kernel: Key type cifs.spnego registered Jun 01 17:55:27 volumio4 kernel: Key type cifs.idmap registered Jun 01 17:55:27 volumio4 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jun 01 17:55:27 volumio4 kernel: CIFS: Attempting to mount //192.168.1.20/muzyka Jun 01 17:55:27 volumio4 kernel: cryptd: max_cpu_qlen set to 1000 Jun 01 17:55:27 volumio4 sudo[2414]: pam_unix(sudo:session): session closed for user root Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 17:55:27 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:27 volumio4 volumio[1032]: info: Jun 01 17:55:27 volumio4 volumio[1032]: ---------------------------- MPD announces state update: update Jun 01 17:55:27 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:27 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:27 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:27 volumio4 volumio[1032]: info: Jun 01 17:55:27 volumio4 volumio[1032]: ---------------------------- MPD announces state update: update Jun 01 17:55:27 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:27 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:27 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:27 volumio4 volumio[1032]: info: Jun 01 17:55:27 volumio4 volumio[1032]: ---------------------------- MPD announces state update: update Jun 01 17:55:27 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:27 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:27 volumio4 volumio[1032]: info: [1748793327403] Database update started Jun 01 17:55:27 volumio4 volumio[1032]: info: sendMpdCommand status took 12 milliseconds Jun 01 17:55:27 volumio4 volumio[1032]: info: sendMpdCommand status took 12 milliseconds Jun 01 17:55:27 volumio4 volumio[1032]: info: sendMpdCommand status took 11 milliseconds Jun 01 17:55:27 volumio4 volumio[1032]: info: sendMpdCommand status took 11 milliseconds Jun 01 17:55:27 volumio4 volumio[1032]: info: sendMpdCommand status took 10 milliseconds Jun 01 17:55:27 volumio4 volumio[1032]: info: sendMpdCommand status took 9 milliseconds Jun 01 17:55:27 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:27 volumio4 volumio[1032]: info: Command Router : Notfying DB Updatetrue Jun 01 17:55:27 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:27 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:27 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:27 volumio4 volumio[1032]: info: Command Router : Notfying DB Updatetrue Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreCommandRouter::Close All Modals sent Jun 01 17:55:27 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:27 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:27 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:27 volumio4 volumio[1032]: info: Command Router : Notfying DB Updatetrue Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreCommandRouter::Close All Modals sent Jun 01 17:55:27 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:27 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:27 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 68 milliseconds Jun 01 17:55:27 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 46 milliseconds Jun 01 17:55:27 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 19 milliseconds Jun 01 17:55:27 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:27 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:27 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:27 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:27 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:27 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":210509,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:27 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:27 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:27 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:27 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:27 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":210509,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:27 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:27 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:27 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:27 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:27 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":210509,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:27 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:27 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:27 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:27 volumio4 volumio[1032]: info: ------------------------------ 169ms Jun 01 17:55:27 volumio4 volumio[1032]: info: ------------------------------ 168ms Jun 01 17:55:27 volumio4 volumio[1032]: info: ------------------------------ 167ms Jun 01 17:55:28 volumio4 volumio[1032]: info: MPD Database updated - AlbumList cache refreshed Jun 01 17:55:28 volumio4 volumio[1032]: info: Jun 01 17:55:28 volumio4 volumio[1032]: ---------------------------- MPD announces state update: database Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:28 volumio4 volumio[1032]: info: Jun 01 17:55:28 volumio4 volumio[1032]: ---------------------------- MPD announces state update: update Jun 01 17:55:28 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:28 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:28 volumio4 volumio[1032]: info: MPD Database updated - AlbumList cache refreshed Jun 01 17:55:28 volumio4 volumio[1032]: info: Jun 01 17:55:28 volumio4 volumio[1032]: ---------------------------- MPD announces state update: database Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:28 volumio4 volumio[1032]: info: Jun 01 17:55:28 volumio4 volumio[1032]: ---------------------------- MPD announces state update: update Jun 01 17:55:28 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:28 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:28 volumio4 volumio[1032]: info: MPD Database updated - AlbumList cache refreshed Jun 01 17:55:28 volumio4 volumio[1032]: info: Jun 01 17:55:28 volumio4 volumio[1032]: ---------------------------- MPD announces state update: database Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:28 volumio4 volumio[1032]: info: Jun 01 17:55:28 volumio4 volumio[1032]: ---------------------------- MPD announces state update: update Jun 01 17:55:28 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:28 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:28 volumio4 volumio[1032]: info: sendMpdCommand status took 13 milliseconds Jun 01 17:55:28 volumio4 volumio[1032]: info: sendMpdCommand status took 12 milliseconds Jun 01 17:55:28 volumio4 volumio[1032]: info: sendMpdCommand status took 12 milliseconds Jun 01 17:55:28 volumio4 volumio[1032]: info: sendMpdCommand status took 10 milliseconds Jun 01 17:55:28 volumio4 volumio[1032]: info: sendMpdCommand status took 10 milliseconds Jun 01 17:55:28 volumio4 volumio[1032]: info: sendMpdCommand status took 10 milliseconds Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:28 volumio4 volumio[1032]: info: Command Router : Notfying DB Updatefalse Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::Close All Modals sent Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:28 volumio4 volumio[1032]: info: Command Router : Notfying DB Updatefalse Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::Close All Modals sent Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:28 volumio4 volumio[1032]: info: sendMpdCommand status took 113 milliseconds Jun 01 17:55:28 volumio4 volumio[1032]: info: sendMpdCommand status took 113 milliseconds Jun 01 17:55:28 volumio4 volumio[1032]: info: sendMpdCommand status took 114 milliseconds Jun 01 17:55:28 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 31 milliseconds Jun 01 17:55:28 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 30 milliseconds Jun 01 17:55:28 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 01 17:55:28 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:28 volumio4 volumio[1032]: info: Command Router : Notfying DB Updatefalse Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::Close All Modals sent Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:28 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:28 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:28 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":211266,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:28 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:28 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:28 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:28 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:28 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":211266,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:28 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:28 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:28 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:28 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:28 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":211266,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:28 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:28 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:28 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:28 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:28 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":211266,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:28 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:28 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:28 volumio4 volumio[1032]: info: ------------------------------ 195ms Jun 01 17:55:28 volumio4 volumio[1032]: info: ------------------------------ 196ms Jun 01 17:55:28 volumio4 volumio[1032]: info: ------------------------------ 193ms Jun 01 17:55:28 volumio4 volumio[1032]: info: ------------------------------ 193ms Jun 01 17:55:28 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 57 milliseconds Jun 01 17:55:28 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 57 milliseconds Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:28 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:28 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:28 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:28 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":211266,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:28 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:28 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:28 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:28 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:28 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":211266,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:28 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:28 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:28 volumio4 volumio[1032]: info: ------------------------------ 229ms Jun 01 17:55:28 volumio4 volumio[1032]: info: ------------------------------ 229ms Jun 01 17:55:28 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 01 17:55:31 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 17:55:35 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jun 01 17:55:38 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand update Jun 01 17:55:38 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:38 volumio4 volumio[1032]: info: Jun 01 17:55:38 volumio4 volumio[1032]: ---------------------------- MPD announces state update: update Jun 01 17:55:38 volumio4 volumio[1032]: info: sendMpdCommand update took 2 milliseconds Jun 01 17:55:38 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:38 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:38 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:38 volumio4 volumio[1032]: info: Jun 01 17:55:38 volumio4 volumio[1032]: ---------------------------- MPD announces state update: update Jun 01 17:55:38 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:38 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:38 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:38 volumio4 volumio[1032]: info: Jun 01 17:55:38 volumio4 volumio[1032]: ---------------------------- MPD announces state update: update Jun 01 17:55:38 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:38 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:38 volumio4 volumio[1032]: info: sendMpdCommand status took 7 milliseconds Jun 01 17:55:38 volumio4 volumio[1032]: info: sendMpdCommand status took 7 milliseconds Jun 01 17:55:38 volumio4 volumio[1032]: info: sendMpdCommand status took 5 milliseconds Jun 01 17:55:38 volumio4 volumio[1032]: info: sendMpdCommand status took 4 milliseconds Jun 01 17:55:38 volumio4 volumio[1032]: info: sendMpdCommand status took 3 milliseconds Jun 01 17:55:38 volumio4 volumio[1032]: info: sendMpdCommand status took 3 milliseconds Jun 01 17:55:38 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:38 volumio4 volumio[1032]: info: Command Router : Notfying DB Updatetrue Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreCommandRouter::Close All Modals sent Jun 01 17:55:38 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:38 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:38 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:38 volumio4 volumio[1032]: info: Command Router : Notfying DB Updatetrue Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreCommandRouter::Close All Modals sent Jun 01 17:55:38 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:38 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:38 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:38 volumio4 volumio[1032]: info: Command Router : Notfying DB Updatetrue Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreCommandRouter::Close All Modals sent Jun 01 17:55:38 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:38 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:38 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 59 milliseconds Jun 01 17:55:38 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 38 milliseconds Jun 01 17:55:38 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 01 17:55:38 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:38 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:38 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:38 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:38 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":221986,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:38 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:38 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:38 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:38 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:38 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":221986,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:38 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:38 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:38 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:38 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:38 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":221986,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:38 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:38 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:38 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:39 volumio4 volumio[1032]: info: ------------------------------ 131ms Jun 01 17:55:39 volumio4 volumio[1032]: info: ------------------------------ 128ms Jun 01 17:55:39 volumio4 volumio[1032]: info: ------------------------------ 127ms Jun 01 17:55:39 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:39 volumio4 volumio[1032]: info: Jun 01 17:55:39 volumio4 volumio[1032]: ---------------------------- MPD announces state update: update Jun 01 17:55:39 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:39 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:39 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:39 volumio4 volumio[1032]: info: Jun 01 17:55:39 volumio4 volumio[1032]: ---------------------------- MPD announces state update: update Jun 01 17:55:39 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:39 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:39 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:39 volumio4 volumio[1032]: info: Jun 01 17:55:39 volumio4 volumio[1032]: ---------------------------- MPD announces state update: update Jun 01 17:55:39 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:39 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:39 volumio4 volumio[1032]: info: sendMpdCommand status took 4 milliseconds Jun 01 17:55:39 volumio4 volumio[1032]: info: sendMpdCommand status took 3 milliseconds Jun 01 17:55:39 volumio4 volumio[1032]: info: sendMpdCommand status took 3 milliseconds Jun 01 17:55:39 volumio4 volumio[1032]: info: sendMpdCommand status took 3 milliseconds Jun 01 17:55:39 volumio4 volumio[1032]: info: sendMpdCommand status took 2 milliseconds Jun 01 17:55:39 volumio4 volumio[1032]: info: sendMpdCommand status took 2 milliseconds Jun 01 17:55:39 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:39 volumio4 volumio[1032]: info: Command Router : Notfying DB Updatefalse Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreCommandRouter::Close All Modals sent Jun 01 17:55:39 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:39 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:39 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:39 volumio4 volumio[1032]: info: Command Router : Notfying DB Updatefalse Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreCommandRouter::Close All Modals sent Jun 01 17:55:39 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:39 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:39 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:39 volumio4 volumio[1032]: info: Command Router : Notfying DB Updatefalse Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreCommandRouter::Close All Modals sent Jun 01 17:55:39 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:39 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:39 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 54 milliseconds Jun 01 17:55:39 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 01 17:55:39 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 01 17:55:39 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:39 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:39 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:39 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:39 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:39 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":222112,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:39 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:39 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:39 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:39 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:39 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":222112,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:39 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:39 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:39 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:39 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:39 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":222112,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:39 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:39 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:39 volumio4 volumio[1032]: info: ------------------------------ 119ms Jun 01 17:55:39 volumio4 volumio[1032]: info: ------------------------------ 117ms Jun 01 17:55:39 volumio4 volumio[1032]: info: ------------------------------ 117ms Jun 01 17:55:39 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jun 01 17:55:41 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand rescan Jun 01 17:55:41 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:41 volumio4 volumio[1032]: info: Jun 01 17:55:41 volumio4 volumio[1032]: ---------------------------- MPD announces state update: update Jun 01 17:55:41 volumio4 volumio[1032]: info: sendMpdCommand rescan took 3 milliseconds Jun 01 17:55:41 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:41 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:41 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:41 volumio4 volumio[1032]: info: Jun 01 17:55:41 volumio4 volumio[1032]: ---------------------------- MPD announces state update: update Jun 01 17:55:41 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:41 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:41 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:41 volumio4 volumio[1032]: info: Jun 01 17:55:41 volumio4 volumio[1032]: ---------------------------- MPD announces state update: update Jun 01 17:55:41 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:41 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:41 volumio4 volumio[1032]: info: sendMpdCommand status took 7 milliseconds Jun 01 17:55:41 volumio4 volumio[1032]: info: sendMpdCommand status took 7 milliseconds Jun 01 17:55:41 volumio4 volumio[1032]: info: sendMpdCommand status took 6 milliseconds Jun 01 17:55:41 volumio4 volumio[1032]: info: sendMpdCommand status took 6 milliseconds Jun 01 17:55:41 volumio4 volumio[1032]: info: sendMpdCommand status took 5 milliseconds Jun 01 17:55:41 volumio4 volumio[1032]: info: sendMpdCommand status took 3 milliseconds Jun 01 17:55:41 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:41 volumio4 volumio[1032]: info: Command Router : Notfying DB Updatetrue Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreCommandRouter::Close All Modals sent Jun 01 17:55:41 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:41 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:41 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:41 volumio4 volumio[1032]: info: Command Router : Notfying DB Updatetrue Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreCommandRouter::Close All Modals sent Jun 01 17:55:41 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:41 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:41 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:41 volumio4 volumio[1032]: info: Command Router : Notfying DB Updatetrue Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreCommandRouter::Close All Modals sent Jun 01 17:55:41 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:41 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:41 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 62 milliseconds Jun 01 17:55:41 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 38 milliseconds Jun 01 17:55:41 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 17 milliseconds Jun 01 17:55:41 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:41 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:41 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:41 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":224425,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:41 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:41 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:41 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:41 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:41 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":224425,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:41 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:41 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:41 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:41 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:41 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":224425,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:41 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:41 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:41 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:41 volumio4 volumio[1032]: info: ------------------------------ 138ms Jun 01 17:55:41 volumio4 volumio[1032]: info: ------------------------------ 137ms Jun 01 17:55:41 volumio4 volumio[1032]: info: ------------------------------ 135ms Jun 01 17:55:43 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 17:55:46 volumio4 volumio[1032]: info: MPD Database updated - AlbumList cache refreshed Jun 01 17:55:46 volumio4 volumio[1032]: info: Jun 01 17:55:46 volumio4 volumio[1032]: ---------------------------- MPD announces state update: database Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:46 volumio4 volumio[1032]: info: Jun 01 17:55:46 volumio4 volumio[1032]: ---------------------------- MPD announces state update: update Jun 01 17:55:46 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:46 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:46 volumio4 volumio[1032]: info: MPD Database updated - AlbumList cache refreshed Jun 01 17:55:46 volumio4 volumio[1032]: info: Jun 01 17:55:46 volumio4 volumio[1032]: ---------------------------- MPD announces state update: database Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:46 volumio4 volumio[1032]: info: Jun 01 17:55:46 volumio4 volumio[1032]: ---------------------------- MPD announces state update: update Jun 01 17:55:46 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:46 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:46 volumio4 volumio[1032]: info: MPD Database updated - AlbumList cache refreshed Jun 01 17:55:46 volumio4 volumio[1032]: info: Jun 01 17:55:46 volumio4 volumio[1032]: ---------------------------- MPD announces state update: database Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:46 volumio4 volumio[1032]: info: Jun 01 17:55:46 volumio4 volumio[1032]: ---------------------------- MPD announces state update: update Jun 01 17:55:46 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:46 volumio4 volumio[1032]: info: ControllerMpd::getState Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand status Jun 01 17:55:46 volumio4 volumio[1032]: info: sendMpdCommand status took 19 milliseconds Jun 01 17:55:46 volumio4 volumio[1032]: info: sendMpdCommand status took 18 milliseconds Jun 01 17:55:46 volumio4 volumio[1032]: info: sendMpdCommand status took 19 milliseconds Jun 01 17:55:46 volumio4 volumio[1032]: info: sendMpdCommand status took 18 milliseconds Jun 01 17:55:46 volumio4 volumio[1032]: info: sendMpdCommand status took 17 milliseconds Jun 01 17:55:46 volumio4 volumio[1032]: info: sendMpdCommand status took 17 milliseconds Jun 01 17:55:46 volumio4 volumio[1032]: info: sendMpdCommand status took 16 milliseconds Jun 01 17:55:46 volumio4 volumio[1032]: info: sendMpdCommand status took 14 milliseconds Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:46 volumio4 volumio[1032]: info: Command Router : Notfying DB Updatefalse Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::Close All Modals sent Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:46 volumio4 volumio[1032]: info: Command Router : Notfying DB Updatefalse Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::Close All Modals sent Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:46 volumio4 volumio[1032]: info: Command Router : Notfying DB Updatefalse Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::Close All Modals sent Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::parseState Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 17:55:46 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 54 milliseconds Jun 01 17:55:46 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 55 milliseconds Jun 01 17:55:46 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 34 milliseconds Jun 01 17:55:46 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 01 17:55:46 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 01 17:55:46 volumio4 volumio[1032]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:46 volumio4 volumio[1032]: verbose: ControllerMpd::parseTrackInfo Jun 01 17:55:46 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:46 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:46 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":229701,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:46 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:46 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:46 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:46 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:46 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":229701,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:46 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:46 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:46 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:46 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:46 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":229701,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:46 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:46 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:46 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:46 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:46 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":229701,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:46 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:46 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:46 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:46 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:46 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":229701,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:46 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:46 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:46 volumio4 volumio[1032]: info: ControllerMpd::pushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::servicePushState Jun 01 17:55:46 volumio4 volumio[1032]: verbose: In UPNP mode Jun 01 17:55:46 volumio4 volumio[1032]: verbose: STATE SERVICE {"status":"play","position":0,"seek":229701,"duration":2084491,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"N/A","artist":"Local Audio Broadcast","album":null,"uri":"http://192.168.1.20:50227/capture","trackType":""} Jun 01 17:55:46 volumio4 volumio[1032]: verbose: CURRENT POSITION 0 Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::syncState stateService play Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::syncState currentStatus play Jun 01 17:55:46 volumio4 volumio[1032]: info: Received an update from plugin. extracting info from payload Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreStateMachine::pushState Jun 01 17:55:46 volumio4 volumio[1032]: info: CoreCommandRouter::volumioPushState Jun 01 17:55:46 volumio4 volumio[1032]: info: ------------------------------ 208ms Jun 01 17:55:46 volumio4 volumio[1032]: info: ------------------------------ 209ms Jun 01 17:55:46 volumio4 volumio[1032]: info: ------------------------------ 207ms Jun 01 17:55:46 volumio4 volumio[1032]: info: ------------------------------ 208ms Jun 01 17:55:46 volumio4 volumio[1032]: info: ------------------------------ 206ms Jun 01 17:55:46 volumio4 volumio[1032]: info: ------------------------------ 206ms Jun 01 17:55:47 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 17:55:51 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 17:55:55 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 17:55:59 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 17:56:00 volumio4 volumio[1032]: info: CoreCommandRouter::volumioGetState Jun 01 17:56:06 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 01 17:56:07 volumio4 volumio[1032]: info: Preload queue cleared Jun 01 17:56:08 volumio4 volumio[1032]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 01 17:56:08 volumio4 volumio[1032]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 17:56:08 volumio4 volumio[1032]: TypeError: Cannot read property 'length' of undefined Jun 01 17:56:08 volumio4 volumio[1032]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 01 17:56:08 volumio4 volumio[1032]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 01 17:56:08 volumio4 volumio[1032]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 01 17:56:08 volumio4 volumio[1032]: at Parser.emit (events.js:315:20) Jun 01 17:56:08 volumio4 volumio[1032]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 01 17:56:08 volumio4 volumio[1032]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 01 17:56:08 volumio4 volumio[1032]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 01 17:56:08 volumio4 volumio[1032]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 01 17:56:08 volumio4 volumio[1032]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 01 17:56:08 volumio4 volumio[1032]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 01 17:56:08 volumio4 volumio[1032]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 01 17:56:08 volumio4 volumio[1032]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 01 17:56:08 volumio4 volumio[1032]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 01 17:56:08 volumio4 volumio[1032]: at IncomingMessage.emit (events.js:327:22) Jun 01 17:56:08 volumio4 volumio[1032]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 01 17:56:08 volumio4 volumio[1032]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 01 17:56:08 volumio4 volumio[1032]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 17:56:09 volumio4 sudo[2691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-01 17:55 Jun 01 17:56:09 volumio4 sudo[2691]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"