-- Logs begin at Fri 2024-07-05 12:12:43 CST, end at Fri 2024-07-05 14:43:50 CST. -- Jul 05 14:42:00 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:01 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:01 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:02 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:02 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:03 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:04 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:04 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:05 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:05 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:06 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:07 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:07 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:08 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:08 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:09 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:10 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:10 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:11 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:11 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:12 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:13 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:42:13 1905 volumio[21917]: info: Jul 05 14:42:13 1905 volumio[21917]: ---------------------------- MPD announces state update: update Jul 05 14:42:13 1905 volumio[21917]: info: ControllerMpd::getState Jul 05 14:42:13 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:42:13 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:42:13 1905 volumio[21917]: info: Jul 05 14:42:13 1905 volumio[21917]: ---------------------------- MPD announces state update: update Jul 05 14:42:13 1905 volumio[21917]: info: sendMpdCommand status took 40 milliseconds Jul 05 14:42:13 1905 volumio[21917]: info: ControllerMpd::getState Jul 05 14:42:13 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:42:13 1905 volumio[21917]: info: sendMpdCommand status took 7 milliseconds Jul 05 14:42:13 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:42:13 1905 volumio[21917]: info: Command Router : Notfying DB Updatetrue Jul 05 14:42:13 1905 volumio[21917]: info: CoreCommandRouter::Close All Modals sent Jul 05 14:42:13 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:42:13 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:42:13 1905 volumio[21917]: info: sendMpdCommand status took 148 milliseconds Jul 05 14:42:13 1905 volumio[21917]: info: sendMpdCommand status took 145 milliseconds Jul 05 14:42:13 1905 volumio[21917]: info: sendMpdCommand playlistinfo took 16 milliseconds Jul 05 14:42:13 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:42:13 1905 volumio[21917]: info: Command Router : Notfying DB Updatefalse Jul 05 14:42:13 1905 volumio[21917]: info: CoreCommandRouter::Close All Modals sent Jul 05 14:42:13 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:42:13 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:42:13 1905 volumio[21917]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:42:13 1905 volumio[21917]: info: ControllerMpd::pushState Jul 05 14:42:13 1905 volumio[21917]: info: CoreCommandRouter::servicePushState Jul 05 14:42:13 1905 volumio[21917]: info: CorePlayQueue::getTrack 2 Jul 05 14:42:13 1905 volumio[21917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":40645,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2079 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"http://192.168.23.23:50002/m/NDLNA/3180.flac","trackType":"flac"} Jul 05 14:42:13 1905 volumio[21917]: verbose: CURRENT POSITION 2 Jul 05 14:42:13 1905 volumio[21917]: info: CoreStateMachine::syncState stateService play Jul 05 14:42:13 1905 volumio[21917]: info: CoreStateMachine::syncState currentStatus play Jul 05 14:42:13 1905 volumio[21917]: info: Received an update from plugin. extracting info from payload Jul 05 14:42:13 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:42:13 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:42:13 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:42:13 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:42:13 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:42:13 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:42:13 1905 volumio[21917]: info: ------------------------------ 289ms Jul 05 14:42:13 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:42:13 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:42:13 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:42:13 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:42:13 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:42:13 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:42:13 1905 volumio[21917]: info: sendMpdCommand playlistinfo took 81 milliseconds Jul 05 14:42:13 1905 volumio[21917]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:42:13 1905 volumio[21917]: info: ControllerMpd::pushState Jul 05 14:42:13 1905 volumio[21917]: info: CoreCommandRouter::servicePushState Jul 05 14:42:13 1905 volumio[21917]: info: CorePlayQueue::getTrack 2 Jul 05 14:42:13 1905 volumio[21917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":40813,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2070 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"http://192.168.23.23:50002/m/NDLNA/3180.flac","trackType":"flac"} Jul 05 14:42:13 1905 volumio[21917]: verbose: CURRENT POSITION 2 Jul 05 14:42:13 1905 volumio[21917]: info: CoreStateMachine::syncState stateService play Jul 05 14:42:13 1905 volumio[21917]: info: CoreStateMachine::syncState currentStatus play Jul 05 14:42:13 1905 volumio[21917]: info: Received an update from plugin. extracting info from payload Jul 05 14:42:13 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:42:13 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:42:13 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:42:13 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:42:13 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:42:13 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:42:13 1905 volumio[21917]: info: ------------------------------ 323ms Jul 05 14:42:13 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:13 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:42:13 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:42:13 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:42:13 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:42:13 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:42:13 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:42:14 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:14 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:15 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:15 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:16 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:17 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:17 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:18 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:19 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:19 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:20 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:20 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:21 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:22 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:22 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:23 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:23 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:24 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:25 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:25 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:26 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:26 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:27 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:28 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:28 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:29 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:29 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:30 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:31 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:31 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:32 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:33 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:33 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:34 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:34 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:35 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:36 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:36 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:37 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:37 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:38 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:39 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:39 1905 hostapd[2450]: wlan0: STA 14:d8:64:4c:f2:43 IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE) Jul 05 14:42:39 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:40 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:40 1905 systemd-networkd[2124]: wlan0: Lost carrier Jul 05 14:42:40 1905 kernel: wlan0: deauthenticated from 14:d8:64:4c:f2:43 (Reason: 6=CLASS2_FRAME_FROM_NONAUTH_STA) Jul 05 14:42:40 1905 systemd-timesyncd[2249]: No network connectivity, watching for changes. Jul 05 14:42:40 1905 hostapd[2450]: wlan0: STA 14:d8:64:4c:f2:43 IEEE 802.11: disassociated Jul 05 14:42:40 1905 wpa_supplicant[2533]: wlan0: CTRL-EVENT-DISCONNECTED bssid=14:d8:64:4c:f2:43 reason=0 Jul 05 14:42:40 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:41 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:42 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:42 1905 wpa_supplicant[2533]: wlan0: Trying to associate with 14:d8:64:4c:f2:43 (SSID='FORTEX' freq=2412 MHz) Jul 05 14:42:42 1905 wpa_supplicant[2533]: Failed to add supported operating classes IE Jul 05 14:42:42 1905 kernel: wlan0: authenticate with 14:d8:64:4c:f2:43 Jul 05 14:42:42 1905 kernel: wlan0: send auth to 14:d8:64:4c:f2:43 (try 1/3) Jul 05 14:42:42 1905 kernel: wlan0: authenticated Jul 05 14:42:42 1905 kernel: wlan0: associate with 14:d8:64:4c:f2:43 (try 1/3) Jul 05 14:42:42 1905 kernel: wlan0: RX AssocResp from 14:d8:64:4c:f2:43 (capab=0x1c11 status=0 aid=1) Jul 05 14:42:42 1905 wpa_supplicant[2533]: wlan0: Associated with 14:d8:64:4c:f2:43 Jul 05 14:42:42 1905 kernel: wlan0: associated Jul 05 14:42:42 1905 systemd-networkd[2124]: wlan0: Gained carrier Jul 05 14:42:42 1905 systemd-timesyncd[2249]: Network configuration changed, trying to establish connection. Jul 05 14:42:42 1905 wpa_supplicant[2533]: wlan0: WPA: Key negotiation completed with 14:d8:64:4c:f2:43 [PTK=CCMP GTK=CCMP] Jul 05 14:42:42 1905 wpa_supplicant[2533]: wlan0: CTRL-EVENT-CONNECTED - Connection to 14:d8:64:4c:f2:43 completed [id=0 id_str=] Jul 05 14:42:42 1905 systemd-networkd[2124]: wlan0: Lost carrier Jul 05 14:42:42 1905 avahi-daemon[2256]: Withdrawing address record for 192.168.23.88 on wlan0. Jul 05 14:42:42 1905 avahi-daemon[2256]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.23.88. Jul 05 14:42:42 1905 kernel: wlan0: Limiting TX power to 35 (35 - 0) dBm as advertised by 14:d8:64:4c:f2:43 Jul 05 14:42:42 1905 avahi-daemon[2256]: Interface wlan0.IPv4 no longer relevant for mDNS. Jul 05 14:42:42 1905 volumio[21917]: info: mDNS: A device disapperared from network Jul 05 14:42:42 1905 volumio[21917]: info: mDNS: Device abcd disapperared from network Jul 05 14:42:42 1905 volumio[21917]: error: Could not retrieve plugin audio_interface multiroom Jul 05 14:42:42 1905 systemd-networkd[2124]: wlan0: Gained carrier Jul 05 14:42:42 1905 avahi-daemon[2256]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.23.88. Jul 05 14:42:42 1905 avahi-daemon[2256]: New relevant interface wlan0.IPv4 for mDNS. Jul 05 14:42:42 1905 avahi-daemon[2256]: Registering new address record for 192.168.23.88 on wlan0.IPv4. Jul 05 14:42:42 1905 systemd-timesyncd[2249]: Network configuration changed, trying to establish connection. Jul 05 14:42:42 1905 volumio[21917]: info: mDNS: A device disapperared from network Jul 05 14:42:42 1905 volumio[21917]: info: mDNS: Device 1905 disapperared from network Jul 05 14:42:42 1905 volumio[21917]: error: Could not retrieve plugin audio_interface multiroom Jul 05 14:42:42 1905 systemd-resolved[2248]: Using degraded feature set (UDP) for DNS server 192.168.23.1. Jul 05 14:42:42 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:42 1905 volumio[21917]: Discovery: adding 316996ef-407b-47f4-8962-70668e4c9944 Jul 05 14:42:42 1905 volumio[21917]: info: mDNS: Found device 1905 Jul 05 14:42:42 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:42 1905 volumio[21917]: error: Could not retrieve plugin audio_interface multiroom Jul 05 14:42:42 1905 volumio[21917]: error: Could not retrieve plugin audio_interface multiroom Jul 05 14:42:42 1905 volumio[21917]: Discovery: adding c5f1452e-5715-489b-b731-347381f23cb3 Jul 05 14:42:42 1905 volumio[21917]: info: mDNS: Found device abcd Jul 05 14:42:42 1905 systemd-timesyncd[2249]: Initial synchronization to time server 185.125.190.56:123 (ntp.ubuntu.com). Jul 05 14:42:42 1905 volumio[21917]: error: Could not retrieve plugin audio_interface multiroom Jul 05 14:42:43 1905 volumio[21917]: error: Could not retrieve plugin audio_interface multiroom Jul 05 14:42:43 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:44 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:44 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:45 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:45 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:46 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:47 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:47 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:47 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:42:47 1905 volumio[21917]: info: Jul 05 14:42:47 1905 volumio[21917]: ---------------------------- MPD announces state update: update Jul 05 14:42:47 1905 volumio[21917]: info: ControllerMpd::getState Jul 05 14:42:47 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:42:47 1905 volumio[21917]: info: sendMpdCommand status took 10 milliseconds Jul 05 14:42:47 1905 volumio[21917]: info: sendMpdCommand status took 6 milliseconds Jul 05 14:42:47 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:42:47 1905 volumio[21917]: info: Command Router : Notfying DB Updatetrue Jul 05 14:42:47 1905 volumio[21917]: info: CoreCommandRouter::Close All Modals sent Jul 05 14:42:47 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:42:47 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:42:47 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:42:47 1905 volumio[21917]: info: Jul 05 14:42:47 1905 volumio[21917]: ---------------------------- MPD announces state update: update Jul 05 14:42:47 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:42:47 1905 volumio[21917]: info: Jul 05 14:42:47 1905 volumio[21917]: ---------------------------- MPD announces state update: update Jul 05 14:42:47 1905 volumio[21917]: info: ControllerMpd::getState Jul 05 14:42:47 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:42:47 1905 volumio[21917]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 05 14:42:47 1905 volumio[21917]: info: ControllerMpd::getState Jul 05 14:42:47 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:42:47 1905 volumio[21917]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:42:47 1905 volumio[21917]: info: ControllerMpd::pushState Jul 05 14:42:47 1905 volumio[21917]: info: CoreCommandRouter::servicePushState Jul 05 14:42:47 1905 volumio[21917]: info: CorePlayQueue::getTrack 2 Jul 05 14:42:47 1905 volumio[21917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":75164,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"2484 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"http://192.168.23.23:50002/m/NDLNA/3180.flac","trackType":"flac"} Jul 05 14:42:47 1905 volumio[21917]: verbose: CURRENT POSITION 2 Jul 05 14:42:47 1905 volumio[21917]: info: CoreStateMachine::syncState stateService play Jul 05 14:42:47 1905 volumio[21917]: info: CoreStateMachine::syncState currentStatus play Jul 05 14:42:47 1905 volumio[21917]: info: Received an update from plugin. extracting info from payload Jul 05 14:42:47 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:42:47 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:42:47 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:42:47 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:42:47 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:42:47 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:42:47 1905 volumio[21917]: info: ------------------------------ 223ms Jul 05 14:42:47 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:42:47 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:42:47 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:42:47 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:42:47 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:42:47 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:42:47 1905 volumio[21917]: info: sendMpdCommand status took 82 milliseconds Jul 05 14:42:47 1905 volumio[21917]: info: sendMpdCommand status took 80 milliseconds Jul 05 14:42:47 1905 volumio[21917]: info: sendMpdCommand status took 77 milliseconds Jul 05 14:42:47 1905 volumio[21917]: info: sendMpdCommand status took 74 milliseconds Jul 05 14:42:47 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:42:48 1905 volumio[21917]: info: Command Router : Notfying DB Updatetrue Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::Close All Modals sent Jul 05 14:42:48 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:42:48 1905 volumio[21917]: info: Command Router : Notfying DB Updatetrue Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::Close All Modals sent Jul 05 14:42:48 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:42:48 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:42:48 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:42:48 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:42:48 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:42:48 1905 volumio[21917]: info: Jul 05 14:42:48 1905 volumio[21917]: ---------------------------- MPD announces state update: update Jul 05 14:42:48 1905 volumio[21917]: info: ControllerMpd::getState Jul 05 14:42:48 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:42:48 1905 volumio[21917]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 05 14:42:48 1905 volumio[21917]: info: sendMpdCommand playlistinfo took 25 milliseconds Jul 05 14:42:48 1905 volumio[21917]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:42:48 1905 volumio[21917]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:42:48 1905 volumio[21917]: info: ControllerMpd::pushState Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::servicePushState Jul 05 14:42:48 1905 volumio[21917]: info: CorePlayQueue::getTrack 2 Jul 05 14:42:48 1905 volumio[21917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":75332,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"2306 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"http://192.168.23.23:50002/m/NDLNA/3180.flac","trackType":"flac"} Jul 05 14:42:48 1905 volumio[21917]: verbose: CURRENT POSITION 2 Jul 05 14:42:48 1905 volumio[21917]: info: CoreStateMachine::syncState stateService play Jul 05 14:42:48 1905 volumio[21917]: info: CoreStateMachine::syncState currentStatus play Jul 05 14:42:48 1905 volumio[21917]: info: Received an update from plugin. extracting info from payload Jul 05 14:42:48 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:42:48 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:42:48 1905 volumio[21917]: info: ControllerMpd::pushState Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::servicePushState Jul 05 14:42:48 1905 volumio[21917]: info: CorePlayQueue::getTrack 2 Jul 05 14:42:48 1905 volumio[21917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":75332,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"2306 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"http://192.168.23.23:50002/m/NDLNA/3180.flac","trackType":"flac"} Jul 05 14:42:48 1905 volumio[21917]: verbose: CURRENT POSITION 2 Jul 05 14:42:48 1905 volumio[21917]: info: CoreStateMachine::syncState stateService play Jul 05 14:42:48 1905 volumio[21917]: info: CoreStateMachine::syncState currentStatus play Jul 05 14:42:48 1905 volumio[21917]: info: Received an update from plugin. extracting info from payload Jul 05 14:42:48 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:42:48 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:42:48 1905 volumio[21917]: info: ------------------------------ 341ms Jul 05 14:42:48 1905 volumio[21917]: info: ------------------------------ 340ms Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:42:48 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:42:48 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:42:48 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:42:48 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:42:48 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:42:48 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:42:48 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:42:48 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:42:48 1905 volumio[21917]: info: sendMpdCommand status took 166 milliseconds Jul 05 14:42:48 1905 volumio[21917]: info: sendMpdCommand status took 164 milliseconds Jul 05 14:42:48 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:42:48 1905 volumio[21917]: info: Command Router : Notfying DB Updatefalse Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::Close All Modals sent Jul 05 14:42:48 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:42:48 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:42:48 1905 volumio[21917]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 05 14:42:48 1905 volumio[21917]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:42:48 1905 volumio[21917]: info: ControllerMpd::pushState Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::servicePushState Jul 05 14:42:48 1905 volumio[21917]: info: CorePlayQueue::getTrack 2 Jul 05 14:42:48 1905 volumio[21917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":75589,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2224 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"http://192.168.23.23:50002/m/NDLNA/3180.flac","trackType":"flac"} Jul 05 14:42:48 1905 volumio[21917]: verbose: CURRENT POSITION 2 Jul 05 14:42:48 1905 volumio[21917]: info: CoreStateMachine::syncState stateService play Jul 05 14:42:48 1905 volumio[21917]: info: CoreStateMachine::syncState currentStatus play Jul 05 14:42:48 1905 volumio[21917]: info: Received an update from plugin. extracting info from payload Jul 05 14:42:48 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:42:48 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:42:48 1905 volumio[21917]: info: ------------------------------ 279ms Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:42:48 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:42:48 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:42:48 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:42:48 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:42:48 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:49 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:49 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:50 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:50 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:51 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:52 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:52 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:53 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:53 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:54 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:55 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:55 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:56 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:56 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:57 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:58 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:58 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:59 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:42:59 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:00 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:01 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:01 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:02 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:03 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:03 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:04 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:04 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:05 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:06 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:06 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:07 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:07 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:08 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:09 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:09 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:10 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:10 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:11 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:12 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:12 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:13 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:43:13 1905 volumio[21917]: info: Jul 05 14:43:13 1905 volumio[21917]: ---------------------------- MPD announces state update: update Jul 05 14:43:13 1905 volumio[21917]: info: ControllerMpd::getState Jul 05 14:43:13 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:43:13 1905 volumio[21917]: info: sendMpdCommand status took 7 milliseconds Jul 05 14:43:13 1905 volumio[21917]: info: sendMpdCommand status took 5 milliseconds Jul 05 14:43:13 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:43:13 1905 volumio[21917]: info: Command Router : Notfying DB Updatetrue Jul 05 14:43:13 1905 volumio[21917]: info: CoreCommandRouter::Close All Modals sent Jul 05 14:43:13 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:43:13 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:43:13 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:43:13 1905 volumio[21917]: info: Jul 05 14:43:13 1905 volumio[21917]: ---------------------------- MPD announces state update: update Jul 05 14:43:13 1905 volumio[21917]: info: ControllerMpd::getState Jul 05 14:43:13 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:43:13 1905 volumio[21917]: info: sendMpdCommand playlistinfo took 39 milliseconds Jul 05 14:43:13 1905 volumio[21917]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:43:13 1905 volumio[21917]: info: ControllerMpd::pushState Jul 05 14:43:13 1905 volumio[21917]: info: CoreCommandRouter::servicePushState Jul 05 14:43:13 1905 volumio[21917]: info: CorePlayQueue::getTrack 2 Jul 05 14:43:13 1905 volumio[21917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":100590,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"2176 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"http://192.168.23.23:50002/m/NDLNA/3180.flac","trackType":"flac"} Jul 05 14:43:13 1905 volumio[21917]: verbose: CURRENT POSITION 2 Jul 05 14:43:13 1905 volumio[21917]: info: CoreStateMachine::syncState stateService play Jul 05 14:43:13 1905 volumio[21917]: info: CoreStateMachine::syncState currentStatus play Jul 05 14:43:13 1905 volumio[21917]: info: Received an update from plugin. extracting info from payload Jul 05 14:43:13 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:43:13 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:43:13 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:43:13 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:43:13 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:43:13 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:43:13 1905 volumio[21917]: info: ------------------------------ 203ms Jul 05 14:43:13 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:43:13 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:43:13 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:43:13 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:43:13 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:43:13 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:43:13 1905 volumio[21917]: info: sendMpdCommand status took 109 milliseconds Jul 05 14:43:13 1905 volumio[21917]: info: sendMpdCommand status took 84 milliseconds Jul 05 14:43:13 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:43:13 1905 volumio[21917]: info: Command Router : Notfying DB Updatefalse Jul 05 14:43:13 1905 volumio[21917]: info: CoreCommandRouter::Close All Modals sent Jul 05 14:43:13 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:43:13 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:43:13 1905 volumio[21917]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 05 14:43:13 1905 volumio[21917]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:43:13 1905 volumio[21917]: info: ControllerMpd::pushState Jul 05 14:43:13 1905 volumio[21917]: info: CoreCommandRouter::servicePushState Jul 05 14:43:13 1905 volumio[21917]: info: CorePlayQueue::getTrack 2 Jul 05 14:43:13 1905 volumio[21917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":100763,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2116 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"http://192.168.23.23:50002/m/NDLNA/3180.flac","trackType":"flac"} Jul 05 14:43:13 1905 volumio[21917]: verbose: CURRENT POSITION 2 Jul 05 14:43:13 1905 volumio[21917]: info: CoreStateMachine::syncState stateService play Jul 05 14:43:13 1905 volumio[21917]: info: CoreStateMachine::syncState currentStatus play Jul 05 14:43:13 1905 volumio[21917]: info: Received an update from plugin. extracting info from payload Jul 05 14:43:13 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:43:13 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:43:13 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:43:13 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:43:13 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:43:13 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:43:13 1905 volumio[21917]: info: ------------------------------ 269ms Jul 05 14:43:13 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:43:13 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:43:13 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:43:13 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:43:13 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:43:13 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:43:13 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:14 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:14 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:15 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:16 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:16 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:17 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:17 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:18 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:19 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:19 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:20 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:20 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:21 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:22 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:22 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:23 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:23 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:24 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:25 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:25 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:26 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:26 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:27 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:28 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:28 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:29 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:29 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:30 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:31 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:31 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:32 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:32 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:33 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:34 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:34 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:35 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:35 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:36 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:37 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:37 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:38 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:39 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:39 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:40 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:43:40 1905 volumio[21917]: info: Jul 05 14:43:40 1905 volumio[21917]: ---------------------------- MPD announces state update: update Jul 05 14:43:40 1905 volumio[21917]: info: ControllerMpd::getState Jul 05 14:43:40 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:43:40 1905 volumio[21917]: info: sendMpdCommand status took 7 milliseconds Jul 05 14:43:40 1905 volumio[21917]: info: sendMpdCommand status took 5 milliseconds Jul 05 14:43:40 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:43:40 1905 volumio[21917]: info: Command Router : Notfying DB Updatetrue Jul 05 14:43:40 1905 volumio[21917]: info: CoreCommandRouter::Close All Modals sent Jul 05 14:43:40 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:43:40 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:43:40 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:43:40 1905 volumio[21917]: info: Jul 05 14:43:40 1905 volumio[21917]: ---------------------------- MPD announces state update: update Jul 05 14:43:40 1905 volumio[21917]: info: ControllerMpd::getState Jul 05 14:43:40 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:43:40 1905 volumio[21917]: info: sendMpdCommand playlistinfo took 16 milliseconds Jul 05 14:43:40 1905 volumio[21917]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:43:40 1905 volumio[21917]: info: ControllerMpd::pushState Jul 05 14:43:40 1905 volumio[21917]: info: CoreCommandRouter::servicePushState Jul 05 14:43:40 1905 volumio[21917]: info: CorePlayQueue::getTrack 2 Jul 05 14:43:40 1905 volumio[21917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":127685,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"2627 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"http://192.168.23.23:50002/m/NDLNA/3180.flac","trackType":"flac"} Jul 05 14:43:40 1905 volumio[21917]: verbose: CURRENT POSITION 2 Jul 05 14:43:40 1905 volumio[21917]: info: CoreStateMachine::syncState stateService play Jul 05 14:43:40 1905 volumio[21917]: info: CoreStateMachine::syncState currentStatus play Jul 05 14:43:40 1905 volumio[21917]: info: Received an update from plugin. extracting info from payload Jul 05 14:43:40 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:43:40 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:43:40 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:43:40 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:43:40 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:43:40 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:43:40 1905 volumio[21917]: info: ------------------------------ 198ms Jul 05 14:43:40 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:40 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:43:40 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:43:40 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:43:40 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:43:40 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:43:40 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:43:40 1905 volumio[21917]: info: sendMpdCommand status took 93 milliseconds Jul 05 14:43:40 1905 volumio[21917]: info: sendMpdCommand status took 90 milliseconds Jul 05 14:43:40 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:43:40 1905 volumio[21917]: info: Command Router : Notfying DB Updatefalse Jul 05 14:43:40 1905 volumio[21917]: info: CoreCommandRouter::Close All Modals sent Jul 05 14:43:40 1905 volumio[21917]: verbose: ControllerMpd::parseState Jul 05 14:43:40 1905 volumio[21917]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:43:40 1905 volumio[21917]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 05 14:43:40 1905 volumio[21917]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:43:40 1905 volumio[21917]: info: ControllerMpd::pushState Jul 05 14:43:40 1905 volumio[21917]: info: CoreCommandRouter::servicePushState Jul 05 14:43:40 1905 volumio[21917]: info: CorePlayQueue::getTrack 2 Jul 05 14:43:40 1905 volumio[21917]: verbose: STATE SERVICE {"status":"play","position":0,"seek":127853,"duration":182,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2771 Kbps","isStreaming":false,"title":"Simple Symphony op. 4 - Boisterous Bourree","artist":"TrondheimSolistene","album":"DIVERTIMENTI","uri":"http://192.168.23.23:50002/m/NDLNA/3180.flac","trackType":"flac"} Jul 05 14:43:40 1905 volumio[21917]: verbose: CURRENT POSITION 2 Jul 05 14:43:40 1905 volumio[21917]: info: CoreStateMachine::syncState stateService play Jul 05 14:43:40 1905 volumio[21917]: info: CoreStateMachine::syncState currentStatus play Jul 05 14:43:40 1905 volumio[21917]: info: Received an update from plugin. extracting info from payload Jul 05 14:43:40 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:43:40 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:43:40 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:43:40 1905 volumio[21917]: info: CoreStateMachine::pushState Jul 05 14:43:40 1905 volumio[21917]: info: CoreCommandRouter::volumioPushState Jul 05 14:43:40 1905 volumio[21917]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:43:40 1905 volumio[21917]: info: ------------------------------ 242ms Jul 05 14:43:40 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:43:40 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:43:40 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:43:40 1905 volumio[21917]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:43:40 1905 volumio[21917]: info: CoreStateMachine::getQueue Jul 05 14:43:40 1905 volumio[21917]: info: CorePlayQueue::getQueue Jul 05 14:43:41 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:41 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:42 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:42 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:43 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:44 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:44 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:45 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:45 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:46 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:47 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:47 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:48 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:48 1905 volumio[21917]: info: CoreCommandRouter::volumioGetState Jul 05 14:43:49 1905 volumio[21917]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 14:43:49 1905 volumio[21917]: { Error: connect ETIMEDOUT 128.242.245.221:443 Jul 05 14:43:49 1905 volumio[21917]: at Object._errnoException (util.js:1022:11) Jul 05 14:43:49 1905 volumio[21917]: at _exceptionWithHostPort (util.js:1044:20) Jul 05 14:43:49 1905 volumio[21917]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Jul 05 14:43:49 1905 volumio[21917]: code: 'ETIMEDOUT', Jul 05 14:43:49 1905 volumio[21917]: errno: 'ETIMEDOUT', Jul 05 14:43:49 1905 volumio[21917]: syscall: 'connect', Jul 05 14:43:49 1905 volumio[21917]: address: '128.242.245.221', Jul 05 14:43:49 1905 volumio[21917]: port: 443 } Jul 05 14:43:49 1905 volumio[21917]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 14:43:50 1905 sudo[24605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-05 14:42 Jul 05 14:43:50 1905 sudo[24605]: pam_unix(sudo:session): session opened for user root by (uid=0) NAME="Ubuntu" VERSION="20.04.5 LTS (Focal Fossa)" ID=ubuntu ID_LIKE=debian PRETTY_NAME="Ubuntu 20.04.5 LTS" VERSION_ID="20.04" HOME_URL="https://www.ubuntu.com/" SUPPORT_URL="https://help.ubuntu.com/" BUG_REPORT_URL="https://bugs.launchpad.net/ubuntu/" PRIVACY_POLICY_URL="https://www.ubuntu.com/legal/terms-and-policies/privacy-policy" VERSION_CODENAME=focal UBUNTU_CODENAME=focal VOLUMIO_BUILD_VERSION="" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7"