-- Logs begin at Sun 2025-07-06 18:19:06 CST, end at Wed 2025-07-16 16:40:58 CST. -- Jul 16 16:39:00 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 16 16:39:00 volumio ntpd[809]: Soliciting pool server 84.16.67.12 Jul 16 16:39:00 volumio ntpd[809]: Soliciting pool server 193.182.111.12 Jul 16 16:39:01 volumio volumio[1180]: info: Preload queue cleared Jul 16 16:39:03 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 16 16:39:03 volumio volumio-remote-updater[611]: No test mode Jul 16 16:39:03 volumio volumio-remote-updater[611]: No alpha test mode Jul 16 16:39:03 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 16 16:39:03 volumio volumio[1180]: info: Preload queue cleared Jul 16 16:39:04 volumio volumio-remote-updater[611]: terminate called after throwing an instance of 'std::invalid_argument' Jul 16 16:39:04 volumio volumio-remote-updater[611]: what(): parse error - unexpected '<' Jul 16 16:39:04 volumio systemd[1]: volumio-remote-updater.service: Main process exited, code=killed, status=6/ABRT Jul 16 16:39:04 volumio systemd[1]: volumio-remote-updater.service: Failed with result 'signal'. Jul 16 16:39:06 volumio ntpd[809]: receive: Unexpected origin timestamp 0xec21e3a8.d50f7065 does not match aorg 0000000000.00000000 from server@84.16.67.12 xmt 0xec21e3aa.1826aac3 Jul 16 16:39:06 volumio ntpd[809]: receive: Unexpected origin timestamp 0xec21e3a8.d513d8b7 does not match aorg 0000000000.00000000 from server@193.182.111.142 xmt 0xec21e3aa.0d46d3ce Jul 16 16:39:06 volumio systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. Jul 16 16:39:06 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Jul 16 16:39:06 volumio systemd[1]: Stopped volumio-remote-updater.service. Jul 16 16:39:06 volumio systemd[1]: Started volumio-remote-updater.service. Jul 16 16:39:06 volumio volumio-remote-updater[1541]: Error: No active session Jul 16 16:39:06 volumio volumio-remote-updater[1541]: [2025-07-16 16:39:06] [connect] Successful connection Jul 16 16:39:06 volumio volumio-remote-updater[1541]: [2025-07-16 16:39:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1752655146 101 Jul 16 16:39:06 volumio volumio[1180]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Jul 16 16:39:08 volumio wpa_supplicant[892]: wlan0: Failed to initiate sched scan Jul 16 16:39:17 volumio wpa_supplicant[892]: wlan0: Failed to initiate sched scan Jul 16 16:39:25 volumio wpa_supplicant[892]: wlan0: Failed to initiate sched scan Jul 16 16:39:34 volumio wpa_supplicant[892]: wlan0: Failed to initiate sched scan Jul 16 16:39:42 volumio wpa_supplicant[892]: wlan0: Failed to initiate sched scan Jul 16 16:39:50 volumio wpa_supplicant[892]: wlan0: Failed to initiate sched scan Jul 16 16:39:59 volumio wpa_supplicant[892]: wlan0: Failed to initiate sched scan Jul 16 16:40:07 volumio wpa_supplicant[892]: wlan0: Failed to initiate sched scan Jul 16 16:40:16 volumio wpa_supplicant[892]: wlan0: Failed to initiate sched scan Jul 16 16:40:18 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 16 16:40:18 volumio volumio[1180]: info: Preload queue cleared Jul 16 16:40:21 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 16 16:40:22 volumio volumio[1180]: info: Preload queue cleared Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i62403 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i66313 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i88819 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i72396 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i72397 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i72399 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i72395 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i72398 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i72400 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i146571 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i122889 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i69694 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i75593 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i108664 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i133732 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i44992 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i98729 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i97494 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i67378 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i115459 Jul 16 16:40:22 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i93029 Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i62403 in service upnp_browser Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i66313 in service upnp_browser Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i88819 in service upnp_browser Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i72396 in service upnp_browser Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i72397 in service upnp_browser Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i72399 in service upnp_browser Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i72395 in service upnp_browser Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i72398 in service upnp_browser Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i72400 in service upnp_browser Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i146571 in service upnp_browser Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i122889 in service upnp_browser Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i69694 in service upnp_browser Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i75593 in service upnp_browser Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i108664 in service upnp_browser Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i133732 in service upnp_browser Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i44992 in service upnp_browser Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i98729 in service upnp_browser Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i97494 in service upnp_browser Jul 16 16:40:22 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i67378 in service upnp_browser Jul 16 16:40:23 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i115459 in service upnp_browser Jul 16 16:40:23 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935$*i93029 in service upnp_browser Jul 16 16:40:23 volumio volumio[1180]: info: Preload queue cleared Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::ClearQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::stop Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::clearPlayQueue Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::saveQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPushQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::addQueueItems Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::addQueueItems Jul 16 16:40:23 volumio volumio[1180]: info: Preload queue cleared Jul 16 16:40:23 volumio volumio[1180]: info: Adding Item to queue: upnp/folder/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935 Jul 16 16:40:23 volumio volumio[1180]: info: Exploding uri upnp/folder/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16935 in service upnp_browser Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPushQueue Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::saveQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::updateTrackBlock Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getTrackBlock Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPlay Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::play index 0 Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::stop Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::play index undefined Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::startPlaybackTimer Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 Jul 16 16:40:23 volumio volumio[1180]: info: [1752655223686] ControllerUPNPBrowser::clearAddPlayTrack Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand stop Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand stop took 4 milliseconds Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand clear Jul 16 16:40:23 volumio volumio[1180]: info: Jul 16 16:40:23 volumio volumio[1180]: ---------------------------- MPD announces system playlist update Jul 16 16:40:23 volumio volumio[1180]: info: Ignoring MPD Status Update Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand clear took 2 milliseconds Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.6.2:9790/minimserver/*/Music/2023.12.26/*5b*e8*80*81*e8*99*8e*e9*b1*bc*5dAYA*20*e3*80*8aAuthentic*20Audio*20Check*202*e3*80*8bCD2/2-01*20The*20Paperboys*20-*20Country*20Life.flac" Jul 16 16:40:23 volumio volumio[1180]: info: Jul 16 16:40:23 volumio volumio[1180]: ---------------------------- MPD announces system playlist update Jul 16 16:40:23 volumio volumio[1180]: info: Ignoring MPD Status Update Jul 16 16:40:23 volumio volumio[1180]: info: Jul 16 16:40:23 volumio volumio[1180]: ---------------------------- MPD announces system playlist update Jul 16 16:40:23 volumio volumio[1180]: info: Ignoring MPD Status Update Jul 16 16:40:23 volumio volumio[1180]: error: updateQueue error: null Jul 16 16:40:23 volumio volumio[1180]: info: ------------------------------ 6ms Jul 16 16:40:23 volumio volumio[1180]: error: Upnp client error: Error: This socket has been ended by the other party Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.6.2:9790/minimserver/*/Music/2023.12.26/*5b*e8*80*81*e8*99*8e*e9*b1*bc*5dAYA*20*e3*80*8aAuthentic*20Audio*20Check*202*e3*80*8bCD2/2-01*20The*20Paperboys*20-*20Country*20Life.flac" Jul 16 16:40:23 volumio volumio[1180]: error: updateQueue error: null Jul 16 16:40:23 volumio volumio[1180]: error: updateQueue error: null Jul 16 16:40:23 volumio volumio[1180]: info: Jul 16 16:40:23 volumio volumio[1180]: ---------------------------- MPD announces system playlist update Jul 16 16:40:23 volumio volumio[1180]: info: Ignoring MPD Status Update Jul 16 16:40:23 volumio volumio[1180]: info: ------------------------------ 41ms Jul 16 16:40:23 volumio volumio[1180]: info: ------------------------------ 40ms Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand add "http://192.168.6.2:9790/minimserver/*/Music/2023.12.26/*5b*e8*80*81*e8*99*8e*e9*b1*bc*5dAYA*20*e3*80*8aAuthentic*20Audio*20Check*202*e3*80*8bCD2/2-01*20The*20Paperboys*20-*20Country*20Life.flac" took 2 milliseconds Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand play Jul 16 16:40:23 volumio volumio[1180]: info: Jul 16 16:40:23 volumio volumio[1180]: ---------------------------- MPD announces system playlist update Jul 16 16:40:23 volumio volumio[1180]: info: Ignoring MPD Status Update Jul 16 16:40:23 volumio volumio[1180]: info: Jul 16 16:40:23 volumio volumio[1180]: ---------------------------- MPD announces system playlist update Jul 16 16:40:23 volumio volumio[1180]: info: Ignoring MPD Status Update Jul 16 16:40:23 volumio volumio[1180]: info: ------------------------------ 6ms Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand play took 4 milliseconds Jul 16 16:40:23 volumio volumio[1180]: info: ------------------------------ 4ms Jul 16 16:40:23 volumio volumio[1180]: info: ------------------------------ 3ms Jul 16 16:40:23 volumio volumio[1180]: info: Jul 16 16:40:23 volumio volumio[1180]: ---------------------------- MPD announces state update: player Jul 16 16:40:23 volumio volumio[1180]: info: ControllerMpd::getState Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jul 16 16:40:23 volumio volumio[1180]: info: Jul 16 16:40:23 volumio volumio[1180]: ---------------------------- MPD announces state update: player Jul 16 16:40:23 volumio volumio[1180]: info: ControllerMpd::getState Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jul 16 16:40:23 volumio volumio[1180]: info: Jul 16 16:40:23 volumio volumio[1180]: ---------------------------- MPD announces state update: player Jul 16 16:40:23 volumio volumio[1180]: info: ControllerMpd::getState Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand status took 5 milliseconds Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::parseState Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 16:40:23 volumio volumio[1180]: info: Jul 16 16:40:23 volumio volumio[1180]: ---------------------------- MPD announces state update: player Jul 16 16:40:23 volumio volumio[1180]: info: ControllerMpd::getState Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jul 16 16:40:23 volumio volumio[1180]: info: Jul 16 16:40:23 volumio volumio[1180]: ---------------------------- MPD announces state update: player Jul 16 16:40:23 volumio volumio[1180]: info: ControllerMpd::getState Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jul 16 16:40:23 volumio volumio[1180]: info: Jul 16 16:40:23 volumio volumio[1180]: ---------------------------- MPD announces state update: player Jul 16 16:40:23 volumio volumio[1180]: info: Jul 16 16:40:23 volumio volumio[1180]: ---------------------------- MPD announces system playlist update Jul 16 16:40:23 volumio volumio[1180]: info: Ignoring MPD Status Update Jul 16 16:40:23 volumio volumio[1180]: info: Jul 16 16:40:23 volumio volumio[1180]: ---------------------------- MPD announces state update: player Jul 16 16:40:23 volumio volumio[1180]: info: ControllerMpd::getState Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand status took 13 milliseconds Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand status took 11 milliseconds Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 16 16:40:23 volumio volumio[1180]: info: ControllerMpd::getState Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand status took 5 milliseconds Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::parseState Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::parseState Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::parseState Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 16:40:23 volumio volumio[1180]: info: ControllerMpd::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::servicePushState Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 Jul 16 16:40:23 volumio volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2-01*20The*20Paperboys*20-*20Country*20Life.flac","artist":null,"album":null,"uri":"http://192.168.6.2:9790/minimserver/*/Music/2023.12.26/*5b*e8*80*81*e8*99*8e*e9*b1*bc*5dAYA*20*e3*80*8aAuthentic*20Audio*20Check*202*e3*80*8bCD2/2-01*20The*20Paperboys*20-*20Country*20Life.flac","trackType":"flac"} Jul 16 16:40:23 volumio volumio[1180]: verbose: CURRENT POSITION 0 Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::syncState stateService play Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::syncState currentStatus stop Jul 16 16:40:23 volumio volumio[1180]: info: ------------------------------ 26ms Jul 16 16:40:23 volumio volumio[1180]: info: Jul 16 16:40:23 volumio volumio[1180]: ---------------------------- MPD announces system playlist update Jul 16 16:40:23 volumio volumio[1180]: info: Ignoring MPD Status Update Jul 16 16:40:23 volumio volumio[1180]: info: Jul 16 16:40:23 volumio volumio[1180]: ---------------------------- MPD announces state update: player Jul 16 16:40:23 volumio volumio[1180]: info: ControllerMpd::getState Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jul 16 16:40:23 volumio volumio[1180]: info: Jul 16 16:40:23 volumio volumio[1180]: ---------------------------- MPD announces system playlist update Jul 16 16:40:23 volumio volumio[1180]: info: Ignoring MPD Status Update Jul 16 16:40:23 volumio volumio[1180]: info: Jul 16 16:40:23 volumio volumio[1180]: ---------------------------- MPD announces state update: player Jul 16 16:40:23 volumio volumio[1180]: info: ControllerMpd::getState Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand status Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand status took 18 milliseconds Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand status took 15 milliseconds Jul 16 16:40:23 volumio volumio[1180]: info: ------------------------------ 16ms Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand status took 15 milliseconds Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand playlistinfo took 14 milliseconds Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand playlistinfo took 13 milliseconds Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 16 16:40:23 volumio volumio[1180]: info: ------------------------------ 7ms Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand status took 6 milliseconds Jul 16 16:40:23 volumio volumio[1180]: info: ------------------------------ 6ms Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::parseState Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::parseState Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::parseState Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::parseState Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 16:40:23 volumio volumio[1180]: info: ControllerMpd::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::servicePushState Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 Jul 16 16:40:23 volumio volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Country Life","artist":"The Paperboys","album":"AYA Authentic Audio Check 2","uri":"http://192.168.6.2:9790/minimserver/*/Music/2023.12.26/*5b*e8*80*81*e8*99*8e*e9*b1*bc*5dAYA*20*e3*80*8aAuthentic*20Audio*20Check*202*e3*80*8bCD2/2-01*20The*20Paperboys*20-*20Country*20Life.flac","trackType":"flac"} Jul 16 16:40:23 volumio volumio[1180]: verbose: CURRENT POSITION 0 Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::syncState stateService play Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::syncState currentStatus play Jul 16 16:40:23 volumio volumio[1180]: info: Received an update from plugin. extracting info from payload Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState Jul 16 16:40:23 volumio volumio[1180]: info: ControllerMpd::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::servicePushState Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 Jul 16 16:40:23 volumio volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Country Life","artist":"The Paperboys","album":"AYA Authentic Audio Check 2","uri":"http://192.168.6.2:9790/minimserver/*/Music/2023.12.26/*5b*e8*80*81*e8*99*8e*e9*b1*bc*5dAYA*20*e3*80*8aAuthentic*20Audio*20Check*202*e3*80*8bCD2/2-01*20The*20Paperboys*20-*20Country*20Life.flac","trackType":"flac"} Jul 16 16:40:23 volumio volumio[1180]: verbose: CURRENT POSITION 0 Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::syncState stateService play Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::syncState currentStatus play Jul 16 16:40:23 volumio volumio[1180]: info: Received an update from plugin. extracting info from payload Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState Jul 16 16:40:23 volumio volumio[1180]: info: ControllerMpd::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::servicePushState Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 Jul 16 16:40:23 volumio volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"913 Kbps","isStreaming":false,"title":"Country Life","artist":"The Paperboys","album":"AYA Authentic Audio Check 2","uri":"http://192.168.6.2:9790/minimserver/*/Music/2023.12.26/*5b*e8*80*81*e8*99*8e*e9*b1*bc*5dAYA*20*e3*80*8aAuthentic*20Audio*20Check*202*e3*80*8bCD2/2-01*20The*20Paperboys*20-*20Country*20Life.flac","trackType":"flac"} Jul 16 16:40:23 volumio volumio[1180]: verbose: CURRENT POSITION 0 Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::syncState stateService play Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::syncState currentStatus play Jul 16 16:40:23 volumio volumio[1180]: info: Received an update from plugin. extracting info from payload Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState Jul 16 16:40:23 volumio volumio[1180]: info: ------------------------------ 73ms Jul 16 16:40:23 volumio volumio[1180]: info: ------------------------------ 71ms Jul 16 16:40:23 volumio volumio[1180]: info: ------------------------------ 66ms Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand status took 49 milliseconds Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand playlistinfo took 45 milliseconds Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand playlistinfo took 43 milliseconds Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::parseState Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jul 16 16:40:23 volumio volumio[1180]: info: ControllerMpd::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::servicePushState Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 Jul 16 16:40:23 volumio volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1249 Kbps","isStreaming":false,"title":"Country Life","artist":"The Paperboys","album":"AYA Authentic Audio Check 2","uri":"http://192.168.6.2:9790/minimserver/*/Music/2023.12.26/*5b*e8*80*81*e8*99*8e*e9*b1*bc*5dAYA*20*e3*80*8aAuthentic*20Audio*20Check*202*e3*80*8bCD2/2-01*20The*20Paperboys*20-*20Country*20Life.flac","trackType":"flac"} Jul 16 16:40:23 volumio volumio[1180]: verbose: CURRENT POSITION 0 Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::syncState stateService play Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::syncState currentStatus play Jul 16 16:40:23 volumio volumio[1180]: info: Received an update from plugin. extracting info from payload Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState Jul 16 16:40:23 volumio volumio[1180]: info: ControllerMpd::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::servicePushState Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 Jul 16 16:40:23 volumio volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1287 Kbps","isStreaming":false,"title":"Country Life","artist":"The Paperboys","album":"AYA Authentic Audio Check 2","uri":"http://192.168.6.2:9790/minimserver/*/Music/2023.12.26/*5b*e8*80*81*e8*99*8e*e9*b1*bc*5dAYA*20*e3*80*8aAuthentic*20Audio*20Check*202*e3*80*8bCD2/2-01*20The*20Paperboys*20-*20Country*20Life.flac","trackType":"flac"} Jul 16 16:40:23 volumio volumio[1180]: verbose: CURRENT POSITION 0 Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::syncState stateService play Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::syncState currentStatus play Jul 16 16:40:23 volumio volumio[1180]: info: Received an update from plugin. extracting info from payload Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState Jul 16 16:40:23 volumio volumio[1180]: info: ControllerMpd::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::servicePushState Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 Jul 16 16:40:23 volumio volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1287 Kbps","isStreaming":false,"title":"Country Life","artist":"The Paperboys","album":"AYA Authentic Audio Check 2","uri":"http://192.168.6.2:9790/minimserver/*/Music/2023.12.26/*5b*e8*80*81*e8*99*8e*e9*b1*bc*5dAYA*20*e3*80*8aAuthentic*20Audio*20Check*202*e3*80*8bCD2/2-01*20The*20Paperboys*20-*20Country*20Life.flac","trackType":"flac"} Jul 16 16:40:23 volumio volumio[1180]: verbose: CURRENT POSITION 0 Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::syncState stateService play Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::syncState currentStatus play Jul 16 16:40:23 volumio volumio[1180]: info: Received an update from plugin. extracting info from payload Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState Jul 16 16:40:23 volumio volumio[1180]: info: ControllerMpd::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::servicePushState Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 Jul 16 16:40:23 volumio volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1287 Kbps","isStreaming":false,"title":"Country Life","artist":"The Paperboys","album":"AYA Authentic Audio Check 2","uri":"http://192.168.6.2:9790/minimserver/*/Music/2023.12.26/*5b*e8*80*81*e8*99*8e*e9*b1*bc*5dAYA*20*e3*80*8aAuthentic*20Audio*20Check*202*e3*80*8bCD2/2-01*20The*20Paperboys*20-*20Country*20Life.flac","trackType":"flac"} Jul 16 16:40:23 volumio volumio[1180]: verbose: CURRENT POSITION 0 Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::syncState stateService play Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::syncState currentStatus play Jul 16 16:40:23 volumio volumio[1180]: info: Received an update from plugin. extracting info from payload Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState Jul 16 16:40:23 volumio volumio[1180]: info: ------------------------------ 124ms Jul 16 16:40:23 volumio volumio[1180]: info: ------------------------------ 123ms Jul 16 16:40:23 volumio volumio[1180]: info: ------------------------------ 122ms Jul 16 16:40:23 volumio volumio[1180]: info: ------------------------------ 111ms Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioGetQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioGetQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioGetQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioGetQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioGetQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioGetQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioGetQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioGetQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioGetQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioGetQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioGetQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioGetQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioGetQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioGetQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: sendMpdCommand playlistinfo took 150 milliseconds Jul 16 16:40:23 volumio volumio[1180]: verbose: ControllerMpd::parseTrackInfo Jul 16 16:40:23 volumio volumio[1180]: info: ControllerMpd::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::servicePushState Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 Jul 16 16:40:23 volumio volumio[1180]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":253,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1287 Kbps","isStreaming":false,"title":"Country Life","artist":"The Paperboys","album":"AYA Authentic Audio Check 2","uri":"http://192.168.6.2:9790/minimserver/*/Music/2023.12.26/*5b*e8*80*81*e8*99*8e*e9*b1*bc*5dAYA*20*e3*80*8aAuthentic*20Audio*20Check*202*e3*80*8bCD2/2-01*20The*20Paperboys*20-*20Country*20Life.flac","trackType":"flac"} Jul 16 16:40:23 volumio volumio[1180]: verbose: CURRENT POSITION 0 Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::syncState stateService play Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::syncState currentStatus play Jul 16 16:40:23 volumio volumio[1180]: info: Received an update from plugin. extracting info from payload Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::pushState Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState Jul 16 16:40:23 volumio volumio[1180]: info: ------------------------------ 221ms Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioGetQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreCommandRouter::volumioGetQueue Jul 16 16:40:23 volumio volumio[1180]: info: CoreStateMachine::getQueue Jul 16 16:40:23 volumio volumio[1180]: info: CorePlayQueue::getQueue Jul 16 16:40:24 volumio wpa_supplicant[892]: wlan0: Failed to initiate sched scan Jul 16 16:40:32 volumio wpa_supplicant[892]: wlan0: Failed to initiate sched scan Jul 16 16:40:33 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 16 16:40:33 volumio volumio[1180]: info: Preload queue cleared Jul 16 16:40:33 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i138137 Jul 16 16:40:33 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i77216 Jul 16 16:40:33 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i119552 Jul 16 16:40:33 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i123720 Jul 16 16:40:33 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i147974 Jul 16 16:40:33 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i100140 Jul 16 16:40:33 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i148118 Jul 16 16:40:33 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i119455 Jul 16 16:40:33 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i148180 Jul 16 16:40:33 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i85285 Jul 16 16:40:33 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i53116 Jul 16 16:40:33 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i122533 Jul 16 16:40:33 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i138994 Jul 16 16:40:33 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i67775 Jul 16 16:40:33 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i138137 in service upnp_browser Jul 16 16:40:33 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i77216 in service upnp_browser Jul 16 16:40:33 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i119552 in service upnp_browser Jul 16 16:40:33 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i123720 in service upnp_browser Jul 16 16:40:33 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i147974 in service upnp_browser Jul 16 16:40:33 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i100140 in service upnp_browser Jul 16 16:40:33 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i148118 in service upnp_browser Jul 16 16:40:33 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i119455 in service upnp_browser Jul 16 16:40:33 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i148180 in service upnp_browser Jul 16 16:40:33 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i85285 in service upnp_browser Jul 16 16:40:33 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i53116 in service upnp_browser Jul 16 16:40:33 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i122533 in service upnp_browser Jul 16 16:40:33 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i138994 in service upnp_browser Jul 16 16:40:33 volumio volumio[1180]: info: Exploding uri upnp/http://192.168.6.2:9791/5ba8df5c-92ed-4668-86c8-0f92691b97ca/upnp.org-ContentDirectory-1/control@0$folders$f16927$f16930$*i67775 in service upnp_browser Jul 16 16:40:41 volumio wpa_supplicant[892]: wlan0: Failed to initiate sched scan Jul 16 16:40:49 volumio wpa_supplicant[892]: wlan0: Failed to initiate sched scan Jul 16 16:40:58 volumio wpa_supplicant[892]: wlan0: Failed to initiate sched scan Jul 16 16:40:58 volumio volumio[1180]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 16 16:40:58 volumio volumio[1180]: Error: connect ETIMEDOUT 157.240.16.50:80 Jul 16 16:40:58 volumio volumio[1180]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jul 16 16:40:58 volumio volumio[1180]: errno: -110, Jul 16 16:40:58 volumio volumio[1180]: code: 'ETIMEDOUT', Jul 16 16:40:58 volumio volumio[1180]: syscall: 'connect', Jul 16 16:40:58 volumio volumio[1180]: address: '157.240.16.50', Jul 16 16:40:58 volumio volumio[1180]: port: 80 Jul 16 16:40:58 volumio volumio[1180]: } Jul 16 16:40:58 volumio volumio[1180]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 16 16:40:58 volumio sudo[1880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-16 16:39 Jul 16 16:40:58 volumio sudo[1880]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"