-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Fri 2025-05-30 15:53:56 CST. -- May 30 15:52:06 volumio wpa_supplicant[893]: wlan0: Failed to initiate sched scan May 30 15:52:14 volumio wpa_supplicant[893]: wlan0: Failed to initiate sched scan May 30 15:52:23 volumio wpa_supplicant[893]: wlan0: Failed to initiate sched scan May 30 15:52:25 volumio volumio[1183]: verbose: New Socket.io Connection to 192.168.6.237 from 192.168.6.142 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 15_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/15.2 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 4 May 30 15:52:25 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 30 15:52:25 volumio volumio[1183]: info: CoreCommandRouter::volumioGetVisibleSources May 30 15:52:25 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 30 15:52:25 volumio volumio[1183]: info: CoreCommandRouter::volumioGetState May 30 15:52:25 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 30 15:52:25 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 30 15:52:25 volumio volumio[1183]: info: Received Get System Info May 30 15:52:25 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 30 15:52:25 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 30 15:52:25 volumio volumio[1183]: info: Discovery: Getting this device information May 30 15:52:25 volumio volumio[1183]: info: CoreCommandRouter::volumioGetState May 30 15:52:25 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 30 15:52:25 volumio volumio[1183]: info: CoreCommandRouter::volumioGetState May 30 15:52:25 volumio volumio[1183]: info: Listing playlists May 30 15:52:25 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 30 15:52:25 volumio volumio[1183]: info: CoreStateMachine::getQueue May 30 15:52:25 volumio volumio[1183]: info: CorePlayQueue::getQueue May 30 15:52:25 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 30 15:52:31 volumio wpa_supplicant[893]: wlan0: Failed to initiate sched scan May 30 15:52:40 volumio wpa_supplicant[893]: wlan0: Failed to initiate sched scan May 30 15:52:43 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 30 15:52:43 volumio volumio[1183]: info: Preload queue cleared May 30 15:52:44 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 30 15:52:44 volumio volumio[1183]: info: Preload queue cleared May 30 15:52:48 volumio wpa_supplicant[893]: wlan0: Failed to initiate sched scan May 30 15:52:51 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 30 15:52:52 volumio volumio[1183]: info: Preload queue cleared May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i0 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i1 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i2 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i3 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i4 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i5 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i6 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i7 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i8 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i9 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i10 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i11 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i12 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i13 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i14 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i15 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i16 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i17 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i18 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i19 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i20 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i21 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i22 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i23 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i24 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i25 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i26 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i27 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i28 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i29 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i30 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i31 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i32 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i33 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i34 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i35 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i36 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i37 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i38 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i39 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i40 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i41 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i42 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i43 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i44 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i45 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i46 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i47 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i48 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i49 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i50 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i51 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i52 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i53 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i54 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i55 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i56 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i57 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i58 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i59 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i60 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i61 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i62 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i63 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i64 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i65 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i66 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i67 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i68 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i69 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i70 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i71 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i72 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i73 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i74 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i75 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i76 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i77 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i78 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i79 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i80 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i81 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i82 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i83 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i84 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i85 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i86 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i87 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i88 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i89 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i90 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i91 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i92 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i93 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i94 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i95 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i96 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i97 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i98 May 30 15:52:52 volumio volumio[1183]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i99 May 30 15:52:52 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i0 in service upnp_browser May 30 15:52:52 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i1 in service upnp_browser May 30 15:52:52 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i2 in service upnp_browser May 30 15:52:52 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i3 in service upnp_browser May 30 15:52:52 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i4 in service upnp_browser May 30 15:52:52 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i5 in service upnp_browser May 30 15:52:52 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i6 in service upnp_browser May 30 15:52:52 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i7 in service upnp_browser May 30 15:52:52 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i8 in service upnp_browser May 30 15:52:52 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i9 in service upnp_browser May 30 15:52:52 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i10 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i11 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i12 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i13 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i14 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i15 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i16 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i17 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i18 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i19 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i20 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i21 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i22 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i23 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i24 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i25 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i26 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i27 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i28 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i29 in service upnp_browser May 30 15:52:53 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i30 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i31 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i32 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i33 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i34 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i35 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i36 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i37 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i38 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i39 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i40 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i41 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i42 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i43 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i44 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i45 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i46 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i47 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i48 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i49 in service upnp_browser May 30 15:52:54 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i50 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i51 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i52 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i53 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i54 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i55 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i56 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i57 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i58 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i59 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i60 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i61 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i62 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i63 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i64 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i65 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i66 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i67 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i68 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i69 in service upnp_browser May 30 15:52:55 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i70 in service upnp_browser May 30 15:52:56 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i71 in service upnp_browser May 30 15:52:56 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i72 in service upnp_browser May 30 15:52:56 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i73 in service upnp_browser May 30 15:52:56 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i74 in service upnp_browser May 30 15:52:56 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i75 in service upnp_browser May 30 15:52:56 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i76 in service upnp_browser May 30 15:52:56 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i77 in service upnp_browser May 30 15:52:56 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i78 in service upnp_browser May 30 15:52:56 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i79 in service upnp_browser May 30 15:52:56 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i80 in service upnp_browser May 30 15:52:56 volumio volumio[1183]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items$*i81 in service upnp_browser May 30 15:52:56 volumio volumio[1183]: info: Preload queue cleared May 30 15:52:56 volumio volumio[1183]: info: CoreCommandRouter::volumioReplaceandPlayItems May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::ClearQueue May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::stop May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::stPlaybackTimer May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::updateTrackBlock May 30 15:52:56 volumio volumio[1183]: info: CorePlayQueue::getTrackBlock May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:56 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:56 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:52:56 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::serviceStop May 30 15:52:56 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:56 volumio volumio[1183]: info: CoreCommandRouter::serviceStop May 30 15:52:56 volumio volumio[1183]: info: [1748591576555] ControllerUPNPBrowser::stop May 30 15:52:56 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand stop May 30 15:52:56 volumio volumio[1183]: info: CorePlayQueue::clearPlayQueue May 30 15:52:56 volumio volumio[1183]: info: CorePlayQueue::saveQueue May 30 15:52:56 volumio volumio[1183]: info: CoreCommandRouter::volumioPushQueue May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::addQueueItems May 30 15:52:56 volumio volumio[1183]: info: CorePlayQueue::addQueueItems May 30 15:52:56 volumio volumio[1183]: info: Preload queue cleared May 30 15:52:56 volumio volumio[1183]: info: Adding Item to queue: upnp/folder/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items May 30 15:52:56 volumio volumio[1183]: info: Exploding uri upnp/folder/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$items in service upnp_browser May 30 15:52:56 volumio volumio[1183]: info: sendMpdCommand stop took 31 milliseconds May 30 15:52:56 volumio volumio[1183]: info: May 30 15:52:56 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:56 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:56 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:56 volumio volumio[1183]: info: May 30 15:52:56 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:56 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:56 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:56 volumio volumio[1183]: info: May 30 15:52:56 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:56 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:56 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:56 volumio volumio[1183]: info: sendMpdCommand status took 2 milliseconds May 30 15:52:56 volumio volumio[1183]: info: sendMpdCommand status took 2 milliseconds May 30 15:52:56 volumio volumio[1183]: info: sendMpdCommand status took 1 milliseconds May 30 15:52:56 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:56 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:56 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:56 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:56 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:56 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:56 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 15:52:56 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 1 milliseconds May 30 15:52:56 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 1 milliseconds May 30 15:52:56 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:56 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:56 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:56 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:56 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:56 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:56 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:52:56 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:56 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:56 volumio volumio[1183]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/*5bAlbum*5d*20*e9*99*88*e4*bd*b3*20*20-*20*e5*8e*bb*e5*b9*b4*e4*bb*8a*e6*97*a51*c2*b7*e9*97*ae*e6*83*85*201*e2*88*b61*e9*bb*84*e9*87*91*e6*af*8d*e7*9b*98*e7*9b*b4*e5*88*bb*e9*99*90*e9*87*8f*e7*bc*96*e5*8f*b7*20*ef*bc*88WAV+CUE*ef*bc*89*20*5b2021*5d*5b16bit*2044.1kHz*5d/CDImage.wav","trackType":"wav"} May 30 15:52:56 volumio volumio[1183]: verbose: CURRENT POSITION 0 May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:56 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:56 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:56 volumio volumio[1183]: info: No code May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:56 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:56 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:56 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:56 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:56 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:56 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:56 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:56 volumio volumio[1183]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/*5bAlbum*5d*20*e9*99*88*e4*bd*b3*20*20-*20*e5*8e*bb*e5*b9*b4*e4*bb*8a*e6*97*a51*c2*b7*e9*97*ae*e6*83*85*201*e2*88*b61*e9*bb*84*e9*87*91*e6*af*8d*e7*9b*98*e7*9b*b4*e5*88*bb*e9*99*90*e9*87*8f*e7*bc*96*e5*8f*b7*20*ef*bc*88WAV+CUE*ef*bc*89*20*5b2021*5d*5b16bit*2044.1kHz*5d/CDImage.wav","trackType":"wav"} May 30 15:52:56 volumio volumio[1183]: verbose: CURRENT POSITION 0 May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:56 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:56 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:56 volumio volumio[1183]: info: No code May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:56 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:56 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:56 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:56 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:56 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:56 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:56 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:56 volumio volumio[1183]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/*5bAlbum*5d*20*e9*99*88*e4*bd*b3*20*20-*20*e5*8e*bb*e5*b9*b4*e4*bb*8a*e6*97*a51*c2*b7*e9*97*ae*e6*83*85*201*e2*88*b61*e9*bb*84*e9*87*91*e6*af*8d*e7*9b*98*e7*9b*b4*e5*88*bb*e9*99*90*e9*87*8f*e7*bc*96*e5*8f*b7*20*ef*bc*88WAV+CUE*ef*bc*89*20*5b2021*5d*5b16bit*2044.1kHz*5d/CDImage.wav","trackType":"wav"} May 30 15:52:56 volumio volumio[1183]: verbose: CURRENT POSITION 0 May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:56 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:56 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:56 volumio volumio[1183]: info: No code May 30 15:52:56 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:56 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:56 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:56 volumio volumio[1183]: info: ------------------------------ 42ms May 30 15:52:56 volumio volumio[1183]: info: ------------------------------ 42ms May 30 15:52:56 volumio volumio[1183]: info: ------------------------------ 42ms May 30 15:52:56 volumio wpa_supplicant[893]: wlan0: Failed to initiate sched scan May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioPushQueue May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::saveQueue May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::updateTrackBlock May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::getTrackBlock May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioPlay May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::play index 0 May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::stop May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::play index undefined May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::startPlaybackTimer May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:57 volumio volumio[1183]: info: [1748591577197] ControllerUPNPBrowser::clearAddPlayTrack May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand stop May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand stop took 11 milliseconds May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand clear May 30 15:52:57 volumio volumio[1183]: info: May 30 15:52:57 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:52:57 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand clear took 116 milliseconds May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.6.2:9790/minimserver/*/Music/FLAC/*e7*88*b5*e5*a3*abJazz/Janet*20Seidel*20*e7*8f*8d*e5*a8*9c*e8*b3*bd*e5*be*b7-2009.04-Best*20of/02*20*c2*80comes*20Love*20*5b*e6*84*9b*e6*83*85*e4*be*86*e4*ba*86*5d.flac" May 30 15:52:57 volumio volumio[1183]: info: May 30 15:52:57 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:52:57 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:52:57 volumio volumio[1183]: info: May 30 15:52:57 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:52:57 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:52:57 volumio volumio[1183]: error: updateQueue error: null May 30 15:52:57 volumio volumio[1183]: info: ------------------------------ 5ms May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.6.2:9790/minimserver/*/Music/FLAC/*e7*88*b5*e5*a3*abJazz/Janet*20Seidel*20*e7*8f*8d*e5*a8*9c*e8*b3*bd*e5*be*b7-2009.04-Best*20of/02*20*c2*80comes*20Love*20*5b*e6*84*9b*e6*83*85*e4*be*86*e4*ba*86*5d.flac" May 30 15:52:57 volumio volumio[1183]: error: updateQueue error: null May 30 15:52:57 volumio volumio[1183]: error: updateQueue error: null May 30 15:52:57 volumio volumio[1183]: info: May 30 15:52:57 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:52:57 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:52:57 volumio volumio[1183]: info: ------------------------------ 6ms May 30 15:52:57 volumio volumio[1183]: info: ------------------------------ 6ms May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand add "http://192.168.6.2:9790/minimserver/*/Music/FLAC/*e7*88*b5*e5*a3*abJazz/Janet*20Seidel*20*e7*8f*8d*e5*a8*9c*e8*b3*bd*e5*be*b7-2009.04-Best*20of/02*20*c2*80comes*20Love*20*5b*e6*84*9b*e6*83*85*e4*be*86*e4*ba*86*5d.flac" took 2 milliseconds May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand play May 30 15:52:57 volumio volumio[1183]: info: May 30 15:52:57 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:52:57 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:52:57 volumio volumio[1183]: info: May 30 15:52:57 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:52:57 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:52:57 volumio volumio[1183]: info: ------------------------------ 4ms May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand play took 3 milliseconds May 30 15:52:57 volumio volumio[1183]: info: ------------------------------ 2ms May 30 15:52:57 volumio volumio[1183]: info: ------------------------------ 2ms May 30 15:52:57 volumio volumio[1183]: info: May 30 15:52:57 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:57 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:57 volumio volumio[1183]: info: May 30 15:52:57 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:57 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand status took 2 milliseconds May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:57 volumio volumio[1183]: info: May 30 15:52:57 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:57 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:57 volumio volumio[1183]: info: May 30 15:52:57 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:57 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand status took 4 milliseconds May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 3 milliseconds May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand status took 2 milliseconds May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand status took 1 milliseconds May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:57 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:57 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":300,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02*20*c2*80comes*20Love*20*5b*e6*84*9b*e6*83*85*e4*be*86*e4*ba*86*5d.flac","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/FLAC/*e7*88*b5*e5*a3*abJazz/Janet*20Seidel*20*e7*8f*8d*e5*a8*9c*e8*b3*bd*e5*be*b7-2009.04-Best*20of/02*20*c2*80comes*20Love*20*5b*e6*84*9b*e6*83*85*e4*be*86*e4*ba*86*5d.flac","trackType":"flac"} May 30 15:52:57 volumio volumio[1183]: verbose: CURRENT POSITION 0 May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 30 15:52:57 volumio volumio[1183]: info: ------------------------------ 9ms May 30 15:52:57 volumio volumio[1183]: info: May 30 15:52:57 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:57 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:57 volumio volumio[1183]: info: May 30 15:52:57 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:57 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 4 milliseconds May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 4 milliseconds May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 3 milliseconds May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand status took 2 milliseconds May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand status took 2 milliseconds May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:57 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:57 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":300,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02*20*c2*80comes*20Love*20*5b*e6*84*9b*e6*83*85*e4*be*86*e4*ba*86*5d.flac","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/FLAC/*e7*88*b5*e5*a3*abJazz/Janet*20Seidel*20*e7*8f*8d*e5*a8*9c*e8*b3*bd*e5*be*b7-2009.04-Best*20of/02*20*c2*80comes*20Love*20*5b*e6*84*9b*e6*83*85*e4*be*86*e4*ba*86*5d.flac","trackType":"flac"} May 30 15:52:57 volumio volumio[1183]: verbose: CURRENT POSITION 0 May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:52:57 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:57 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:57 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":300,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02*20*c2*80comes*20Love*20*5b*e6*84*9b*e6*83*85*e4*be*86*e4*ba*86*5d.flac","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/FLAC/*e7*88*b5*e5*a3*abJazz/Janet*20Seidel*20*e7*8f*8d*e5*a8*9c*e8*b3*bd*e5*be*b7-2009.04-Best*20of/02*20*c2*80comes*20Love*20*5b*e6*84*9b*e6*83*85*e4*be*86*e4*ba*86*5d.flac","trackType":"flac"} May 30 15:52:57 volumio volumio[1183]: verbose: CURRENT POSITION 0 May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:52:57 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:57 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:57 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":300,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02*20*c2*80comes*20Love*20*5b*e6*84*9b*e6*83*85*e4*be*86*e4*ba*86*5d.flac","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/FLAC/*e7*88*b5*e5*a3*abJazz/Janet*20Seidel*20*e7*8f*8d*e5*a8*9c*e8*b3*bd*e5*be*b7-2009.04-Best*20of/02*20*c2*80comes*20Love*20*5b*e6*84*9b*e6*83*85*e4*be*86*e4*ba*86*5d.flac","trackType":"flac"} May 30 15:52:57 volumio volumio[1183]: verbose: CURRENT POSITION 0 May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:52:57 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:57 volumio volumio[1183]: info: ------------------------------ 40ms May 30 15:52:57 volumio volumio[1183]: info: ------------------------------ 38ms May 30 15:52:57 volumio volumio[1183]: info: ------------------------------ 39ms May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 31 milliseconds May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 31 milliseconds May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:57 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:57 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":300,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02*20*c2*80comes*20Love*20*5b*e6*84*9b*e6*83*85*e4*be*86*e4*ba*86*5d.flac","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/FLAC/*e7*88*b5*e5*a3*abJazz/Janet*20Seidel*20*e7*8f*8d*e5*a8*9c*e8*b3*bd*e5*be*b7-2009.04-Best*20of/02*20*c2*80comes*20Love*20*5b*e6*84*9b*e6*83*85*e4*be*86*e4*ba*86*5d.flac","trackType":"flac"} May 30 15:52:57 volumio volumio[1183]: verbose: CURRENT POSITION 0 May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:52:57 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:57 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:57 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":300,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02*20*c2*80comes*20Love*20*5b*e6*84*9b*e6*83*85*e4*be*86*e4*ba*86*5d.flac","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/FLAC/*e7*88*b5*e5*a3*abJazz/Janet*20Seidel*20*e7*8f*8d*e5*a8*9c*e8*b3*bd*e5*be*b7-2009.04-Best*20of/02*20*c2*80comes*20Love*20*5b*e6*84*9b*e6*83*85*e4*be*86*e4*ba*86*5d.flac","trackType":"flac"} May 30 15:52:57 volumio volumio[1183]: verbose: CURRENT POSITION 0 May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:52:57 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:57 volumio volumio[1183]: info: ------------------------------ 57ms May 30 15:52:57 volumio volumio[1183]: info: ------------------------------ 57ms May 30 15:52:57 volumio volumio[1183]: info: May 30 15:52:57 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:52:57 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:52:57 volumio volumio[1183]: info: May 30 15:52:57 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:57 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:57 volumio volumio[1183]: info: May 30 15:52:57 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:52:57 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:52:57 volumio volumio[1183]: info: May 30 15:52:57 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:57 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:57 volumio volumio[1183]: info: May 30 15:52:57 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:52:57 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:52:57 volumio volumio[1183]: info: May 30 15:52:57 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:57 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:57 volumio volumio[1183]: info: ------------------------------ 9ms May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand status took 9 milliseconds May 30 15:52:57 volumio volumio[1183]: info: ------------------------------ 9ms May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand status took 7 milliseconds May 30 15:52:57 volumio volumio[1183]: info: ------------------------------ 8ms May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand status took 6 milliseconds May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 3 milliseconds May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 3 milliseconds May 30 15:52:57 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:57 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:57 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:57 volumio volumio[1183]: [570B blob data] May 30 15:52:57 volumio volumio[1183]: verbose: CURRENT POSITION 0 May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:52:57 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:57 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:57 volumio volumio[1183]: [570B blob data] May 30 15:52:57 volumio volumio[1183]: verbose: CURRENT POSITION 0 May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:52:57 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:57 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:57 volumio volumio[1183]: [570B blob data] May 30 15:52:57 volumio volumio[1183]: verbose: CURRENT POSITION 0 May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:52:57 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:57 volumio volumio[1183]: info: ------------------------------ 53ms May 30 15:52:57 volumio volumio[1183]: info: ------------------------------ 51ms May 30 15:52:57 volumio volumio[1183]: info: ------------------------------ 50ms May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::getQueue May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::getQueue May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::getQueue May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::getQueue May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::getQueue May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::getQueue May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::getQueue May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::getQueue May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::getQueue May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::getQueue May 30 15:52:57 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 30 15:52:57 volumio volumio[1183]: info: CoreStateMachine::getQueue May 30 15:52:57 volumio volumio[1183]: info: CorePlayQueue::getQueue May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioNext May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::next May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::next May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand next May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand next took 33 milliseconds May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand status took 2 milliseconds May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand status took 1 milliseconds May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand status took 1 milliseconds May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 0 May 30 15:52:59 volumio volumio[1183]: 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} May 30 15:52:59 volumio volumio[1183]: verbose: CURRENT POSITION 0 May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::play index undefined May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: 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} May 30 15:52:59 volumio volumio[1183]: verbose: CURRENT POSITION 1 May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: No code May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: 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} May 30 15:52:59 volumio volumio[1183]: verbose: CURRENT POSITION 1 May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: No code May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::startPlaybackTimer May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: info: [1748591579601] ControllerUPNPBrowser::clearAddPlayTrack May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand stop May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 36ms May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 35ms May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 35ms May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand stop took 7 milliseconds May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand clear May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::getQueue May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getQueue May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:52:59 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand clear took 11 milliseconds May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.6.2:9790/minimserver/*/Music/2016.7/*e5*91*a8*e6*97*8b*20DENON*e7*89*88/*c2*ad*c2*ae*c2*ab*c3*91*c2*a4*c2*a7*c2*acK.flac" May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:52:59 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:52:59 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:52:59 volumio volumio[1183]: error: updateQueue error: null May 30 15:52:59 volumio volumio[1183]: error: updateQueue error: null May 30 15:52:59 volumio volumio[1183]: error: updateQueue error: null May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 18ms May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.6.2:9790/minimserver/*/Music/2016.7/*e5*91*a8*e6*97*8b*20DENON*e7*89*88/*c2*ad*c2*ae*c2*ab*c3*91*c2*a4*c2*a7*c2*acK.flac" May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 17ms May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 16ms May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:52:59 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand add "http://192.168.6.2:9790/minimserver/*/Music/2016.7/*e5*91*a8*e6*97*8b*20DENON*e7*89*88/*c2*ad*c2*ae*c2*ab*c3*91*c2*a4*c2*a7*c2*acK.flac" took 1 milliseconds May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand play May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:52:59 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:52:59 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 3ms May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand play took 3 milliseconds May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 3ms May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 4ms May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:52:59 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand status took 4 milliseconds May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:52:59 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand status took 8 milliseconds May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand status took 6 milliseconds May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand status took 6 milliseconds May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 7ms May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand status took 5 milliseconds May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 5 milliseconds May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 5ms May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand status took 3 milliseconds May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":150,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"587 Kbps","isStreaming":false,"title":"­®«Ñ¤§¬K","artist":"©Pæ¢","album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/2016.7/*e5*91*a8*e6*97*8b*20DENON*e7*89*88/*c2*ad*c2*ae*c2*ab*c3*91*c2*a4*c2*a7*c2*acK.flac","trackType":"flac"} May 30 15:52:59 volumio volumio[1183]: verbose: CURRENT POSITION 1 May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 16ms May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:52:59 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:52:59 volumio volumio[1183]: info: May 30 15:52:59 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 6 milliseconds May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 6 milliseconds May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 6 milliseconds May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 5 milliseconds May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 4 milliseconds May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 3ms May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand status took 3 milliseconds May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":150,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"593 Kbps","isStreaming":false,"title":"­®«Ñ¤§¬K","artist":"©Pæ¢","album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/2016.7/*e5*91*a8*e6*97*8b*20DENON*e7*89*88/*c2*ad*c2*ae*c2*ab*c3*91*c2*a4*c2*a7*c2*acK.flac","trackType":"flac"} May 30 15:52:59 volumio volumio[1183]: verbose: CURRENT POSITION 1 May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:52:59 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":150,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"575 Kbps","isStreaming":false,"title":"­®«Ñ¤§¬K","artist":"©Pæ¢","album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/2016.7/*e5*91*a8*e6*97*8b*20DENON*e7*89*88/*c2*ad*c2*ae*c2*ab*c3*91*c2*a4*c2*a7*c2*acK.flac","trackType":"flac"} May 30 15:52:59 volumio volumio[1183]: verbose: CURRENT POSITION 1 May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:52:59 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":150,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"575 Kbps","isStreaming":false,"title":"­®«Ñ¤§¬K","artist":"©Pæ¢","album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/2016.7/*e5*91*a8*e6*97*8b*20DENON*e7*89*88/*c2*ad*c2*ae*c2*ab*c3*91*c2*a4*c2*a7*c2*acK.flac","trackType":"flac"} May 30 15:52:59 volumio volumio[1183]: verbose: CURRENT POSITION 1 May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:52:59 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":150,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"575 Kbps","isStreaming":false,"title":"­®«Ñ¤§¬K","artist":"©Pæ¢","album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/2016.7/*e5*91*a8*e6*97*8b*20DENON*e7*89*88/*c2*ad*c2*ae*c2*ab*c3*91*c2*a4*c2*a7*c2*acK.flac","trackType":"flac"} May 30 15:52:59 volumio volumio[1183]: verbose: CURRENT POSITION 1 May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:52:59 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":150,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"575 Kbps","isStreaming":false,"title":"­®«Ñ¤§¬K","artist":"©Pæ¢","album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/2016.7/*e5*91*a8*e6*97*8b*20DENON*e7*89*88/*c2*ad*c2*ae*c2*ab*c3*91*c2*a4*c2*a7*c2*acK.flac","trackType":"flac"} May 30 15:52:59 volumio volumio[1183]: verbose: CURRENT POSITION 1 May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:52:59 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 69ms May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 69ms May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 69ms May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 68ms May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 66ms May 30 15:52:59 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 54 milliseconds May 30 15:52:59 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:52:59 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:52:59 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:52:59 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":150,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"575 Kbps","isStreaming":false,"title":"­®«Ñ¤§¬K","artist":"©Pæ¢","album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/2016.7/*e5*91*a8*e6*97*8b*20DENON*e7*89*88/*c2*ad*c2*ae*c2*ab*c3*91*c2*a4*c2*a7*c2*acK.flac","trackType":"flac"} May 30 15:52:59 volumio volumio[1183]: verbose: CURRENT POSITION 1 May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:52:59 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:52:59 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:52:59 volumio volumio[1183]: info: ------------------------------ 70ms May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioNext May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::next May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::next May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand next May 30 15:53:01 volumio volumio[1183]: info: May 30 15:53:01 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand next took 28 milliseconds May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:01 volumio volumio[1183]: info: May 30 15:53:01 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:01 volumio volumio[1183]: info: May 30 15:53:01 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand status took 2 milliseconds May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand status took 2 milliseconds May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand status took 1 milliseconds May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 1 May 30 15:53:01 volumio volumio[1183]: 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} May 30 15:53:01 volumio volumio[1183]: verbose: CURRENT POSITION 1 May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::play index undefined May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:01 volumio volumio[1183]: 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} May 30 15:53:01 volumio volumio[1183]: verbose: CURRENT POSITION 2 May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: No code May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:01 volumio volumio[1183]: 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} May 30 15:53:01 volumio volumio[1183]: verbose: CURRENT POSITION 2 May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: No code May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::startPlaybackTimer May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:01 volumio volumio[1183]: info: [1748591581394] ControllerUPNPBrowser::clearAddPlayTrack May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand stop May 30 15:53:01 volumio volumio[1183]: info: ------------------------------ 38ms May 30 15:53:01 volumio volumio[1183]: info: ------------------------------ 38ms May 30 15:53:01 volumio volumio[1183]: info: ------------------------------ 37ms May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand stop took 9 milliseconds May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand clear May 30 15:53:01 volumio volumio[1183]: info: May 30 15:53:01 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:01 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand clear took 5 milliseconds May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.6.2:9790/minimserver/*/Music/WAV/*e9*9f*b3*e5*93*8d*e5*b1*95/*e5*8f*b0*e6*b9*be*e7*ac*ac16*e5*b1*8a*e5*9b*bd*e9*99*85Hi-End*e9*9f*b3*e5*93*8d*e5*b1*95*202007/11.*20*c2*ad*c3*ba*c2*ac*c3*a2.wav" May 30 15:53:01 volumio volumio[1183]: info: May 30 15:53:01 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:01 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:01 volumio volumio[1183]: info: May 30 15:53:01 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:01 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:01 volumio volumio[1183]: error: updateQueue error: null May 30 15:53:01 volumio volumio[1183]: info: ------------------------------ 2ms May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.6.2:9790/minimserver/*/Music/WAV/*e9*9f*b3*e5*93*8d*e5*b1*95/*e5*8f*b0*e6*b9*be*e7*ac*ac16*e5*b1*8a*e5*9b*bd*e9*99*85Hi-End*e9*9f*b3*e5*93*8d*e5*b1*95*202007/11.*20*c2*ad*c3*ba*c2*ac*c3*a2.wav" May 30 15:53:01 volumio volumio[1183]: error: updateQueue error: null May 30 15:53:01 volumio volumio[1183]: error: updateQueue error: null May 30 15:53:01 volumio volumio[1183]: info: ------------------------------ 34ms May 30 15:53:01 volumio volumio[1183]: info: ------------------------------ 33ms May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand add "http://192.168.6.2:9790/minimserver/*/Music/WAV/*e9*9f*b3*e5*93*8d*e5*b1*95/*e5*8f*b0*e6*b9*be*e7*ac*ac16*e5*b1*8a*e5*9b*bd*e9*99*85Hi-End*e9*9f*b3*e5*93*8d*e5*b1*95*202007/11.*20*c2*ad*c3*ba*c2*ac*c3*a2.wav" took 1 milliseconds May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand play May 30 15:53:01 volumio volumio[1183]: info: May 30 15:53:01 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:01 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:01 volumio volumio[1183]: info: May 30 15:53:01 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:01 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:01 volumio volumio[1183]: info: May 30 15:53:01 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:01 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand play took 3 milliseconds May 30 15:53:01 volumio volumio[1183]: info: ------------------------------ 3ms May 30 15:53:01 volumio volumio[1183]: info: ------------------------------ 2ms May 30 15:53:01 volumio volumio[1183]: info: ------------------------------ 3ms May 30 15:53:01 volumio volumio[1183]: info: May 30 15:53:01 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:01 volumio volumio[1183]: info: May 30 15:53:01 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:01 volumio volumio[1183]: info: May 30 15:53:01 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand status took 3 milliseconds May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:53:01 volumio volumio[1183]: info: May 30 15:53:01 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand status took 4 milliseconds May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand status took 4 milliseconds May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 2 milliseconds May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:01 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11.*20*c2*ad*c3*ba*c2*ac*c3*a2.wav","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/WAV/*e9*9f*b3*e5*93*8d*e5*b1*95/*e5*8f*b0*e6*b9*be*e7*ac*ac16*e5*b1*8a*e5*9b*bd*e9*99*85Hi-End*e9*9f*b3*e5*93*8d*e5*b1*95*202007/11.*20*c2*ad*c3*ba*c2*ac*c3*a2.wav","trackType":"wav"} May 30 15:53:01 volumio volumio[1183]: verbose: CURRENT POSITION 2 May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 30 15:53:01 volumio volumio[1183]: info: ------------------------------ 10ms May 30 15:53:01 volumio volumio[1183]: info: May 30 15:53:01 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:01 volumio volumio[1183]: info: May 30 15:53:01 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand status took 7 milliseconds May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 5 milliseconds May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 6 milliseconds May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:01 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11.*20*c2*ad*c3*ba*c2*ac*c3*a2.wav","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/WAV/*e9*9f*b3*e5*93*8d*e5*b1*95/*e5*8f*b0*e6*b9*be*e7*ac*ac16*e5*b1*8a*e5*9b*bd*e9*99*85Hi-End*e9*9f*b3*e5*93*8d*e5*b1*95*202007/11.*20*c2*ad*c3*ba*c2*ac*c3*a2.wav","trackType":"wav"} May 30 15:53:01 volumio volumio[1183]: verbose: CURRENT POSITION 2 May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:53:01 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:01 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11.*20*c2*ad*c3*ba*c2*ac*c3*a2.wav","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/WAV/*e9*9f*b3*e5*93*8d*e5*b1*95/*e5*8f*b0*e6*b9*be*e7*ac*ac16*e5*b1*8a*e5*9b*bd*e9*99*85Hi-End*e9*9f*b3*e5*93*8d*e5*b1*95*202007/11.*20*c2*ad*c3*ba*c2*ac*c3*a2.wav","trackType":"wav"} May 30 15:53:01 volumio volumio[1183]: verbose: CURRENT POSITION 2 May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:53:01 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: ------------------------------ 45ms May 30 15:53:01 volumio volumio[1183]: info: ------------------------------ 44ms May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand status took 42 milliseconds May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand status took 41 milliseconds May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 39 milliseconds May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:01 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11.*20*c2*ad*c3*ba*c2*ac*c3*a2.wav","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/WAV/*e9*9f*b3*e5*93*8d*e5*b1*95/*e5*8f*b0*e6*b9*be*e7*ac*ac16*e5*b1*8a*e5*9b*bd*e9*99*85Hi-End*e9*9f*b3*e5*93*8d*e5*b1*95*202007/11.*20*c2*ad*c3*ba*c2*ac*c3*a2.wav","trackType":"wav"} May 30 15:53:01 volumio volumio[1183]: verbose: CURRENT POSITION 2 May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:53:01 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: ------------------------------ 62ms May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 17 milliseconds May 30 15:53:01 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 17 milliseconds May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:53:01 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:01 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11.*20*c2*ad*c3*ba*c2*ac*c3*a2.wav","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/WAV/*e9*9f*b3*e5*93*8d*e5*b1*95/*e5*8f*b0*e6*b9*be*e7*ac*ac16*e5*b1*8a*e5*9b*bd*e9*99*85Hi-End*e9*9f*b3*e5*93*8d*e5*b1*95*202007/11.*20*c2*ad*c3*ba*c2*ac*c3*a2.wav","trackType":"wav"} May 30 15:53:01 volumio volumio[1183]: verbose: CURRENT POSITION 2 May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:53:01 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:01 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:01 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"11.*20*c2*ad*c3*ba*c2*ac*c3*a2.wav","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/WAV/*e9*9f*b3*e5*93*8d*e5*b1*95/*e5*8f*b0*e6*b9*be*e7*ac*ac16*e5*b1*8a*e5*9b*bd*e9*99*85Hi-End*e9*9f*b3*e5*93*8d*e5*b1*95*202007/11.*20*c2*ad*c3*ba*c2*ac*c3*a2.wav","trackType":"wav"} May 30 15:53:01 volumio volumio[1183]: verbose: CURRENT POSITION 2 May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:53:01 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:01 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:01 volumio volumio[1183]: info: ------------------------------ 77ms May 30 15:53:01 volumio volumio[1183]: info: ------------------------------ 77ms May 30 15:53:05 volumio wpa_supplicant[893]: wlan0: Failed to initiate sched scan May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioNext May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::next May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::next May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand next May 30 15:53:07 volumio volumio[1183]: info: May 30 15:53:07 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand next took 29 milliseconds May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:07 volumio volumio[1183]: info: May 30 15:53:07 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:07 volumio volumio[1183]: info: May 30 15:53:07 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand status took 2 milliseconds May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand status took 2 milliseconds May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand status took 1 milliseconds May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 2 May 30 15:53:07 volumio volumio[1183]: 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} May 30 15:53:07 volumio volumio[1183]: verbose: CURRENT POSITION 2 May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::play index undefined May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:07 volumio volumio[1183]: 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} May 30 15:53:07 volumio volumio[1183]: verbose: CURRENT POSITION 3 May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: No code May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:07 volumio volumio[1183]: 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} May 30 15:53:07 volumio volumio[1183]: verbose: CURRENT POSITION 3 May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: No code May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::startPlaybackTimer May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:07 volumio volumio[1183]: info: [1748591587702] ControllerUPNPBrowser::clearAddPlayTrack May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand stop May 30 15:53:07 volumio volumio[1183]: info: ------------------------------ 37ms May 30 15:53:07 volumio volumio[1183]: info: ------------------------------ 36ms May 30 15:53:07 volumio volumio[1183]: info: ------------------------------ 36ms May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand stop took 8 milliseconds May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand clear May 30 15:53:07 volumio volumio[1183]: info: May 30 15:53:07 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:07 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand clear took 7 milliseconds May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.6.2:9790/minimserver/*/Music/CD*e6*8a*93*e8*bd*a8/*e9*9f*b3*e5*93*8d*e5*b1*95/2014*e5*b9*bf*e5*b7*9e*e5*9b*bd*e9*99*85*e9*ab*98*e7*ba*a7*e9*9f*b3*e5*93*8d*e5*b1*95HI-FI*e7*8f*8d*e8*97*8f*e7*89*88CD/01*20*e7*be*a4*e6*98*9f*20-*20*27*e6*98*a5*e4*b9*8b*e7*a5*ad*27*e8*88*9e*e6*9b*b2.wav" May 30 15:53:07 volumio volumio[1183]: info: May 30 15:53:07 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:07 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:07 volumio volumio[1183]: info: May 30 15:53:07 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:07 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:07 volumio volumio[1183]: error: updateQueue error: null May 30 15:53:07 volumio volumio[1183]: info: ------------------------------ 4ms May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.6.2:9790/minimserver/*/Music/CD*e6*8a*93*e8*bd*a8/*e9*9f*b3*e5*93*8d*e5*b1*95/2014*e5*b9*bf*e5*b7*9e*e5*9b*bd*e9*99*85*e9*ab*98*e7*ba*a7*e9*9f*b3*e5*93*8d*e5*b1*95HI-FI*e7*8f*8d*e8*97*8f*e7*89*88CD/01*20*e7*be*a4*e6*98*9f*20-*20*27*e6*98*a5*e4*b9*8b*e7*a5*ad*27*e8*88*9e*e6*9b*b2.wav" May 30 15:53:07 volumio volumio[1183]: error: updateQueue error: null May 30 15:53:07 volumio volumio[1183]: error: updateQueue error: null May 30 15:53:07 volumio volumio[1183]: info: ------------------------------ 40ms May 30 15:53:07 volumio volumio[1183]: info: ------------------------------ 39ms May 30 15:53:07 volumio volumio[1183]: info: May 30 15:53:07 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:07 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand add "http://192.168.6.2:9790/minimserver/*/Music/CD*e6*8a*93*e8*bd*a8/*e9*9f*b3*e5*93*8d*e5*b1*95/2014*e5*b9*bf*e5*b7*9e*e5*9b*bd*e9*99*85*e9*ab*98*e7*ba*a7*e9*9f*b3*e5*93*8d*e5*b1*95HI-FI*e7*8f*8d*e8*97*8f*e7*89*88CD/01*20*e7*be*a4*e6*98*9f*20-*20*27*e6*98*a5*e4*b9*8b*e7*a5*ad*27*e8*88*9e*e6*9b*b2.wav" took 2 milliseconds May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand play May 30 15:53:07 volumio volumio[1183]: info: May 30 15:53:07 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:07 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:07 volumio volumio[1183]: info: May 30 15:53:07 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:07 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:07 volumio volumio[1183]: info: ------------------------------ 2ms May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand play took 3 milliseconds May 30 15:53:07 volumio volumio[1183]: info: ------------------------------ 2ms May 30 15:53:07 volumio volumio[1183]: info: ------------------------------ 3ms May 30 15:53:07 volumio volumio[1183]: info: May 30 15:53:07 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:07 volumio volumio[1183]: info: May 30 15:53:07 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:07 volumio volumio[1183]: info: May 30 15:53:07 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:07 volumio volumio[1183]: info: May 30 15:53:07 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand status took 3 milliseconds May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand status took 9 milliseconds May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand status took 9 milliseconds May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:53:07 volumio volumio[1183]: info: May 30 15:53:07 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:07 volumio volumio[1183]: info: May 30 15:53:07 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand status took 11 milliseconds May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 12 milliseconds May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 5 milliseconds May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 5 milliseconds May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand status took 5 milliseconds May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:07 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01*20*e7*be*a4*e6*98*9f*20-*20*27*e6*98*a5*e4*b9*8b*e7*a5*ad*27*e8*88*9e*e6*9b*b2.wav","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/CD*e6*8a*93*e8*bd*a8/*e9*9f*b3*e5*93*8d*e5*b1*95/2014*e5*b9*bf*e5*b7*9e*e5*9b*bd*e9*99*85*e9*ab*98*e7*ba*a7*e9*9f*b3*e5*93*8d*e5*b1*95HI-FI*e7*8f*8d*e8*97*8f*e7*89*88CD/01*20*e7*be*a4*e6*98*9f*20-*20*27*e6*98*a5*e4*b9*8b*e7*a5*ad*27*e8*88*9e*e6*9b*b2.wav","trackType":"wav"} May 30 15:53:07 volumio volumio[1183]: verbose: CURRENT POSITION 3 May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:07 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01*20*e7*be*a4*e6*98*9f*20-*20*27*e6*98*a5*e4*b9*8b*e7*a5*ad*27*e8*88*9e*e6*9b*b2.wav","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/CD*e6*8a*93*e8*bd*a8/*e9*9f*b3*e5*93*8d*e5*b1*95/2014*e5*b9*bf*e5*b7*9e*e5*9b*bd*e9*99*85*e9*ab*98*e7*ba*a7*e9*9f*b3*e5*93*8d*e5*b1*95HI-FI*e7*8f*8d*e8*97*8f*e7*89*88CD/01*20*e7*be*a4*e6*98*9f*20-*20*27*e6*98*a5*e4*b9*8b*e7*a5*ad*27*e8*88*9e*e6*9b*b2.wav","trackType":"wav"} May 30 15:53:07 volumio volumio[1183]: verbose: CURRENT POSITION 3 May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:53:07 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:07 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01*20*e7*be*a4*e6*98*9f*20-*20*27*e6*98*a5*e4*b9*8b*e7*a5*ad*27*e8*88*9e*e6*9b*b2.wav","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/CD*e6*8a*93*e8*bd*a8/*e9*9f*b3*e5*93*8d*e5*b1*95/2014*e5*b9*bf*e5*b7*9e*e5*9b*bd*e9*99*85*e9*ab*98*e7*ba*a7*e9*9f*b3*e5*93*8d*e5*b1*95HI-FI*e7*8f*8d*e8*97*8f*e7*89*88CD/01*20*e7*be*a4*e6*98*9f*20-*20*27*e6*98*a5*e4*b9*8b*e7*a5*ad*27*e8*88*9e*e6*9b*b2.wav","trackType":"wav"} May 30 15:53:07 volumio volumio[1183]: verbose: CURRENT POSITION 3 May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:53:07 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: ------------------------------ 31ms May 30 15:53:07 volumio volumio[1183]: info: ------------------------------ 45ms May 30 15:53:07 volumio volumio[1183]: info: ------------------------------ 45ms May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand status took 33 milliseconds May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 31 milliseconds May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 30 milliseconds May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:07 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":823,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01*20*e7*be*a4*e6*98*9f*20-*20*27*e6*98*a5*e4*b9*8b*e7*a5*ad*27*e8*88*9e*e6*9b*b2.wav","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/CD*e6*8a*93*e8*bd*a8/*e9*9f*b3*e5*93*8d*e5*b1*95/2014*e5*b9*bf*e5*b7*9e*e5*9b*bd*e9*99*85*e9*ab*98*e7*ba*a7*e9*9f*b3*e5*93*8d*e5*b1*95HI-FI*e7*8f*8d*e8*97*8f*e7*89*88CD/01*20*e7*be*a4*e6*98*9f*20-*20*27*e6*98*a5*e4*b9*8b*e7*a5*ad*27*e8*88*9e*e6*9b*b2.wav","trackType":"wav"} May 30 15:53:07 volumio volumio[1183]: verbose: CURRENT POSITION 3 May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:53:07 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:07 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01*20*e7*be*a4*e6*98*9f*20-*20*27*e6*98*a5*e4*b9*8b*e7*a5*ad*27*e8*88*9e*e6*9b*b2.wav","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/CD*e6*8a*93*e8*bd*a8/*e9*9f*b3*e5*93*8d*e5*b1*95/2014*e5*b9*bf*e5*b7*9e*e5*9b*bd*e9*99*85*e9*ab*98*e7*ba*a7*e9*9f*b3*e5*93*8d*e5*b1*95HI-FI*e7*8f*8d*e8*97*8f*e7*89*88CD/01*20*e7*be*a4*e6*98*9f*20-*20*27*e6*98*a5*e4*b9*8b*e7*a5*ad*27*e8*88*9e*e6*9b*b2.wav","trackType":"wav"} May 30 15:53:07 volumio volumio[1183]: verbose: CURRENT POSITION 3 May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:53:07 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: ------------------------------ 67ms May 30 15:53:07 volumio volumio[1183]: info: ------------------------------ 58ms May 30 15:53:07 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 32 milliseconds May 30 15:53:07 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:53:07 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:07 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:07 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01*20*e7*be*a4*e6*98*9f*20-*20*27*e6*98*a5*e4*b9*8b*e7*a5*ad*27*e8*88*9e*e6*9b*b2.wav","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/CD*e6*8a*93*e8*bd*a8/*e9*9f*b3*e5*93*8d*e5*b1*95/2014*e5*b9*bf*e5*b7*9e*e5*9b*bd*e9*99*85*e9*ab*98*e7*ba*a7*e9*9f*b3*e5*93*8d*e5*b1*95HI-FI*e7*8f*8d*e8*97*8f*e7*89*88CD/01*20*e7*be*a4*e6*98*9f*20-*20*27*e6*98*a5*e4*b9*8b*e7*a5*ad*27*e8*88*9e*e6*9b*b2.wav","trackType":"wav"} May 30 15:53:07 volumio volumio[1183]: verbose: CURRENT POSITION 3 May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:53:07 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:07 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:07 volumio volumio[1183]: info: ------------------------------ 77ms May 30 15:53:13 volumio wpa_supplicant[893]: wlan0: Failed to initiate sched scan May 30 15:53:22 volumio wpa_supplicant[893]: wlan0: Failed to initiate sched scan May 30 15:53:30 volumio wpa_supplicant[893]: wlan0: Failed to initiate sched scan May 30 15:53:39 volumio wpa_supplicant[893]: wlan0: Failed to initiate sched scan May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioNext May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::next May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::next May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand next May 30 15:53:45 volumio volumio[1183]: info: May 30 15:53:45 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand next took 19 milliseconds May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:45 volumio volumio[1183]: info: May 30 15:53:45 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:45 volumio volumio[1183]: info: May 30 15:53:45 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand status took 3 milliseconds May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand status took 3 milliseconds May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand status took 2 milliseconds May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 3 May 30 15:53:45 volumio volumio[1183]: 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} May 30 15:53:45 volumio volumio[1183]: verbose: CURRENT POSITION 3 May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::play index undefined May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 4 May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 4 May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 4 May 30 15:53:45 volumio volumio[1183]: 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} May 30 15:53:45 volumio volumio[1183]: verbose: CURRENT POSITION 4 May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 4 May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: No code May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 4 May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 4 May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 4 May 30 15:53:45 volumio volumio[1183]: 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} May 30 15:53:45 volumio volumio[1183]: verbose: CURRENT POSITION 4 May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::syncState stateService stop May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 4 May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: No code May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 4 May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 4 May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::startPlaybackTimer May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 4 May 30 15:53:45 volumio volumio[1183]: info: [1748591625208] ControllerUPNPBrowser::clearAddPlayTrack May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand stop May 30 15:53:45 volumio volumio[1183]: info: ------------------------------ 43ms May 30 15:53:45 volumio volumio[1183]: info: ------------------------------ 42ms May 30 15:53:45 volumio volumio[1183]: info: ------------------------------ 42ms May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 4 May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand stop took 10 milliseconds May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand clear May 30 15:53:45 volumio volumio[1183]: info: May 30 15:53:45 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:45 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand clear took 6 milliseconds May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.6.2:9790/minimserver/*/Music/2015.07.06*e5*8c*97*e4*ba*ac/A*20DG/Herbert*20Von*20Karajan*20Spectacular/Karajan*20Spectacular/09*20Peter*20Ilyich*20Tchaikovsky*20-*20*271812*27*20Overture.flac" May 30 15:53:45 volumio volumio[1183]: info: May 30 15:53:45 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:45 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:45 volumio volumio[1183]: info: May 30 15:53:45 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:45 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:45 volumio volumio[1183]: error: updateQueue error: null May 30 15:53:45 volumio volumio[1183]: info: ------------------------------ 4ms May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.6.2:9790/minimserver/*/Music/2015.07.06*e5*8c*97*e4*ba*ac/A*20DG/Herbert*20Von*20Karajan*20Spectacular/Karajan*20Spectacular/09*20Peter*20Ilyich*20Tchaikovsky*20-*20*271812*27*20Overture.flac" May 30 15:53:45 volumio volumio[1183]: error: updateQueue error: null May 30 15:53:45 volumio volumio[1183]: error: updateQueue error: null May 30 15:53:45 volumio volumio[1183]: info: May 30 15:53:45 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:45 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:45 volumio volumio[1183]: info: ------------------------------ 9ms May 30 15:53:45 volumio volumio[1183]: info: ------------------------------ 9ms May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand add "http://192.168.6.2:9790/minimserver/*/Music/2015.07.06*e5*8c*97*e4*ba*ac/A*20DG/Herbert*20Von*20Karajan*20Spectacular/Karajan*20Spectacular/09*20Peter*20Ilyich*20Tchaikovsky*20-*20*271812*27*20Overture.flac" took 2 milliseconds May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand play May 30 15:53:45 volumio volumio[1183]: info: May 30 15:53:45 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:45 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:45 volumio volumio[1183]: info: May 30 15:53:45 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:45 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:45 volumio volumio[1183]: info: ------------------------------ 4ms May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand play took 3 milliseconds May 30 15:53:45 volumio volumio[1183]: info: ------------------------------ 3ms May 30 15:53:45 volumio volumio[1183]: info: ------------------------------ 2ms May 30 15:53:45 volumio volumio[1183]: info: May 30 15:53:45 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:45 volumio volumio[1183]: info: May 30 15:53:45 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:45 volumio volumio[1183]: info: May 30 15:53:45 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:45 volumio volumio[1183]: info: May 30 15:53:45 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:45 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:45 volumio volumio[1183]: info: May 30 15:53:45 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand status took 21 milliseconds May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand status took 21 milliseconds May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand status took 20 milliseconds May 30 15:53:45 volumio volumio[1183]: info: ------------------------------ 18ms May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand status took 16 milliseconds May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:53:45 volumio volumio[1183]: info: May 30 15:53:45 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:45 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:45 volumio volumio[1183]: info: May 30 15:53:45 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:45 volumio volumio[1183]: info: May 30 15:53:45 volumio volumio[1183]: ---------------------------- MPD announces system playlist update May 30 15:53:45 volumio volumio[1183]: info: Ignoring MPD Status Update May 30 15:53:45 volumio volumio[1183]: info: May 30 15:53:45 volumio volumio[1183]: ---------------------------- MPD announces state update: player May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::getState May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand status May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 7 milliseconds May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 7 milliseconds May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 7 milliseconds May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 7 milliseconds May 30 15:53:45 volumio volumio[1183]: info: ------------------------------ 5ms May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand status took 4 milliseconds May 30 15:53:45 volumio volumio[1183]: info: ------------------------------ 5ms May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand status took 3 milliseconds May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::parseState May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 4 May 30 15:53:45 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":930,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"432 Kbps","isStreaming":false,"title":"\"1812\" Overture","artist":"Peter Ilyich Tchaikovsky","album":"Karajan Spectacular","uri":"http://192.168.6.2:9790/minimserver/*/Music/2015.07.06*e5*8c*97*e4*ba*ac/A*20DG/Herbert*20Von*20Karajan*20Spectacular/Karajan*20Spectacular/09*20Peter*20Ilyich*20Tchaikovsky*20-*20*271812*27*20Overture.flac","trackType":"flac"} May 30 15:53:45 volumio volumio[1183]: verbose: CURRENT POSITION 4 May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus stop May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 4 May 30 15:53:45 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":930,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"432 Kbps","isStreaming":false,"title":"\"1812\" Overture","artist":"Peter Ilyich Tchaikovsky","album":"Karajan Spectacular","uri":"http://192.168.6.2:9790/minimserver/*/Music/2015.07.06*e5*8c*97*e4*ba*ac/A*20DG/Herbert*20Von*20Karajan*20Spectacular/Karajan*20Spectacular/09*20Peter*20Ilyich*20Tchaikovsky*20-*20*271812*27*20Overture.flac","trackType":"flac"} May 30 15:53:45 volumio volumio[1183]: verbose: CURRENT POSITION 4 May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:53:45 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 4 May 30 15:53:45 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":930,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"432 Kbps","isStreaming":false,"title":"\"1812\" Overture","artist":"Peter Ilyich Tchaikovsky","album":"Karajan Spectacular","uri":"http://192.168.6.2:9790/minimserver/*/Music/2015.07.06*e5*8c*97*e4*ba*ac/A*20DG/Herbert*20Von*20Karajan*20Spectacular/Karajan*20Spectacular/09*20Peter*20Ilyich*20Tchaikovsky*20-*20*271812*27*20Overture.flac","trackType":"flac"} May 30 15:53:45 volumio volumio[1183]: verbose: CURRENT POSITION 4 May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:53:45 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 4 May 30 15:53:45 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":930,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"432 Kbps","isStreaming":false,"title":"\"1812\" Overture","artist":"Peter Ilyich Tchaikovsky","album":"Karajan Spectacular","uri":"http://192.168.6.2:9790/minimserver/*/Music/2015.07.06*e5*8c*97*e4*ba*ac/A*20DG/Herbert*20Von*20Karajan*20Spectacular/Karajan*20Spectacular/09*20Peter*20Ilyich*20Tchaikovsky*20-*20*271812*27*20Overture.flac","trackType":"flac"} May 30 15:53:45 volumio volumio[1183]: verbose: CURRENT POSITION 4 May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:53:45 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: ------------------------------ 53ms May 30 15:53:45 volumio volumio[1183]: info: ------------------------------ 72ms May 30 15:53:45 volumio volumio[1183]: info: ------------------------------ 71ms May 30 15:53:45 volumio volumio[1183]: info: ------------------------------ 69ms May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 42 milliseconds May 30 15:53:45 volumio volumio[1183]: info: sendMpdCommand playlistinfo took 41 milliseconds May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:53:45 volumio volumio[1183]: verbose: ControllerMpd::parseTrackInfo May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 4 May 30 15:53:45 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":930,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"432 Kbps","isStreaming":false,"title":"\"1812\" Overture","artist":"Peter Ilyich Tchaikovsky","album":"Karajan Spectacular","uri":"http://192.168.6.2:9790/minimserver/*/Music/2015.07.06*e5*8c*97*e4*ba*ac/A*20DG/Herbert*20Von*20Karajan*20Spectacular/Karajan*20Spectacular/09*20Peter*20Ilyich*20Tchaikovsky*20-*20*271812*27*20Overture.flac","trackType":"flac"} May 30 15:53:45 volumio volumio[1183]: verbose: CURRENT POSITION 4 May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:53:45 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: ControllerMpd::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::servicePushState May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getTrack 4 May 30 15:53:45 volumio volumio[1183]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":930,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"432 Kbps","isStreaming":false,"title":"\"1812\" Overture","artist":"Peter Ilyich Tchaikovsky","album":"Karajan Spectacular","uri":"http://192.168.6.2:9790/minimserver/*/Music/2015.07.06*e5*8c*97*e4*ba*ac/A*20DG/Herbert*20Von*20Karajan*20Spectacular/Karajan*20Spectacular/09*20Peter*20Ilyich*20Tchaikovsky*20-*20*271812*27*20Overture.flac","trackType":"flac"} May 30 15:53:45 volumio volumio[1183]: verbose: CURRENT POSITION 4 May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::syncState stateService play May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::syncState currentStatus play May 30 15:53:45 volumio volumio[1183]: info: Received an update from plugin. extracting info from payload May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::pushState May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioPushState May 30 15:53:45 volumio volumio[1183]: info: ------------------------------ 125ms May 30 15:53:45 volumio volumio[1183]: info: ------------------------------ 124ms May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::getQueue May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getQueue May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::getQueue May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getQueue May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::getQueue May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getQueue May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::getQueue May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getQueue May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::getQueue May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getQueue May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::getQueue May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getQueue May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::getQueue May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getQueue May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::getQueue May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getQueue May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::getQueue May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getQueue May 30 15:53:45 volumio volumio[1183]: info: CoreCommandRouter::volumioGetQueue May 30 15:53:45 volumio volumio[1183]: info: CoreStateMachine::getQueue May 30 15:53:45 volumio volumio[1183]: info: CorePlayQueue::getQueue May 30 15:53:47 volumio wpa_supplicant[893]: wlan0: Failed to initiate sched scan May 30 15:53:54 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 30 15:53:54 volumio volumio[1183]: info: Preload queue cleared May 30 15:53:54 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:55 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:55 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:55 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:55 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:55 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:55 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:55 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:55 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:55 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:55 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:55 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:55 volumio wpa_supplicant[893]: wlan0: Failed to initiate sched scan May 30 15:53:55 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:55 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:55 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:55 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:56 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:56 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:56 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:56 volumio volumio[1183]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 30 15:53:56 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:56 volumio volumio[1183]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/none.svg' May 30 15:53:56 volumio volumio[1183]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 15:53:56 volumio volumio[1183]: TypeError: Cannot read property 'length' of undefined May 30 15:53:56 volumio volumio[1183]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 30 15:53:56 volumio volumio[1183]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 30 15:53:56 volumio volumio[1183]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 30 15:53:56 volumio volumio[1183]: at Parser.emit (events.js:315:20) May 30 15:53:56 volumio volumio[1183]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 30 15:53:56 volumio volumio[1183]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 30 15:53:56 volumio volumio[1183]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 30 15:53:56 volumio volumio[1183]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 30 15:53:56 volumio volumio[1183]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 30 15:53:56 volumio volumio[1183]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 30 15:53:56 volumio volumio[1183]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 30 15:53:56 volumio volumio[1183]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 30 15:53:56 volumio volumio[1183]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 30 15:53:56 volumio volumio[1183]: at IncomingMessage.emit (events.js:327:22) May 30 15:53:56 volumio volumio[1183]: at endReadableNT (internal/streams/readable.js:1327:12) May 30 15:53:56 volumio volumio[1183]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 30 15:53:56 volumio volumio[1183]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 15:53:56 volumio sudo[13321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-30 15:52 May 30 15:53:56 volumio sudo[13321]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"