Feb 25 12:59:31 krutghz volumio[4211]: info: Feb 25 12:59:31 krutghz volumio[4211]: ---------------------------- MPD announces system playlist update Feb 25 12:59:31 krutghz volumio[4211]: info: Ignoring MPD Status Update Feb 25 12:59:31 krutghz volumio[4211]: info: Feb 25 12:59:31 krutghz volumio[4211]: ---------------------------- MPD announces state update: player Feb 25 12:59:31 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:31 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:31 krutghz volumio[4211]: info: Feb 25 12:59:31 krutghz volumio[4211]: ---------------------------- MPD announces system playlist update Feb 25 12:59:31 krutghz volumio[4211]: info: Ignoring MPD Status Update Feb 25 12:59:31 krutghz volumio[4211]: info: Feb 25 12:59:31 krutghz volumio[4211]: ---------------------------- MPD announces state update: player Feb 25 12:59:31 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:31 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:31 krutghz volumio[4211]: info: ------------------------------ 1ms Feb 25 12:59:31 krutghz volumio[4211]: info: sendMpdCommand status took 1 milliseconds Feb 25 12:59:31 krutghz volumio[4211]: info: ------------------------------ 0ms Feb 25 12:59:31 krutghz volumio[4211]: info: sendMpdCommand status took 0 milliseconds Feb 25 12:59:31 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:31 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:31 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:31 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:31 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 25 12:59:31 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 25 12:59:31 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:31 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:31 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:31 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:31 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:31 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:31 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:31 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:31 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:31 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:31 krutghz volumio[4211]: info: ------------------------------ 1ms Feb 25 12:59:31 krutghz volumio[4211]: info: ------------------------------ 0ms Feb 25 12:59:39 krutghz sshd[19570]: Accepted password for volumio from 10.8.81.178 port 57081 ssh2 Feb 25 12:59:39 krutghz sshd[19570]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Feb 25 12:59:39 krutghz systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Feb 25 12:59:39 krutghz systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Feb 25 12:59:39 krutghz systemd-logind[1016]: New session 3 of user volumio. Feb 25 12:59:39 krutghz systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Feb 25 12:59:39 krutghz systemd[1]: Starting user@1000.service - User Manager for UID 1000... Feb 25 12:59:39 krutghz (systemd)[19593]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Feb 25 12:59:39 krutghz systemd[19593]: Queued start job for default target default.target. Feb 25 12:59:39 krutghz systemd[19593]: Created slice app.slice - User Application Slice. Feb 25 12:59:39 krutghz systemd[19593]: Reached target paths.target - Paths. Feb 25 12:59:39 krutghz systemd[19593]: Reached target timers.target - Timers. Feb 25 12:59:39 krutghz systemd[19593]: Starting dbus.socket - D-Bus User Message Bus Socket... Feb 25 12:59:39 krutghz systemd[19593]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Feb 25 12:59:39 krutghz systemd[19593]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Feb 25 12:59:39 krutghz systemd[19593]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Feb 25 12:59:39 krutghz systemd[19593]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Feb 25 12:59:39 krutghz systemd[19593]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Feb 25 12:59:39 krutghz systemd[19593]: Listening on dbus.socket - D-Bus User Message Bus Socket. Feb 25 12:59:39 krutghz systemd[19593]: Reached target sockets.target - Sockets. Feb 25 12:59:39 krutghz systemd[19593]: Reached target basic.target - Basic System. Feb 25 12:59:39 krutghz systemd[1]: Started user@1000.service - User Manager for UID 1000. Feb 25 12:59:39 krutghz systemd[19593]: Started mpris-proxy.service - Bluetooth mpris proxy. Feb 25 12:59:39 krutghz systemd[19593]: Reached target default.target - Main User Target. Feb 25 12:59:39 krutghz systemd[19593]: Startup finished in 137ms. Feb 25 12:59:39 krutghz systemd[1]: Started session-3.scope - Session 3 of User volumio. Feb 25 12:59:39 krutghz systemd[19593]: Created slice session.slice - User Core Session Slice. Feb 25 12:59:39 krutghz systemd[19593]: Starting dbus.service - D-Bus User Message Bus... Feb 25 12:59:39 krutghz systemd[19593]: Started dbus.service - D-Bus User Message Bus. Feb 25 12:59:39 krutghz sshd[19570]: pam_env(sshd:session): deprecated reading of user environment enabled Feb 25 12:59:40 krutghz sshd[19622]: Received disconnect from 10.8.81.178 port 57081:11: disconnected by user Feb 25 12:59:40 krutghz sshd[19622]: Disconnected from user volumio 10.8.81.178 port 57081 Feb 25 12:59:40 krutghz sshd[19570]: pam_unix(sshd:session): session closed for user volumio Feb 25 12:59:40 krutghz systemd[1]: session-3.scope: Deactivated successfully. Feb 25 12:59:40 krutghz systemd-logind[1016]: Session 3 logged out. Waiting for processes to exit. Feb 25 12:59:40 krutghz systemd-logind[1016]: Removed session 3. Feb 25 12:59:41 krutghz volumio[4211]: info: Feb 25 12:59:41 krutghz volumio[4211]: ---------------------------- MPD announces system playlist update Feb 25 12:59:41 krutghz volumio[4211]: info: Ignoring MPD Status Update Feb 25 12:59:41 krutghz volumio[4211]: info: Feb 25 12:59:41 krutghz volumio[4211]: ---------------------------- MPD announces state update: player Feb 25 12:59:41 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:41 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:41 krutghz volumio[4211]: info: Feb 25 12:59:41 krutghz volumio[4211]: ---------------------------- MPD announces system playlist update Feb 25 12:59:41 krutghz volumio[4211]: info: Ignoring MPD Status Update Feb 25 12:59:41 krutghz volumio[4211]: info: Feb 25 12:59:41 krutghz volumio[4211]: ---------------------------- MPD announces state update: player Feb 25 12:59:41 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:41 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:41 krutghz volumio[4211]: info: ------------------------------ 1ms Feb 25 12:59:41 krutghz volumio[4211]: info: sendMpdCommand status took 1 milliseconds Feb 25 12:59:41 krutghz volumio[4211]: info: ------------------------------ 0ms Feb 25 12:59:41 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:41 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:41 krutghz volumio[4211]: info: sendMpdCommand status took 1 milliseconds Feb 25 12:59:41 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 25 12:59:41 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:41 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:41 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:41 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:41 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:41 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:41 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:41 krutghz volumio[4211]: info: ------------------------------ 2ms Feb 25 12:59:41 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 25 12:59:41 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:41 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:41 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:41 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:41 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:41 krutghz volumio[4211]: info: ------------------------------ 1ms Feb 25 12:59:46 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 25 12:59:46 krutghz volumio[4211]: info: CURURI: music-library/INTERNAL Feb 25 12:59:46 krutghz volumio[4211]: info: Preload queue cleared Feb 25 12:59:50 krutghz systemd[1]: Stopping user@1000.service - User Manager for UID 1000... Feb 25 12:59:50 krutghz systemd[19593]: Activating special unit exit.target... Feb 25 12:59:50 krutghz systemd[19593]: Stopped target default.target - Main User Target. Feb 25 12:59:50 krutghz systemd[19593]: Stopping mpris-proxy.service - Bluetooth mpris proxy... Feb 25 12:59:50 krutghz mpris-proxy[19611]: org.bluez appeared Feb 25 12:59:50 krutghz mpris-proxy[19611]: Bluetooth Adapter /org/bluez/hci0 found Feb 25 12:59:50 krutghz mpris-proxy[19611]: Bluetooth Adapter /org/bluez/hci0 removed Feb 25 12:59:50 krutghz mpris-proxy[19611]: org.bluez disappeared Feb 25 12:59:50 krutghz systemd[19593]: Stopped mpris-proxy.service - Bluetooth mpris proxy. Feb 25 12:59:50 krutghz systemd[19593]: Stopping dbus.service - D-Bus User Message Bus... Feb 25 12:59:50 krutghz systemd[19593]: Stopped dbus.service - D-Bus User Message Bus. Feb 25 12:59:50 krutghz systemd[19593]: Removed slice session.slice - User Core Session Slice. Feb 25 12:59:50 krutghz systemd[19593]: Stopped target basic.target - Basic System. Feb 25 12:59:50 krutghz systemd[19593]: Stopped target paths.target - Paths. Feb 25 12:59:50 krutghz systemd[19593]: Stopped target sockets.target - Sockets. Feb 25 12:59:50 krutghz systemd[19593]: Stopped target timers.target - Timers. Feb 25 12:59:50 krutghz systemd[19593]: Closed dbus.socket - D-Bus User Message Bus Socket. Feb 25 12:59:50 krutghz systemd[19593]: Closed dirmngr.socket - GnuPG network certificate management daemon. Feb 25 12:59:50 krutghz systemd[19593]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Feb 25 12:59:50 krutghz systemd[19593]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Feb 25 12:59:50 krutghz systemd[19593]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Feb 25 12:59:50 krutghz systemd[19593]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Feb 25 12:59:50 krutghz systemd[19593]: Removed slice app.slice - User Application Slice. Feb 25 12:59:50 krutghz systemd[19593]: Reached target shutdown.target - Shutdown. Feb 25 12:59:50 krutghz systemd[19593]: Finished systemd-exit.service - Exit the Session. Feb 25 12:59:50 krutghz systemd[19593]: Reached target exit.target - Exit the Session. Feb 25 12:59:50 krutghz systemd[1]: user@1000.service: Deactivated successfully. Feb 25 12:59:50 krutghz systemd[1]: Stopped user@1000.service - User Manager for UID 1000. Feb 25 12:59:50 krutghz systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Feb 25 12:59:50 krutghz systemd[1]: run-user-1000.mount: Deactivated successfully. Feb 25 12:59:50 krutghz systemd[1]: user-runtime-dir@1000.service: Deactivated successfully. Feb 25 12:59:50 krutghz systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Feb 25 12:59:50 krutghz systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000. Feb 25 12:59:51 krutghz volumio[4211]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 12:59:51 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 25 12:59:51 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 25 12:59:51 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 25 12:59:51 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 25 12:59:51 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 12:59:51 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 12:59:51 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 12:59:51 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 12:59:51 krutghz volumio[4211]: info: CoreCommandRouter::volumioGetBrowseSources Feb 25 12:59:51 krutghz volumio[4211]: info: CoreCommandRouter::volumioGetBrowseSources Feb 25 12:59:51 krutghz volumio[4211]: info: CoreCommandRouter::volumioGetBrowseSources Feb 25 12:59:51 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 25 12:59:51 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 25 12:59:52 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand update Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:52 krutghz volumio[4211]: info: Feb 25 12:59:52 krutghz volumio[4211]: ---------------------------- MPD announces state update: update Feb 25 12:59:52 krutghz volumio[4211]: info: sendMpdCommand update took 1 milliseconds Feb 25 12:59:52 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:52 krutghz volumio[4211]: info: Feb 25 12:59:52 krutghz volumio[4211]: ---------------------------- MPD announces state update: update Feb 25 12:59:52 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:52 krutghz volumio[4211]: info: sendMpdCommand status took 1 milliseconds Feb 25 12:59:52 krutghz volumio[4211]: info: sendMpdCommand status took 1 milliseconds Feb 25 12:59:52 krutghz volumio[4211]: info: sendMpdCommand status took 1 milliseconds Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:52 krutghz volumio[4211]: info: Command Router : Notfying DB Updatetrue Feb 25 12:59:52 krutghz volumio[4211]: info: CoreCommandRouter::Close All Modals sent Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:52 krutghz volumio[4211]: info: Command Router : Notfying DB Updatetrue Feb 25 12:59:52 krutghz volumio[4211]: info: CoreCommandRouter::Close All Modals sent Feb 25 12:59:52 krutghz volumio[4211]: info: MPD Database updated - AlbumList cache refreshed Feb 25 12:59:52 krutghz volumio[4211]: info: Feb 25 12:59:52 krutghz volumio[4211]: ---------------------------- MPD announces state update: database Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:52 krutghz volumio[4211]: info: Feb 25 12:59:52 krutghz volumio[4211]: ---------------------------- MPD announces state update: update Feb 25 12:59:52 krutghz volumio[4211]: info: sendMpdCommand status took 412 milliseconds Feb 25 12:59:52 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:52 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:52 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:52 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:52 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:52 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:52 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:52 krutghz volumio[4211]: info: ------------------------------ 413ms Feb 25 12:59:52 krutghz volumio[4211]: info: MPD Database updated - AlbumList cache refreshed Feb 25 12:59:52 krutghz volumio[4211]: info: Feb 25 12:59:52 krutghz volumio[4211]: ---------------------------- MPD announces state update: database Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:52 krutghz volumio[4211]: info: Feb 25 12:59:52 krutghz volumio[4211]: ---------------------------- MPD announces state update: update Feb 25 12:59:52 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:52 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:52 krutghz volumio[4211]: info: sendMpdCommand status took 3 milliseconds Feb 25 12:59:52 krutghz volumio[4211]: info: sendMpdCommand status took 3 milliseconds Feb 25 12:59:52 krutghz volumio[4211]: info: sendMpdCommand status took 3 milliseconds Feb 25 12:59:52 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 25 12:59:52 krutghz volumio[4211]: info: sendMpdCommand status took 2 milliseconds Feb 25 12:59:52 krutghz volumio[4211]: info: sendMpdCommand status took 2 milliseconds Feb 25 12:59:52 krutghz volumio[4211]: info: sendMpdCommand status took 2 milliseconds Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:52 krutghz volumio[4211]: info: Command Router : Notfying DB Updatefalse Feb 25 12:59:52 krutghz volumio[4211]: info: CoreCommandRouter::Close All Modals sent Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:52 krutghz volumio[4211]: info: Command Router : Notfying DB Updatefalse Feb 25 12:59:52 krutghz volumio[4211]: info: CoreCommandRouter::Close All Modals sent Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:52 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:52 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:52 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:52 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:52 krutghz volumio[4211]: info: ------------------------------ 426ms Feb 25 12:59:52 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 25 12:59:52 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 25 12:59:52 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 25 12:59:52 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:52 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:52 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:52 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:52 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:52 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:52 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:52 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:52 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:52 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:52 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:52 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:52 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:52 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:52 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:52 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:52 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:52 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:52 krutghz volumio[4211]: info: ------------------------------ 15ms Feb 25 12:59:52 krutghz volumio[4211]: info: ------------------------------ 15ms Feb 25 12:59:52 krutghz volumio[4211]: info: ------------------------------ 14ms Feb 25 12:59:52 krutghz volumio[4211]: info: ------------------------------ 14ms Feb 25 12:59:53 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand rescan Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: info: Feb 25 12:59:53 krutghz volumio[4211]: ---------------------------- MPD announces state update: update Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand rescan took 1 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: info: Feb 25 12:59:53 krutghz volumio[4211]: ---------------------------- MPD announces state update: update Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 1 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 0 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 0 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 0 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:53 krutghz volumio[4211]: info: Command Router : Notfying DB Updatetrue Feb 25 12:59:53 krutghz volumio[4211]: info: CoreCommandRouter::Close All Modals sent Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:53 krutghz volumio[4211]: info: Command Router : Notfying DB Updatetrue Feb 25 12:59:53 krutghz volumio[4211]: info: CoreCommandRouter::Close All Modals sent Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:53 krutghz volumio[4211]: info: MPD Database updated - AlbumList cache refreshed Feb 25 12:59:53 krutghz volumio[4211]: info: Feb 25 12:59:53 krutghz volumio[4211]: ---------------------------- MPD announces state update: database Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: info: Feb 25 12:59:53 krutghz volumio[4211]: ---------------------------- MPD announces state update: update Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:53 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:53 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:53 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:53 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:53 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:53 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:53 krutghz volumio[4211]: info: ------------------------------ 38ms Feb 25 12:59:53 krutghz volumio[4211]: info: ------------------------------ 37ms Feb 25 12:59:53 krutghz volumio[4211]: info: MPD Database updated - AlbumList cache refreshed Feb 25 12:59:53 krutghz volumio[4211]: info: Feb 25 12:59:53 krutghz volumio[4211]: ---------------------------- MPD announces state update: database Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: info: Feb 25 12:59:53 krutghz volumio[4211]: ---------------------------- MPD announces state update: update Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 3 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 2 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 2 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 2 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 1 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 1 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:53 krutghz volumio[4211]: info: Command Router : Notfying DB Updatefalse Feb 25 12:59:53 krutghz volumio[4211]: info: CoreCommandRouter::Close All Modals sent Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:53 krutghz volumio[4211]: info: Command Router : Notfying DB Updatefalse Feb 25 12:59:53 krutghz volumio[4211]: info: CoreCommandRouter::Close All Modals sent Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:53 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:53 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:53 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:53 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:53 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:53 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:53 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:53 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:53 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:53 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:53 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:53 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:53 krutghz volumio[4211]: info: ------------------------------ 15ms Feb 25 12:59:53 krutghz volumio[4211]: info: ------------------------------ 14ms Feb 25 12:59:53 krutghz volumio[4211]: info: ------------------------------ 15ms Feb 25 12:59:53 krutghz volumio[4211]: info: ------------------------------ 15ms Feb 25 12:59:53 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand rescan Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: info: Feb 25 12:59:53 krutghz volumio[4211]: ---------------------------- MPD announces state update: update Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand rescan took 1 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: info: Feb 25 12:59:53 krutghz volumio[4211]: ---------------------------- MPD announces state update: update Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 0 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 0 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 0 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:53 krutghz volumio[4211]: info: Command Router : Notfying DB Updatetrue Feb 25 12:59:53 krutghz volumio[4211]: info: CoreCommandRouter::Close All Modals sent Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:53 krutghz volumio[4211]: info: Command Router : Notfying DB Updatetrue Feb 25 12:59:53 krutghz volumio[4211]: info: CoreCommandRouter::Close All Modals sent Feb 25 12:59:53 krutghz volumio[4211]: info: MPD Database updated - AlbumList cache refreshed Feb 25 12:59:53 krutghz volumio[4211]: info: Feb 25 12:59:53 krutghz volumio[4211]: ---------------------------- MPD announces state update: database Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: info: Feb 25 12:59:53 krutghz volumio[4211]: ---------------------------- MPD announces state update: update Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 54 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:53 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:53 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:53 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:53 krutghz volumio[4211]: info: ------------------------------ 54ms Feb 25 12:59:53 krutghz volumio[4211]: info: MPD Database updated - AlbumList cache refreshed Feb 25 12:59:53 krutghz volumio[4211]: info: Feb 25 12:59:53 krutghz volumio[4211]: ---------------------------- MPD announces state update: database Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: info: Feb 25 12:59:53 krutghz volumio[4211]: ---------------------------- MPD announces state update: update Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 2 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 2 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 2 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 1 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 2 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: info: sendMpdCommand status took 2 milliseconds Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:53 krutghz volumio[4211]: info: Command Router : Notfying DB Updatefalse Feb 25 12:59:53 krutghz volumio[4211]: info: CoreCommandRouter::Close All Modals sent Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:53 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:53 krutghz volumio[4211]: info: Command Router : Notfying DB Updatefalse Feb 25 12:59:54 krutghz volumio[4211]: info: CoreCommandRouter::Close All Modals sent Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:54 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:54 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:54 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:54 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:54 krutghz volumio[4211]: info: ------------------------------ 67ms Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:54 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:54 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:54 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:54 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:54 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:54 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:54 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:54 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:54 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:54 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:54 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:54 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:54 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:54 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:54 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:54 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:54 krutghz volumio[4211]: info: ------------------------------ 14ms Feb 25 12:59:54 krutghz volumio[4211]: info: ------------------------------ 14ms Feb 25 12:59:54 krutghz volumio[4211]: info: ------------------------------ 14ms Feb 25 12:59:54 krutghz volumio[4211]: info: ------------------------------ 13ms Feb 25 12:59:54 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand rescan Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:54 krutghz volumio[4211]: info: Feb 25 12:59:54 krutghz volumio[4211]: ---------------------------- MPD announces state update: update Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand rescan took 1 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:54 krutghz volumio[4211]: info: Feb 25 12:59:54 krutghz volumio[4211]: ---------------------------- MPD announces state update: update Feb 25 12:59:54 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand status took 0 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand status took 0 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand status took 0 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand status took 0 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:54 krutghz volumio[4211]: info: Command Router : Notfying DB Updatetrue Feb 25 12:59:54 krutghz volumio[4211]: info: CoreCommandRouter::Close All Modals sent Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:54 krutghz volumio[4211]: info: Command Router : Notfying DB Updatetrue Feb 25 12:59:54 krutghz volumio[4211]: info: CoreCommandRouter::Close All Modals sent Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:54 krutghz volumio[4211]: info: MPD Database updated - AlbumList cache refreshed Feb 25 12:59:54 krutghz volumio[4211]: info: Feb 25 12:59:54 krutghz volumio[4211]: ---------------------------- MPD announces state update: database Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:54 krutghz volumio[4211]: info: Feb 25 12:59:54 krutghz volumio[4211]: ---------------------------- MPD announces state update: update Feb 25 12:59:54 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:54 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:54 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:54 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:54 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:54 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:54 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:54 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:54 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:54 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:54 krutghz volumio[4211]: info: ------------------------------ 34ms Feb 25 12:59:54 krutghz volumio[4211]: info: ------------------------------ 34ms Feb 25 12:59:54 krutghz volumio[4211]: info: MPD Database updated - AlbumList cache refreshed Feb 25 12:59:54 krutghz volumio[4211]: info: Feb 25 12:59:54 krutghz volumio[4211]: ---------------------------- MPD announces state update: database Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:54 krutghz volumio[4211]: info: Feb 25 12:59:54 krutghz volumio[4211]: ---------------------------- MPD announces state update: update Feb 25 12:59:54 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:54 krutghz volumio[4211]: info: ControllerMpd::getState Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand status Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand status took 2 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand status took 2 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand status took 2 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand status took 1 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand status took 1 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand status took 1 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:54 krutghz volumio[4211]: info: Command Router : Notfying DB Updatefalse Feb 25 12:59:54 krutghz volumio[4211]: info: CoreCommandRouter::Close All Modals sent Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:54 krutghz volumio[4211]: info: Command Router : Notfying DB Updatefalse Feb 25 12:59:54 krutghz volumio[4211]: info: CoreCommandRouter::Close All Modals sent Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseState Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:54 krutghz volumio[4211]: verbose: ControllerMpd::parseTrackInfo Feb 25 12:59:54 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:54 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:54 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:54 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:54 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:54 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:54 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:54 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:54 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:54 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:54 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:54 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:54 krutghz volumio[4211]: info: ControllerMpd::pushState Feb 25 12:59:54 krutghz volumio[4211]: info: CoreCommandRouter::servicePushState Feb 25 12:59:54 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:54 krutghz volumio[4211]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 25 12:59:54 krutghz volumio[4211]: info: ------------------------------ 13ms Feb 25 12:59:54 krutghz volumio[4211]: info: ------------------------------ 13ms Feb 25 12:59:54 krutghz volumio[4211]: info: ------------------------------ 12ms Feb 25 12:59:54 krutghz volumio[4211]: info: ------------------------------ 12ms Feb 25 12:59:55 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 25 12:59:58 krutghz sudo[19744]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 12:59:58 krutghz sudo[19744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 12:59:58 krutghz sudo[19744]: pam_unix(sudo:session): session closed for user root Feb 25 12:59:58 krutghz sudo[19746]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 12:59:58 krutghz sudo[19746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 12:59:58 krutghz sudo[19746]: pam_unix(sudo:session): session closed for user root Feb 25 12:59:58 krutghz volumio[4211]: verbose: New Socket.io Connection to 10.8.81.252 from 10.8.81.178 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Edg/144.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Feb 25 12:59:58 krutghz volumio[4211]: info: CoreCommandRouter::volumioGetState Feb 25 12:59:58 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 12:59:58 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 25 12:59:58 krutghz volumio[4211]: info: CoreCommandRouter::volumioGetVisibleSources Feb 25 12:59:58 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 12:59:58 krutghz volumio[4211]: info: Listing playlists Feb 25 12:59:58 krutghz volumio[4211]: info: CoreCommandRouter::volumioGetQueue Feb 25 12:59:58 krutghz volumio[4211]: info: CoreStateMachine::getQueue Feb 25 12:59:58 krutghz volumio[4211]: info: CorePlayQueue::getQueue Feb 25 12:59:59 krutghz sudo[19757]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 12:59:59 krutghz sudo[19757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 12:59:59 krutghz sudo[19759]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 12:59:59 krutghz sudo[19757]: pam_unix(sudo:session): session closed for user root Feb 25 12:59:59 krutghz sudo[19759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 12:59:59 krutghz sudo[19759]: pam_unix(sudo:session): session closed for user root Feb 25 12:59:59 krutghz volumio[4211]: verbose: New Socket.io Connection to 10.8.81.252 from 10.8.81.178 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Edg/144.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Feb 25 13:00:00 krutghz sudo[19763]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 13:00:00 krutghz sudo[19763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 13:00:00 krutghz sudo[19763]: pam_unix(sudo:session): session closed for user root Feb 25 13:00:00 krutghz sudo[19765]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 13:00:00 krutghz sudo[19765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 13:00:00 krutghz sudo[19765]: pam_unix(sudo:session): session closed for user root Feb 25 13:00:00 krutghz volumio[4211]: verbose: New Socket.io Connection to 10.8.81.252 from 10.8.81.178 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Edg/144.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Feb 25 13:00:00 krutghz volumio[4211]: info: CoreCommandRouter::volumioGetState Feb 25 13:00:00 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 13:00:00 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 25 13:00:00 krutghz volumio[4211]: info: CoreCommandRouter::volumioGetVisibleSources Feb 25 13:00:00 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 13:00:00 krutghz volumio[4211]: info: Listing playlists Feb 25 13:00:00 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 25 13:00:00 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 25 13:00:00 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 25 13:00:00 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 13:00:03 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 13:00:03 krutghz volumio[4211]: info: Received Get System Info Feb 25 13:00:03 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 13:00:03 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 13:00:03 krutghz volumio[4211]: info: Discovery: Getting this device information Feb 25 13:00:03 krutghz volumio[4211]: info: CoreCommandRouter::volumioGetState Feb 25 13:00:03 krutghz volumio[4211]: info: CorePlayQueue::getTrack 0 Feb 25 13:00:03 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 13:00:03 krutghz volumio[4211]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 25 13:00:03 krutghz volumio[4211]: info: CURURI: albums:// Feb 25 13:00:03 krutghz volumio[4211]: info: listAlbums - loading Albums from cache Feb 25 13:00:03 krutghz volumio[4211]: info: CoreCommandRouter::volumioGetQueue Feb 25 13:00:03 krutghz volumio[4211]: info: CoreStateMachine::getQueue Feb 25 13:00:03 krutghz volumio[4211]: info: CorePlayQueue::getQueue Feb 25 13:00:03 krutghz volumio[4211]: info: Preload queue cleared Feb 25 13:00:04 krutghz volumio[4211]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 13:00:04 krutghz volumio[4211]: Error: self-signed certificate in certificate chain Feb 25 13:00:04 krutghz volumio[4211]: at TLSSocket.onConnectSecure (node:_tls_wrap:1627:34) Feb 25 13:00:04 krutghz volumio[4211]: at TLSSocket.emit (node:events:514:28) Feb 25 13:00:04 krutghz volumio[4211]: at TLSSocket._finishInit (node:_tls_wrap:1038:8) Feb 25 13:00:04 krutghz volumio[4211]: at ssl.onhandshakedone (node:_tls_wrap:824:12) { Feb 25 13:00:04 krutghz volumio[4211]: code: 'SELF_SIGNED_CERT_IN_CHAIN' Feb 25 13:00:04 krutghz volumio[4211]: } Feb 25 13:00:04 krutghz volumio[4211]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 13:00:04 krutghz sudo[19787]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 12:59' Feb 25 13:00:04 krutghz sudo[19787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"