-- Logs begin at Thu 2024-09-26 20:01:06 CST, end at Fri 2024-10-11 08:49:33 CST. -- Oct 11 08:48:11 smsl-d400n kernel: start_addr=(0x8000), end_addr=(0x10000), buffer_size=(0x8000), smp_number_max=(4096) Oct 11 08:48:15 smsl-d400n wpa_supplicant[1009]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="SMSL" Oct 11 08:48:15 smsl-d400n wpa_supplicant[1009]: wlan0: Trying to associate with e0:e0:fc:7d:75:e8 (SSID='SMSL' freq=2437 MHz) Oct 11 08:48:15 smsl-d400n wpa_supplicant[1009]: nl80211: kernel reports: Authentication algorithm number required Oct 11 08:48:17 smsl-d400n wpa_supplicant[1009]: wlan0: CTRL-EVENT-ASSOC-REJECT status_code=1 Oct 11 08:48:17 smsl-d400n wpa_supplicant[1009]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="SMSL" auth_failures=12 duration=122 reason=CONN_FAILED Oct 11 08:48:17 smsl-d400n wpa_supplicant[1009]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Oct 11 08:48:17 smsl-d400n wpa_supplicant[1009]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Oct 11 08:48:28 smsl-d400n kernel: start_addr=(0x8000), end_addr=(0x10000), buffer_size=(0x8000), smp_number_max=(4096) Oct 11 08:48:40 smsl-d400n volumio[3684]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preload queue cleared Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-94462 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-64749 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-89125 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-63755 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-89123 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-64888 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-64707 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-63011 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-63012 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-67941 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-67943 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56437 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56755 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56756 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56283 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56307 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56356 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56372 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56374 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56764 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56770 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-101702 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-67942 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Preloading song: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-67945 Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-94462 in service upnp_browser Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-64749 in service upnp_browser Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-89125 in service upnp_browser Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-63755 in service upnp_browser Oct 11 08:48:40 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-89123 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-64888 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-64707 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-63011 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-63012 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-67941 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-67943 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56437 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56755 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56756 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56283 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56307 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56356 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56372 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56374 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56764 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56770 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-101702 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-67942 in service upnp_browser Oct 11 08:48:41 smsl-d400n volumio[3684]: info: Exploding uri upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-67945 in service upnp_browser Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Preload queue cleared Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::ClearQueue Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::stop Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::stPlaybackTimer Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::updateTrackBlock Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrackBlock Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrack 0 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output update for this device Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::serviceStop Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrack 0 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::serviceStop Oct 11 08:48:50 smsl-d400n volumio[3684]: info: [1728607730046] ControllerUPNPBrowser::stop Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::clearPlayQueue Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::saveQueue Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushQueue Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::addQueueItems Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::addQueueItems Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Preload queue cleared Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-94462 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-94462 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-64749 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-64749 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-89125 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-89125 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-63755 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-63755 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-89123 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-89123 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-64888 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-64888 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-64707 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-64707 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-63011 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-63011 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-63012 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-63012 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-67941 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-67941 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-67943 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-67943 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56437 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56437 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56755 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56755 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56756 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56756 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56283 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56283 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56307 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56307 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56356 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56356 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56372 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56372 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56374 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56374 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56764 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56764 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56770 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-56770 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-101702 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-101702 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-67942 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-67942 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Adding Item to queue: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-67945 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Using cached record of: upnp/http://192.168.2.46:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@4-67945 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushQueue Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::saveQueue Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::updateTrackBlock Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrackBlock Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPlay Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::play index 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::stop Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::play index undefined Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrack 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::startPlaybackTimer Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrack 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: [1728607730133] ControllerUPNPBrowser::clearAddPlayTrack Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Oct 11 08:48:50 smsl-d400n volumio[3684]: ---------------------------- MPD announces state update: player Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand stop took 110 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ControllerMpd::getState Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand status Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand stop took 25 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Oct 11 08:48:50 smsl-d400n volumio[3684]: ---------------------------- MPD announces state update: player Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ControllerMpd::getState Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand status Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Oct 11 08:48:50 smsl-d400n volumio[3684]: ---------------------------- MPD announces state update: player Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ControllerMpd::getState Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand status Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Oct 11 08:48:50 smsl-d400n volumio[3684]: ---------------------------- MPD announces system playlist update Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Ignoring MPD Status Update Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand status took 12 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand clear took 11 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand status took 9 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand status took 6 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::parseState Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.46:10243/WMPNSSv4/2700598965/0_NC01Njc3MA" Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::parseState Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::parseState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ControllerMpd::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::servicePushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrack 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output update for this device Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrack 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: 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} Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: CURRENT POSITION 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::syncState stateService stop Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::syncState currentStatus stop Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrack 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output update for this device Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output Oct 11 08:48:50 smsl-d400n volumio[3684]: info: No code Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrack 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output update for this device Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ControllerMpd::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::servicePushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrack 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output update for this device Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrack 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: 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} Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: CURRENT POSITION 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::syncState stateService stop Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::syncState currentStatus stop Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrack 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output update for this device Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output Oct 11 08:48:50 smsl-d400n volumio[3684]: info: No code Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrack 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output update for this device Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ------------------------------ 194ms Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ------------------------------ 193ms Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Oct 11 08:48:50 smsl-d400n volumio[3684]: ---------------------------- MPD announces system playlist update Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Ignoring MPD Status Update Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Oct 11 08:48:50 smsl-d400n volumio[3684]: ---------------------------- MPD announces system playlist update Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Ignoring MPD Status Update Oct 11 08:48:50 smsl-d400n volumio[3684]: error: updateQueue error: null Oct 11 08:48:50 smsl-d400n volumio[3684]: error: updateQueue error: null Oct 11 08:48:50 smsl-d400n volumio[3684]: error: updateQueue error: null Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ------------------------------ 198ms Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand playlistinfo took 194 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.46:10243/WMPNSSv4/2700598965/0_NC01Njc3MA" Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ------------------------------ 10ms Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ------------------------------ 8ms Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::parseTrackInfo Oct 11 08:48:50 smsl-d400n volumio[3684]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ------------------------------ 218ms Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Oct 11 08:48:50 smsl-d400n volumio[3684]: ---------------------------- MPD announces system playlist update Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Ignoring MPD Status Update Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand add "http://192.168.2.46:10243/WMPNSSv4/2700598965/0_NC01Njc3MA" took 49 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand play Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Oct 11 08:48:50 smsl-d400n volumio[3684]: ---------------------------- MPD announces system playlist update Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Ignoring MPD Status Update Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Oct 11 08:48:50 smsl-d400n volumio[3684]: ---------------------------- MPD announces system playlist update Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Ignoring MPD Status Update Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ------------------------------ 16ms Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand play took 12 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ------------------------------ 12ms Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ------------------------------ 10ms Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Oct 11 08:48:50 smsl-d400n volumio[3684]: ---------------------------- MPD announces state update: player Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ControllerMpd::getState Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand status Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Oct 11 08:48:50 smsl-d400n volumio[3684]: ---------------------------- MPD announces state update: player Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ControllerMpd::getState Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand status Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Oct 11 08:48:50 smsl-d400n volumio[3684]: ---------------------------- MPD announces state update: player Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ControllerMpd::getState Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand status Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Oct 11 08:48:50 smsl-d400n volumio[3684]: ---------------------------- MPD announces state update: player Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ControllerMpd::getState Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand status Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand status took 25 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand status took 14 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand status took 7 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::parseState Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::parseState Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::parseState Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Oct 11 08:48:50 smsl-d400n volumio[3684]: ---------------------------- MPD announces state update: player Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ControllerMpd::getState Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand status Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Oct 11 08:48:50 smsl-d400n volumio[3684]: ---------------------------- MPD announces state update: player Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ControllerMpd::getState Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand status Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand status took 16 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand status took 13 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand status took 17 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::parseState Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::parseTrackInfo Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::parseTrackInfo Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::parseTrackInfo Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::parseState Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::parseState Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ControllerMpd::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::servicePushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrack 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"768 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_NC01Njc3MA","artist":null,"album":null,"uri":"http://192.168.2.46:10243/WMPNSSv4/2700598965/0_NC01Njc3MA","trackType":""} Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: CURRENT POSITION 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::syncState stateService play Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::syncState currentStatus stop Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ControllerMpd::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::servicePushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrack 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"768 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_NC01Njc3MA","artist":null,"album":null,"uri":"http://192.168.2.46:10243/WMPNSSv4/2700598965/0_NC01Njc3MA","trackType":""} Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: CURRENT POSITION 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::syncState stateService play Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::syncState currentStatus play Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Received an update from plugin. extracting info from payload Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output update for this device Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output update for this device Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ControllerMpd::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::servicePushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrack 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"768 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_NC01Njc3MA","artist":null,"album":null,"uri":"http://192.168.2.46:10243/WMPNSSv4/2700598965/0_NC01Njc3MA","trackType":""} Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: CURRENT POSITION 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::syncState stateService play Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::syncState currentStatus play Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Received an update from plugin. extracting info from payload Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output update for this device Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output update for this device Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ------------------------------ 132ms Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ------------------------------ 210ms Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ------------------------------ 199ms Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand playlistinfo took 148 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand playlistinfo took 140 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: info: sendMpdCommand playlistinfo took 140 milliseconds Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::parseTrackInfo Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::parseTrackInfo Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: ControllerMpd::parseTrackInfo Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ControllerMpd::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::servicePushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrack 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"768 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_NC01Njc3MA","artist":null,"album":null,"uri":"http://192.168.2.46:10243/WMPNSSv4/2700598965/0_NC01Njc3MA","trackType":""} Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: CURRENT POSITION 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::syncState stateService play Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::syncState currentStatus play Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Received an update from plugin. extracting info from payload Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output update for this device Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output update for this device Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ControllerMpd::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::servicePushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrack 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"768 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_NC01Njc3MA","artist":null,"album":null,"uri":"http://192.168.2.46:10243/WMPNSSv4/2700598965/0_NC01Njc3MA","trackType":""} Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: CURRENT POSITION 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::syncState stateService play Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::syncState currentStatus play Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Received an update from plugin. extracting info from payload Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output update for this device Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output update for this device Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ControllerMpd::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::servicePushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CorePlayQueue::getTrack 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"768 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_NC01Njc3MA","artist":null,"album":null,"uri":"http://192.168.2.46:10243/WMPNSSv4/2700598965/0_NC01Njc3MA","trackType":""} Oct 11 08:48:50 smsl-d400n volumio[3684]: verbose: CURRENT POSITION 20 Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::syncState stateService play Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::syncState currentStatus play Oct 11 08:48:50 smsl-d400n volumio[3684]: info: Received an update from plugin. extracting info from payload Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output update for this device Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreStateMachine::pushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: CoreCommandRouter::volumioPushState Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output update for this device Oct 11 08:48:50 smsl-d400n volumio[3684]: info: MRS: Pushing multiroomSync output Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ------------------------------ 404ms Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ------------------------------ 394ms Oct 11 08:48:50 smsl-d400n volumio[3684]: info: ------------------------------ 392ms Oct 11 08:48:53 smsl-d400n volumiologrotate[691]: ls: cannot access '/var/log/samba/log.wb-SMSL': No such file or directory Oct 11 08:48:53 smsl-d400n volumiologrotate[691]: ls: cannot access 'D400N': No such file or directory Oct 11 08:49:33 smsl-d400n volumio[3684]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 08:49:33 smsl-d400n volumio[3684]: Error: connect ETIMEDOUT 174.36.196.242:80 Oct 11 08:49:33 smsl-d400n volumio[3684]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 11 08:49:33 smsl-d400n volumio[3684]: errno: -110, Oct 11 08:49:33 smsl-d400n volumio[3684]: code: 'ETIMEDOUT', Oct 11 08:49:33 smsl-d400n volumio[3684]: syscall: 'connect', Oct 11 08:49:33 smsl-d400n volumio[3684]: address: '174.36.196.242', Oct 11 08:49:33 smsl-d400n volumio[3684]: port: 80 Oct 11 08:49:33 smsl-d400n volumio[3684]: } Oct 11 08:49:33 smsl-d400n volumio[3684]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 08:49:33 smsl-d400n sudo[4078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 08:48 Oct 11 08:49:33 smsl-d400n sudo[4078]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 26 Sep 2024 02:01:06 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="nanopineo3-armbian" VOLUMIO_DEVICENAME="NanoPi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="14faf490d09f3983d26ad2f504844908"