-- Logs begin at Sun 2025-12-21 15:21:52 CET, end at Sun 2025-12-21 15:25:50 CET. -- Dec 21 15:24:00 volumio sudo[3838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 15:24:00 volumio sudo[3838]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:24:00 volumio sudo[3838]: pam_unix(sudo:session): session closed for user root Dec 21 15:24:00 volumio dhcpcd[853]: wlan0: leased 192.168.68.109 for 7200 seconds Dec 21 15:24:00 volumio dhcpcd[853]: wlan0: adding route to 192.168.68.0/22 Dec 21 15:24:00 volumio dhcpcd[853]: wlan0: adding default route via 192.168.68.1 Dec 21 15:24:00 volumio avahi-daemon[766]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.68.109. Dec 21 15:24:00 volumio avahi-daemon[766]: New relevant interface wlan0.IPv4 for mDNS. Dec 21 15:24:00 volumio avahi-daemon[766]: Registering new address record for 192.168.68.109 on wlan0.IPv4. Dec 21 15:24:00 volumio ntpd[951]: ntpd exiting on signal 15 (Terminated) Dec 21 15:24:00 volumio systemd[1]: Stopping Network Time Service... Dec 21 15:24:00 volumio systemd[1]: ntp.service: Succeeded. Dec 21 15:24:00 volumio systemd[1]: Stopped Network Time Service. Dec 21 15:24:00 volumio systemd[1]: Starting Network Time Service... Dec 21 15:24:00 volumio ntpd[3873]: ntpd 4.2.8p12@1.3728-o (1): Starting Dec 21 15:24:00 volumio ntpd[3873]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Dec 21 15:24:00 volumio systemd[1]: Started Network Time Service. Dec 21 15:24:00 volumio ntpd[3879]: proto: precision = 0.043 usec (-24) Dec 21 15:24:00 volumio ntpd[3879]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 21 15:24:00 volumio ntpd[3879]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Dec 21 15:24:00 volumio ntpd[3879]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1090 days ago Dec 21 15:24:00 volumio ntpd[3879]: Listen and drop on 0 v6wildcard [::]:123 Dec 21 15:24:00 volumio ntpd[3879]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 21 15:24:00 volumio ntpd[3879]: Listen normally on 2 lo 127.0.0.1:123 Dec 21 15:24:00 volumio ntpd[3879]: Listen normally on 3 wlan0 192.168.68.109:123 Dec 21 15:24:00 volumio ntpd[3879]: Listening on routing socket on fd #20 for interface updates Dec 21 15:24:00 volumio ntpd[3879]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 21 15:24:00 volumio ntpd[3879]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 21 15:24:00 volumio wireless.js[3653]: trying... Dec 21 15:24:00 volumio sudo[3883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 21 15:24:00 volumio sudo[3883]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:24:00 volumio sudo[3883]: pam_unix(sudo:session): session closed for user root Dec 21 15:24:00 volumio wireless.js[3653]: Connected to: ----TOMY Dec 21 15:24:00 volumio wireless.js[3653]: ---- Dec 21 15:24:00 volumio sudo[3886]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 15:24:00 volumio sudo[3886]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:24:00 volumio sudo[3886]: pam_unix(sudo:session): session closed for user root Dec 21 15:24:00 volumio wireless.js[3653]: ... joined AP, wlan0 IPv4 is 192.168.68.109, ipV6 is undefined Dec 21 15:24:00 volumio wireless.js[3653]: It's done! AP Dec 21 15:24:00 volumio iwconfig[3890]: Error for wireless request "Set Power Management" (8B2C) : Dec 21 15:24:00 volumio iwconfig[3890]: SET failed on device wlan0 ; Operation not supported. Dec 21 15:24:00 volumio systemd[1]: Started Wireless Services. Dec 21 15:24:00 volumio sudo[3651]: pam_unix(sudo:session): session closed for user root Dec 21 15:24:01 volumio sudo[3892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 15:24:01 volumio sudo[3892]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:24:01 volumio sudo[3892]: pam_unix(sudo:session): session closed for user root Dec 21 15:24:01 volumio sudo[3895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 21 15:24:01 volumio sudo[3895]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:24:01 volumio sudo[3895]: pam_unix(sudo:session): session closed for user root Dec 21 15:24:01 volumio ntpd[3879]: Soliciting pool server 185.198.109.227 Dec 21 15:24:02 volumio ntpd[3879]: Soliciting pool server 172.233.111.193 Dec 21 15:24:02 volumio ntpd[3879]: Soliciting pool server 82.165.173.235 Dec 21 15:24:03 volumio ntpd[3879]: Soliciting pool server 185.134.42.7 Dec 21 15:24:03 volumio ntpd[3879]: Soliciting pool server 5.250.184.159 Dec 21 15:24:03 volumio ntpd[3879]: Soliciting pool server 5.250.191.170 Dec 21 15:24:03 volumio volumio-time-update[757]: volumio-time-update-util: Fetching time from Volumio... Dec 21 15:24:03 volumio volumio-time-update[757]: volumio-time-update-util: Setting system time to: 2025-12-21 15:24:03 Dec 21 15:24:03 volumio sudo[3911]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-12-21 15:24:03 Dec 21 15:24:03 volumio sudo[3911]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:24:03 volumio dbus-daemon[763]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.74' (uid=0 pid=3912 comm="timedatectl set-time 2025-12-21 15:24:03 ") Dec 21 15:24:03 volumio systemd[1]: Starting Time & Date Service... Dec 21 15:24:03 volumio dbus-daemon[763]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 21 15:24:03 volumio systemd[1]: Started Time & Date Service. Dec 21 15:24:03 volumio systemd-timedated[3913]: Changed local time to Sun Dec 21 15:24:03 2025 Dec 21 15:24:03 volumio sudo[3911]: pam_unix(sudo:session): session closed for user root Dec 21 15:24:03 volumio volumio-time-update[757]: volumio-time-update-util: System time updated successfully. Dec 21 15:24:03 volumio systemd[1]: Started Volumio Time Update Utility. Dec 21 15:24:03 volumio systemd[1]: Reached target Multi-User System. Dec 21 15:24:03 volumio systemd[1]: Reached target Graphical Interface. Dec 21 15:24:03 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 21 15:24:03 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 21 15:24:03 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 21 15:24:03 volumio systemd[1]: Startup finished in 11.167s (firmware) + 633ms (loader) + 13.998s (kernel) + 2min 11.571s (userspace) = 2min 37.371s. Dec 21 15:24:03 volumio ntpd[3879]: Soliciting pool server 195.20.235.143 Dec 21 15:24:03 volumio ntpd[3879]: Soliciting pool server 93.176.191.241 Dec 21 15:24:03 volumio ntpd[3879]: Soliciting pool server 92.113.12.77 Dec 21 15:24:03 volumio ntpd[3879]: Soliciting pool server 92.113.12.78 Dec 21 15:24:04 volumio ntpd[3879]: Soliciting pool server 162.159.200.123 Dec 21 15:24:04 volumio ntpd[3879]: Soliciting pool server 194.164.164.175 Dec 21 15:24:04 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Dec 21 15:24:04 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Dec 21 15:24:04 volumio sudo[3917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 15:24:04 volumio sudo[3917]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:24:04 volumio sudo[3917]: pam_unix(sudo:session): session closed for user root Dec 21 15:24:04 volumio sudo[3920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 21 15:24:04 volumio sudo[3920]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:24:04 volumio sudo[3920]: pam_unix(sudo:session): session closed for user root Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 21 15:24:05 volumio volumio[1088]: info: Adding plugin bluetooth to MyMusic Plugins Dec 21 15:24:05 volumio volumio[1088]: info: Adding plugin multiroom to MyMusic Plugins Dec 21 15:24:05 volumio volumio[1088]: info: Adding plugin metavolumio to MyMusic Plugins Dec 21 15:24:05 volumio volumio[1088]: info: Adding plugin cd_controller to MyMusic Plugins Dec 21 15:24:05 volumio volumio[1088]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 21 15:24:05 volumio volumio[1088]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 21 15:24:05 volumio volumio[1088]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 21 15:24:05 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 15:24:05 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 15:24:05 volumio volumio[1088]: info: Starting MyVolumio Remote Streaming Endpoints Dec 21 15:24:05 volumio volumio[1088]: info: MyVolumio not started Dec 21 15:24:05 volumio volumio[1088]: info: Initializing device activation check Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 21 15:24:05 volumio volumio[1088]: info: Starting Streaming Service Transparent Proxy Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 21 15:24:05 volumio volumio[1088]: info: Streaming services startup Dec 21 15:24:05 volumio volumio[1088]: info: Starting Streaming Daemon Dec 21 15:24:05 volumio sudo[3946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 21 15:24:05 volumio sudo[3946]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:24:05 volumio sudo[3946]: pam_unix(sudo:session): session closed for user root Dec 21 15:24:05 volumio volumio[1088]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 21 15:24:05 volumio volumio[1088]: info: Volumio Network Manager: Network status updated: 2 Dec 21 15:24:05 volumio volumio[1088]: error: Cannot start Volumio Streaming Daemon Dec 21 15:24:05 volumio volumio[1088]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 21 15:24:05 volumio volumio[1088]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 21 15:24:06 volumio volumio[1088]: STREAMING PROXY: Starting server on port 3245 Dec 21 15:24:06 volumio volumio[1088]: Node JS runtime: 14 Dec 21 15:24:08 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 15:24:08 volumio volumio[1088]: info: Retrieving Cloud Streaming UI Dec 21 15:24:08 volumio volumio[1088]: info: Getting Tidal Cloud Configuration Dec 21 15:24:08 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 15:24:08 volumio volumio[1088]: info: Getting Qobuz Cloud Configuration Dec 21 15:24:08 volumio volumio[1088]: info: Asking plugin for UI Config Dec 21 15:24:08 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 15:24:08 volumio volumio[1088]: info: Getting Spotify Cloud Configuration Dec 21 15:24:08 volumio volumio[1088]: info: Asking plugin for UI Config Dec 21 15:24:08 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 15:24:08 volumio volumio[1088]: info: Saving Spotify Acccount Dec 21 15:24:08 volumio volumio[1088]: info: Got Tidal Cloud Configuration Dec 21 15:24:08 volumio volumio[1088]: info: Got it Dec 21 15:24:08 volumio volumio[1088]: info: Got it Dec 21 15:24:08 volumio volumio[1088]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 21 15:24:08 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 15:24:08 volumio volumio[1088]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 21 15:24:10 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Dec 21 15:24:10 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Dec 21 15:24:10 volumio volumio[1088]: info: Refreshing Cached IP Addresses Dec 21 15:24:10 volumio sudo[3957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 21 15:24:10 volumio sudo[3957]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:24:10 volumio sudo[3957]: pam_unix(sudo:session): session closed for user root Dec 21 15:24:10 volumio sudo[3960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 21 15:24:10 volumio sudo[3960]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:24:10 volumio sudo[3960]: pam_unix(sudo:session): session closed for user root Dec 21 15:24:10 volumio volumio[1088]: error: Upnp client error: Error: This socket has been ended by the other party Dec 21 15:24:10 volumio sudo[3963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 15:24:10 volumio sudo[3963]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:24:10 volumio sudo[3963]: pam_unix(sudo:session): session closed for user root Dec 21 15:24:13 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Dec 21 15:24:13 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 21 15:24:13 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Dec 21 15:24:13 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Dec 21 15:24:13 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Dec 21 15:24:16 volumio volumio[1088]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Dec 21 15:24:16 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Dec 21 15:24:16 volumio volumio[1088]: error: Failed callmethod call: TypeError: Cannot read property 'value' of undefined Dec 21 15:24:18 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Dec 21 15:24:18 volumio volumio[1088]: info: CoreCommandRouter::Reload Ui Dec 21 15:24:18 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Dec 21 15:24:18 volumio volumio[1088]: info: Wizard terminated Successfully Dec 21 15:24:18 volumio volumio[1088]: info: CoreCommandRouter::volumiosetStartupVolume Dec 21 15:24:18 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 15:24:19 volumio volumio[1088]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::volumioGetVisibleSources Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:24:19 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 21 15:24:19 volumio volumio[1088]: info: Received Get System Info Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 15:24:19 volumio volumio[1088]: info: Discovery: Getting this device information Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:24:19 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:24:19 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:24:19 volumio volumio[1088]: info: Listing playlists Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 21 15:24:19 volumio volumio[1088]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::volumioGetVisibleSources Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:24:19 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 21 15:24:19 volumio volumio[1088]: info: Received Get System Info Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 15:24:19 volumio volumio[1088]: info: Discovery: Getting this device information Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:24:19 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:24:19 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:24:19 volumio volumio[1088]: info: Listing playlists Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 21 15:24:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 21 15:24:20 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:24:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:24:20 volumio sudo[3989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 21 15:24:20 volumio sudo[3989]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:24:20 volumio sudo[3989]: pam_unix(sudo:session): session closed for user root Dec 21 15:24:20 volumio sudo[3992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 21 15:24:20 volumio sudo[3992]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:24:20 volumio sudo[3992]: pam_unix(sudo:session): session closed for user root Dec 21 15:24:20 volumio sudo[3995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 21 15:24:20 volumio sudo[3995]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:24:20 volumio sudo[3995]: pam_unix(sudo:session): session closed for user root Dec 21 15:24:20 volumio volumio[1088]: info: Upmpdcli Daemon Started Dec 21 15:24:20 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 21 15:24:21 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 21 15:24:21 volumio volumio[1088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 21 15:24:21 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:24:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:24:21 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 21 15:24:21 volumio volumio[1088]: info: Received Get System Info Dec 21 15:24:21 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 15:24:21 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 15:24:21 volumio volumio[1088]: info: Discovery: Getting this device information Dec 21 15:24:21 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:24:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:24:21 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 15:24:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 15:24:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 15:24:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 21 15:24:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 21 15:24:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 21 15:24:26 volumio volumio[1088]: info: CoreCommandRouter::volumioGetBrowseSources Dec 21 15:24:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 15:24:29 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 21 15:24:33 volumio systemd[1]: systemd-timedated.service: Succeeded. Dec 21 15:24:37 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 21 15:24:37 volumio volumio[1088]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 21 15:24:37 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 21 15:24:37 volumio volumio[1088]: info: Received Get System Version Dec 21 15:24:37 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 15:24:37 volumio volumio[1088]: info: Received Get System Info Dec 21 15:24:37 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 15:24:37 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 15:24:37 volumio volumio[1088]: info: Discovery: Getting this device information Dec 21 15:24:37 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:24:37 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:24:37 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 15:24:46 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Dec 21 15:24:49 volumio volumio[1088]: info: CoreCommandRouter::volumioGetQueue Dec 21 15:24:49 volumio volumio[1088]: info: CoreStateMachine::getQueue Dec 21 15:24:49 volumio volumio[1088]: info: CorePlayQueue::getQueue Dec 21 15:24:50 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 21 15:24:50 volumio volumio[1088]: info: CURURI: music-library Dec 21 15:24:50 volumio volumio[1088]: error: Failed LSINFO: null Dec 21 15:24:50 volumio volumio[1088]: info: Preload queue cleared Dec 21 15:24:54 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:24:54 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:24:56 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 21 15:24:56 volumio volumio[1088]: info: CURURI: music-library Dec 21 15:24:56 volumio volumio[1088]: error: Failed LSINFO: null Dec 21 15:24:56 volumio volumio[1088]: info: Preload queue cleared Dec 21 15:24:57 volumio volumio[1088]: info: Discovery: adding e5424d42-5bdd-4131-9b7d-47163b5389e0 Dec 21 15:24:57 volumio volumio[1088]: info: Discovery: Found device Volumio Dec 21 15:24:57 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:24:57 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:00 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:25:00 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:04 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 15:25:04 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 21 15:25:04 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 21 15:25:04 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 21 15:25:04 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 21 15:25:04 volumio volumio[1088]: info: Received Get System Version Dec 21 15:25:04 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 15:25:08 volumio volumio[1088]: info: CALLMETHOD: system_controller system setLanguageTimezone [object Object] Dec 21 15:25:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , setLanguageTimezone Dec 21 15:25:08 volumio volumio[1088]: info: Setting timezone to Europe/Madrid Dec 21 15:25:08 volumio sudo[4157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Dec 21 15:25:08 volumio sudo[4157]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:25:08 volumio sudo[4157]: pam_unix(sudo:session): session closed for user root Dec 21 15:25:08 volumio sudo[4160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Madrid /etc/localtime Dec 21 15:25:08 volumio sudo[4160]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:25:08 volumio sudo[4160]: pam_unix(sudo:session): session closed for user root Dec 21 15:25:08 volumio sudo[4163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Dec 21 15:25:08 volumio sudo[4163]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:25:08 volumio sudo[4163]: pam_unix(sudo:session): session closed for user root Dec 21 15:25:08 volumio sudo[4166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Madrid Dec 21 15:25:08 volumio sudo[4166]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 21 15:25:08 volumio dbus-daemon[763]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.76' (uid=0 pid=4167 comm="/usr/bin/timedatectl set-timezone Europe/Madrid ") Dec 21 15:25:09 volumio systemd[1]: Starting Time & Date Service... Dec 21 15:25:09 volumio dbus-daemon[763]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 21 15:25:09 volumio systemd[1]: Started Time & Date Service. Dec 21 15:25:09 volumio sudo[4166]: pam_unix(sudo:session): session closed for user root Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Dec 21 15:25:09 volumio volumio[1088]: info: Loading i18n strings for locale es Dec 21 15:25:09 volumio volumio[1088]: Updating browse sources language Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 15:25:09 volumio volumio[1088]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::volumioGetVisibleSources Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:25:09 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 21 15:25:09 volumio volumio[1088]: info: Received Get System Info Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 15:25:09 volumio volumio[1088]: info: Discovery: Getting this device information Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:25:09 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:25:09 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:09 volumio volumio[1088]: info: Listing playlists Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 21 15:25:09 volumio volumio[1088]: info: Fetching Streaming Services browse cache Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 21 15:25:09 volumio volumio[1088]: info: Received Get System Version Dec 21 15:25:09 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 15:25:11 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 21 15:25:11 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 21 15:25:11 volumio volumio[1088]: info: Received Get System Info Dec 21 15:25:11 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 15:25:11 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 15:25:11 volumio volumio[1088]: info: Discovery: Getting this device information Dec 21 15:25:11 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:25:11 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:11 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 15:25:14 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:25:14 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:15 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 15:25:15 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 15:25:15 volumio volumio[1088]: info: Retrieving Cloud Streaming UI Dec 21 15:25:15 volumio volumio[1088]: info: Getting Tidal Cloud Configuration Dec 21 15:25:15 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 15:25:15 volumio volumio[1088]: info: Getting Qobuz Cloud Configuration Dec 21 15:25:15 volumio volumio[1088]: info: Asking plugin for UI Config Dec 21 15:25:15 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 15:25:15 volumio volumio[1088]: info: Getting Spotify Cloud Configuration Dec 21 15:25:15 volumio volumio[1088]: info: Asking plugin for UI Config Dec 21 15:25:15 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 15:25:15 volumio volumio[1088]: info: Saving Spotify Acccount Dec 21 15:25:15 volumio volumio[1088]: info: Got Tidal Cloud Configuration Dec 21 15:25:15 volumio volumio[1088]: info: Got it Dec 21 15:25:15 volumio volumio[1088]: info: Got it Dec 21 15:25:15 volumio volumio[1088]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 21 15:25:15 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 15:25:15 volumio volumio[1088]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 21 15:25:15 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 21 15:25:15 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 21 15:25:15 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 21 15:25:15 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 21 15:25:15 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 15:25:15 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 15:25:15 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 15:25:15 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 21 15:25:15 volumio volumio[1088]: info: CoreCommandRouter::volumioGetBrowseSources Dec 21 15:25:15 volumio volumio[1088]: info: CoreCommandRouter::volumioGetBrowseSources Dec 21 15:25:15 volumio volumio[1088]: info: CoreCommandRouter::volumioGetBrowseSources Dec 21 15:25:16 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 21 15:25:16 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 21 15:25:17 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand update Dec 21 15:25:17 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:17 volumio volumio[1088]: info: Dec 21 15:25:17 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:17 volumio volumio[1088]: info: sendMpdCommand update took 2 milliseconds Dec 21 15:25:17 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:17 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:17 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:17 volumio volumio[1088]: info: Dec 21 15:25:17 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:17 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:17 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:17 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Dec 21 15:25:17 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:17 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:17 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:17 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:17 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:17 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:17 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:17 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:17 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:17 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:17 volumio volumio[1088]: info: No code Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:17 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:17 volumio volumio[1088]: info: ------------------------------ 20ms Dec 21 15:25:17 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:17 volumio volumio[1088]: info: Dec 21 15:25:17 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:17 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:17 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:17 volumio volumio[1088]: info: sendMpdCommand status took 20 milliseconds Dec 21 15:25:17 volumio volumio[1088]: info: sendMpdCommand status took 19 milliseconds Dec 21 15:25:17 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:17 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:17 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:17 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:17 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:17 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:17 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:17 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:17 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:17 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:17 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:17 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:17 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:17 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:17 volumio volumio[1088]: info: No code Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:17 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:17 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:17 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:17 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:17 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:17 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:17 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:17 volumio volumio[1088]: info: No code Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:17 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:17 volumio volumio[1088]: info: ------------------------------ 47ms Dec 21 15:25:17 volumio volumio[1088]: info: ------------------------------ 27ms Dec 21 15:25:17 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:17 volumio volumio[1088]: info: Dec 21 15:25:17 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:17 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:17 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:17 volumio volumio[1088]: info: sendMpdCommand status took 3 milliseconds Dec 21 15:25:17 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:17 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:17 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:17 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:17 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:17 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:17 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:17 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:17 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:17 volumio volumio[1088]: info: No code Dec 21 15:25:17 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:17 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:17 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:17 volumio volumio[1088]: info: ------------------------------ 27ms Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 21 15:25:18 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand rescan Dec 21 15:25:18 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:18 volumio volumio[1088]: info: Dec 21 15:25:18 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:18 volumio volumio[1088]: info: sendMpdCommand rescan took 1 milliseconds Dec 21 15:25:18 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:18 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:18 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:18 volumio volumio[1088]: info: Dec 21 15:25:18 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:18 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:18 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:18 volumio volumio[1088]: info: Dec 21 15:25:18 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:18 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:18 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:18 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:18 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:18 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:18 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:18 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:18 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:18 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:18 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:18 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:18 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:18 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:18 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:18 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:18 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:18 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:18 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:18 volumio volumio[1088]: info: No code Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:18 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:18 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:18 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:18 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:18 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:18 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:18 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:18 volumio volumio[1088]: info: No code Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:18 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:18 volumio volumio[1088]: info: ------------------------------ 26ms Dec 21 15:25:18 volumio volumio[1088]: info: ------------------------------ 26ms Dec 21 15:25:18 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:18 volumio volumio[1088]: info: Dec 21 15:25:18 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:18 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:18 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:18 volumio volumio[1088]: info: sendMpdCommand status took 26 milliseconds Dec 21 15:25:18 volumio volumio[1088]: info: sendMpdCommand status took 26 milliseconds Dec 21 15:25:18 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:18 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:18 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:18 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:18 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:18 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:18 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:18 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:18 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:18 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:18 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:18 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:18 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:18 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:18 volumio volumio[1088]: info: No code Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:18 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:18 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:18 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:18 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:18 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:18 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:18 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:18 volumio volumio[1088]: info: No code Dec 21 15:25:18 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:18 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:18 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:18 volumio volumio[1088]: info: ------------------------------ 57ms Dec 21 15:25:18 volumio volumio[1088]: info: ------------------------------ 32ms Dec 21 15:25:19 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 21 15:25:20 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand update Dec 21 15:25:20 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:20 volumio volumio[1088]: info: Dec 21 15:25:20 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:20 volumio volumio[1088]: info: sendMpdCommand update took 1 milliseconds Dec 21 15:25:20 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:20 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:20 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:20 volumio volumio[1088]: info: Dec 21 15:25:20 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:20 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:20 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:20 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:20 volumio volumio[1088]: info: Dec 21 15:25:20 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:20 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:20 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:20 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:20 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:20 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:20 volumio volumio[1088]: info: sendMpdCommand status took 12 milliseconds Dec 21 15:25:20 volumio volumio[1088]: info: sendMpdCommand status took 12 milliseconds Dec 21 15:25:20 volumio volumio[1088]: info: sendMpdCommand status took 12 milliseconds Dec 21 15:25:20 volumio volumio[1088]: info: sendMpdCommand status took 11 milliseconds Dec 21 15:25:20 volumio volumio[1088]: info: sendMpdCommand status took 11 milliseconds Dec 21 15:25:20 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:20 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:20 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:20 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:20 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:20 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:20 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:20 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:20 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:20 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:20 volumio volumio[1088]: info: No code Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:20 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:20 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:20 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:20 volumio volumio[1088]: info: No code Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:20 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:20 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:20 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:20 volumio volumio[1088]: info: No code Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:20 volumio volumio[1088]: info: ------------------------------ 36ms Dec 21 15:25:20 volumio volumio[1088]: info: ------------------------------ 40ms Dec 21 15:25:20 volumio volumio[1088]: info: ------------------------------ 39ms Dec 21 15:25:20 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:20 volumio volumio[1088]: info: Dec 21 15:25:20 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:20 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:20 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:20 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Dec 21 15:25:20 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Dec 21 15:25:20 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:20 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:20 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:20 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:20 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:20 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:20 volumio volumio[1088]: info: No code Dec 21 15:25:20 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:20 volumio volumio[1088]: info: ------------------------------ 15ms Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand update Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: Dec 21 15:25:21 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand update took 1 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: Dec 21 15:25:21 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: Dec 21 15:25:21 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:21 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: No code Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: ------------------------------ 22ms Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: Dec 21 15:25:21 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 23 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 22 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 22 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:21 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: No code Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:21 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: No code Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:21 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: No code Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: ------------------------------ 48ms Dec 21 15:25:21 volumio volumio[1088]: info: ------------------------------ 52ms Dec 21 15:25:21 volumio volumio[1088]: info: ------------------------------ 31ms Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand update Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: Dec 21 15:25:21 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand update took 0 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: Dec 21 15:25:21 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: Dec 21 15:25:21 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:21 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: No code Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: ------------------------------ 15ms Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 14 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 14 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 14 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 14 milliseconds Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:21 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: No code Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:21 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: No code Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: ------------------------------ 38ms Dec 21 15:25:21 volumio volumio[1088]: info: ------------------------------ 38ms Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: Dec 21 15:25:21 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 3 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 3 milliseconds Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:21 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: No code Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: ------------------------------ 15ms Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand update Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: Dec 21 15:25:21 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand update took 1 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: Dec 21 15:25:21 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: Dec 21 15:25:21 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:21 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: No code Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:21 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: No code Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: ------------------------------ 24ms Dec 21 15:25:21 volumio volumio[1088]: info: ------------------------------ 24ms Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: Dec 21 15:25:21 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 24 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 25 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:21 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: No code Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:21 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: No code Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: ------------------------------ 48ms Dec 21 15:25:21 volumio volumio[1088]: info: ------------------------------ 24ms Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand update Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: Dec 21 15:25:21 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand update took 0 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: Dec 21 15:25:21 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:21 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: No code Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: ------------------------------ 14ms Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: Dec 21 15:25:21 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 13 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 13 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:21 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:21 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: No code Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:21 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: No code Dec 21 15:25:21 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:21 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:21 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:21 volumio volumio[1088]: info: ------------------------------ 37ms Dec 21 15:25:21 volumio volumio[1088]: info: ------------------------------ 24ms Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:21 volumio volumio[1088]: info: Dec 21 15:25:21 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:21 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:21 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:22 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Dec 21 15:25:22 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:22 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:22 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:22 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:22 volumio volumio[1088]: info: No code Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:22 volumio volumio[1088]: info: ------------------------------ 15ms Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand update Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:22 volumio volumio[1088]: info: Dec 21 15:25:22 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:22 volumio volumio[1088]: info: sendMpdCommand update took 1 milliseconds Dec 21 15:25:22 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:22 volumio volumio[1088]: info: Dec 21 15:25:22 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:22 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:22 volumio volumio[1088]: info: Dec 21 15:25:22 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:22 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:22 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:22 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:22 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:22 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:22 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:22 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:22 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:22 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:22 volumio volumio[1088]: info: No code Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:22 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:22 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:22 volumio volumio[1088]: info: No code Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:22 volumio volumio[1088]: info: ------------------------------ 24ms Dec 21 15:25:22 volumio volumio[1088]: info: ------------------------------ 24ms Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:22 volumio volumio[1088]: info: Dec 21 15:25:22 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:22 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:22 volumio volumio[1088]: info: sendMpdCommand status took 24 milliseconds Dec 21 15:25:22 volumio volumio[1088]: info: sendMpdCommand status took 24 milliseconds Dec 21 15:25:22 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:22 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:22 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:22 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:22 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:22 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:22 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:22 volumio volumio[1088]: info: No code Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:22 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:22 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:22 volumio volumio[1088]: info: No code Dec 21 15:25:22 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:22 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:22 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:22 volumio volumio[1088]: info: ------------------------------ 49ms Dec 21 15:25:22 volumio volumio[1088]: info: ------------------------------ 26ms Dec 21 15:25:24 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 21 15:25:27 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 21 15:25:27 volumio volumio[1088]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 21 15:25:27 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 21 15:25:27 volumio volumio[1088]: info: Received Get System Version Dec 21 15:25:27 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 15:25:27 volumio volumio[1088]: info: Received Get System Info Dec 21 15:25:27 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 15:25:27 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 15:25:27 volumio volumio[1088]: info: Discovery: Getting this device information Dec 21 15:25:27 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 21 15:25:27 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:27 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 15:25:28 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 21 15:25:32 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 21 15:25:36 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 21 15:25:39 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Dec 21 15:25:39 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 21 15:25:39 volumio systemd[1]: systemd-timedated.service: Succeeded. Dec 21 15:25:39 volumio volumio-remote-updater[2420]: No test mode Dec 21 15:25:39 volumio volumio-remote-updater[2420]: No alpha test mode Dec 21 15:25:39 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 21 15:25:39 volumio volumio[1088]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES



 

 

 



NEW ADDITIONS



 

 

 

","title":"Update v3.886","updateavailable":true} Dec 21 15:25:39 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 21 15:25:40 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand rescan Dec 21 15:25:40 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:40 volumio volumio[1088]: info: Dec 21 15:25:40 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:40 volumio volumio[1088]: info: sendMpdCommand rescan took 1 milliseconds Dec 21 15:25:40 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:40 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:40 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:40 volumio volumio[1088]: info: Dec 21 15:25:40 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:40 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:40 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:40 volumio volumio[1088]: info: Dec 21 15:25:40 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:40 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:40 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:40 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:40 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:40 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:40 volumio volumio[1088]: info: sendMpdCommand status took 11 milliseconds Dec 21 15:25:40 volumio volumio[1088]: info: sendMpdCommand status took 11 milliseconds Dec 21 15:25:40 volumio volumio[1088]: info: sendMpdCommand status took 10 milliseconds Dec 21 15:25:40 volumio volumio[1088]: info: sendMpdCommand status took 10 milliseconds Dec 21 15:25:40 volumio volumio[1088]: info: sendMpdCommand status took 10 milliseconds Dec 21 15:25:40 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:40 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:40 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:40 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:40 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:40 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:40 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:40 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:40 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:40 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:40 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:40 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:40 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:40 volumio volumio[1088]: info: No code Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:40 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:40 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:40 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:40 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:40 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:40 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:40 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:40 volumio volumio[1088]: info: No code Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:40 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:40 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:40 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:40 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:40 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:40 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:40 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:40 volumio volumio[1088]: info: No code Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:40 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:40 volumio volumio[1088]: info: ------------------------------ 37ms Dec 21 15:25:40 volumio volumio[1088]: info: ------------------------------ 41ms Dec 21 15:25:40 volumio volumio[1088]: info: ------------------------------ 42ms Dec 21 15:25:40 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:40 volumio volumio[1088]: info: Dec 21 15:25:40 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:40 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:40 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:40 volumio volumio[1088]: info: sendMpdCommand status took 3 milliseconds Dec 21 15:25:40 volumio volumio[1088]: info: sendMpdCommand status took 4 milliseconds Dec 21 15:25:40 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:40 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:40 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:40 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:40 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:40 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:40 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:40 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:40 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:40 volumio volumio[1088]: info: No code Dec 21 15:25:40 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:40 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:40 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:40 volumio volumio[1088]: info: ------------------------------ 15ms Dec 21 15:25:41 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 21 15:25:41 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand update Dec 21 15:25:41 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:41 volumio volumio[1088]: info: Dec 21 15:25:41 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:41 volumio volumio[1088]: info: sendMpdCommand update took 1 milliseconds Dec 21 15:25:41 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:41 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:41 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:41 volumio volumio[1088]: info: Dec 21 15:25:41 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:41 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:41 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:41 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:41 volumio volumio[1088]: info: Dec 21 15:25:41 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:41 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:41 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:41 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:41 volumio volumio[1088]: info: Dec 21 15:25:41 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:41 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:41 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:41 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Dec 21 15:25:41 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:41 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:41 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:41 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:41 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:41 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:41 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:41 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 29ms Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 28ms Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 28 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 29 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 29 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 28 milliseconds Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 52ms Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 51ms Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand update Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand update took 1 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 10 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 10 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 10 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 10 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 9 milliseconds Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 31ms Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 37ms Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 37ms Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 3 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 3 milliseconds Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 16ms Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand update Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand update took 1 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 26ms Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 25ms Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 26 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 26 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 50ms Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 26ms Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand update Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand update took 1 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatetrue Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 15ms Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 14 milliseconds Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 29ms Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 16 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 50ms Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 35ms Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand update Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand update took 0 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 14ms Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 13 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 13 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 12 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 12 milliseconds Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 36ms Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 36ms Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 2 milliseconds Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:42 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: No code Dec 21 15:25:42 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:42 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:42 volumio volumio[1088]: info: ------------------------------ 13ms Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand update Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand update took 1 milliseconds Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: Dec 21 15:25:42 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:42 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:42 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:42 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:42 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:43 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: No code Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:43 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: No code Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: ------------------------------ 31ms Dec 21 15:25:43 volumio volumio[1088]: info: ------------------------------ 31ms Dec 21 15:25:43 volumio volumio[1088]: info: sendMpdCommand status took 30 milliseconds Dec 21 15:25:43 volumio volumio[1088]: info: sendMpdCommand status took 30 milliseconds Dec 21 15:25:43 volumio volumio[1088]: info: sendMpdCommand status took 30 milliseconds Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:43 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: No code Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:43 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: No code Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: ------------------------------ 46ms Dec 21 15:25:43 volumio volumio[1088]: info: ------------------------------ 48ms Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand rescan Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:43 volumio volumio[1088]: info: Dec 21 15:25:43 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:43 volumio volumio[1088]: info: sendMpdCommand rescan took 1 milliseconds Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:43 volumio volumio[1088]: info: Dec 21 15:25:43 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:43 volumio volumio[1088]: info: Dec 21 15:25:43 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:43 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:43 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:43 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: No code Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:43 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: No code Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: ------------------------------ 27ms Dec 21 15:25:43 volumio volumio[1088]: info: ------------------------------ 27ms Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:43 volumio volumio[1088]: info: Dec 21 15:25:43 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:43 volumio volumio[1088]: info: sendMpdCommand status took 28 milliseconds Dec 21 15:25:43 volumio volumio[1088]: info: sendMpdCommand status took 27 milliseconds Dec 21 15:25:43 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:43 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:43 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: No code Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:43 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: No code Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: ------------------------------ 51ms Dec 21 15:25:43 volumio volumio[1088]: info: ------------------------------ 23ms Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand rescan Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:43 volumio volumio[1088]: info: Dec 21 15:25:43 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:43 volumio volumio[1088]: info: sendMpdCommand rescan took 1 milliseconds Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:43 volumio volumio[1088]: info: Dec 21 15:25:43 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:43 volumio volumio[1088]: info: Dec 21 15:25:43 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:43 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:43 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: No code Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: ------------------------------ 13ms Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:43 volumio volumio[1088]: info: Dec 21 15:25:43 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:43 volumio volumio[1088]: info: sendMpdCommand status took 14 milliseconds Dec 21 15:25:43 volumio volumio[1088]: info: sendMpdCommand status took 13 milliseconds Dec 21 15:25:43 volumio volumio[1088]: info: sendMpdCommand status took 13 milliseconds Dec 21 15:25:43 volumio volumio[1088]: info: sendMpdCommand status took 13 milliseconds Dec 21 15:25:43 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:43 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:43 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:43 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: No code Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:43 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: No code Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:43 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: No code Dec 21 15:25:43 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:43 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:43 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:43 volumio volumio[1088]: info: ------------------------------ 46ms Dec 21 15:25:43 volumio volumio[1088]: info: ------------------------------ 45ms Dec 21 15:25:43 volumio volumio[1088]: info: ------------------------------ 33ms Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand rescan Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: Dec 21 15:25:44 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand rescan took 1 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: Dec 21 15:25:44 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: Dec 21 15:25:44 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:44 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: No code Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: ------------------------------ 21ms Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: Dec 21 15:25:44 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 22 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 22 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 21 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:44 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: No code Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:44 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: No code Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:44 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: No code Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: ------------------------------ 45ms Dec 21 15:25:44 volumio volumio[1088]: info: ------------------------------ 52ms Dec 21 15:25:44 volumio volumio[1088]: info: ------------------------------ 30ms Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand rescan Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: Dec 21 15:25:44 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand rescan took 1 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: Dec 21 15:25:44 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: Dec 21 15:25:44 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:44 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: No code Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:44 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: No code Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: ------------------------------ 23ms Dec 21 15:25:44 volumio volumio[1088]: info: ------------------------------ 23ms Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: Dec 21 15:25:44 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 22 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 22 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:44 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: No code Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:44 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: No code Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: ------------------------------ 44ms Dec 21 15:25:44 volumio volumio[1088]: info: ------------------------------ 22ms Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand rescan Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: Dec 21 15:25:44 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand rescan took 2 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: Dec 21 15:25:44 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: Dec 21 15:25:44 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: Dec 21 15:25:44 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:44 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: No code Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:44 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: No code Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:44 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: No code Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: ------------------------------ 32ms Dec 21 15:25:44 volumio volumio[1088]: info: ------------------------------ 32ms Dec 21 15:25:44 volumio volumio[1088]: info: ------------------------------ 31ms Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 32 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 32 milliseconds Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:44 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: No code Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: ------------------------------ 45ms Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand rescan Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: Dec 21 15:25:44 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand rescan took 0 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: Dec 21 15:25:44 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: Dec 21 15:25:44 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:44 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: No code Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:44 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: No code Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: ------------------------------ 24ms Dec 21 15:25:44 volumio volumio[1088]: info: ------------------------------ 24ms Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: Dec 21 15:25:44 volumio volumio[1088]: ---------------------------- MPD announces state update: update Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::getState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 24 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 24 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:44 volumio volumio[1088]: info: sendMpdCommand status took 0 milliseconds Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: Command Router : Notfying DB Updatefalse Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 21 15:25:44 volumio volumio[1088]: verbose: ControllerMpd::parseState Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:44 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: No code Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: ControllerMpd::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 21 15:25:44 volumio volumio[1088]: verbose: CURRENT POSITION 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus stop Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: No code Dec 21 15:25:44 volumio volumio[1088]: info: CoreStateMachine::pushState Dec 21 15:25:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 21 15:25:44 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Dec 21 15:25:44 volumio volumio[1088]: info: ------------------------------ 48ms Dec 21 15:25:44 volumio volumio[1088]: info: ------------------------------ 24ms Dec 21 15:25:45 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Dec 21 15:25:45 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Dec 21 15:25:48 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 21 15:25:50 volumio volumio[1088]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 15:25:50 volumio volumio[1088]: Error: dns service error: unknown Dec 21 15:25:50 volumio volumio[1088]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Dec 21 15:25:50 volumio volumio[1088]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 21 15:25:50 volumio volumio[1088]: errorCode: -65537 Dec 21 15:25:50 volumio volumio[1088]: } Dec 21 15:25:50 volumio volumio[1088]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 15:25:50 volumio sudo[4451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-21 15:24 Dec 21 15:25:50 volumio sudo[4451]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="5d50cd80bebce60dddccd7f5e813d656e28ca4cd" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="7840f283c310c38bfe939a4c485727a6ff6a0ce1" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 21 Oct 2025 04:19:36 PM CEST" VOLUMIO_VERSION="3.866" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a531aa6bc68498be155aace11c795690"