-- Logs begin at Fri 2024-10-04 17:42:57 CDT, end at Mon 2024-10-07 21:58:39 CDT. -- Oct 07 21:57:02 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 07 21:57:02 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 21:57:02 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 21:57:02 volumio volumio[1025]: info: Discovery: Getting this device information Oct 07 21:57:02 volumio volumio[1025]: info: CoreCommandRouter::volumioGetState Oct 07 21:57:02 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:02 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 21:57:03 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 07 21:57:03 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand rescan Oct 07 21:57:03 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:03 volumio volumio[1025]: info: Oct 07 21:57:03 volumio volumio[1025]: ---------------------------- MPD announces state update: update Oct 07 21:57:03 volumio volumio[1025]: info: sendMpdCommand rescan took 7 milliseconds Oct 07 21:57:03 volumio volumio[1025]: info: ControllerMpd::getState Oct 07 21:57:03 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:03 volumio volumio[1025]: info: sendMpdCommand status took 8 milliseconds Oct 07 21:57:03 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:03 volumio volumio[1025]: info: Command Router : Notfying DB Updatetrue Oct 07 21:57:03 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:03 volumio volumio[1025]: info: Oct 07 21:57:03 volumio volumio[1025]: ---------------------------- MPD announces state update: update Oct 07 21:57:03 volumio volumio[1025]: info: ControllerMpd::getState Oct 07 21:57:03 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:03 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:03 volumio volumio[1025]: info: Oct 07 21:57:03 volumio volumio[1025]: ---------------------------- MPD announces state update: update Oct 07 21:57:03 volumio volumio[1025]: info: ControllerMpd::getState Oct 07 21:57:03 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:03 volumio volumio[1025]: info: sendMpdCommand status took 125 milliseconds Oct 07 21:57:03 volumio volumio[1025]: info: sendMpdCommand status took 14 milliseconds Oct 07 21:57:03 volumio volumio[1025]: info: sendMpdCommand status took 11 milliseconds Oct 07 21:57:03 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:03 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:03 volumio volumio[1025]: info: Command Router : Notfying DB Updatetrue Oct 07 21:57:03 volumio volumio[1025]: info: CoreCommandRouter::Close All Modals sent Oct 07 21:57:03 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:03 volumio volumio[1025]: info: ControllerMpd::pushState Oct 07 21:57:03 volumio volumio[1025]: info: CoreCommandRouter::servicePushState Oct 07 21:57:03 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:03 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:03 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:03 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:03 volumio volumio[1025]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 07 21:57:03 volumio volumio[1025]: verbose: CURRENT POSITION 0 Oct 07 21:57:03 volumio volumio[1025]: info: CoreStateMachine::syncState stateService stop Oct 07 21:57:03 volumio volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Oct 07 21:57:03 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:03 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:03 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 21:57:03 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:03 volumio volumio[1025]: info: No code Oct 07 21:57:03 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:03 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:03 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:03 volumio volumio[1025]: info: ControllerMpd::pushState Oct 07 21:57:03 volumio volumio[1025]: info: CoreCommandRouter::servicePushState Oct 07 21:57:03 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:03 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:03 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:03 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:03 volumio volumio[1025]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 07 21:57:03 volumio volumio[1025]: verbose: CURRENT POSITION 0 Oct 07 21:57:03 volumio volumio[1025]: info: CoreStateMachine::syncState stateService stop Oct 07 21:57:03 volumio volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Oct 07 21:57:03 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:03 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:03 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:03 volumio volumio[1025]: info: No code Oct 07 21:57:03 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:03 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:03 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:03 volumio volumio[1025]: info: ------------------------------ 304ms Oct 07 21:57:03 volumio volumio[1025]: info: ------------------------------ 252ms Oct 07 21:57:03 volumio volumio[1025]: info: sendMpdCommand status took 283 milliseconds Oct 07 21:57:03 volumio volumio[1025]: info: sendMpdCommand status took 279 milliseconds Oct 07 21:57:03 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:03 volumio volumio[1025]: info: Command Router : Notfying DB Updatetrue Oct 07 21:57:03 volumio volumio[1025]: info: CoreCommandRouter::Close All Modals sent Oct 07 21:57:03 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:03 volumio volumio[1025]: info: ControllerMpd::pushState Oct 07 21:57:03 volumio volumio[1025]: info: CoreCommandRouter::servicePushState Oct 07 21:57:04 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:04 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:04 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:04 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:04 volumio volumio[1025]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 07 21:57:04 volumio volumio[1025]: verbose: CURRENT POSITION 0 Oct 07 21:57:04 volumio volumio[1025]: info: CoreStateMachine::syncState stateService stop Oct 07 21:57:04 volumio volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Oct 07 21:57:04 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:04 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:04 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:04 volumio volumio[1025]: info: No code Oct 07 21:57:04 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:04 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:04 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:04 volumio volumio[1025]: info: ------------------------------ 425ms Oct 07 21:57:06 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 07 21:57:08 volumio volumio[1025]: info: Disabling MyMusic plugin upnp Oct 07 21:57:08 volumio sudo[2636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 07 21:57:08 volumio sudo[2636]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 21:57:08 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 07 21:57:08 volumio systemd[1]: upmpdcli.service: Succeeded. Oct 07 21:57:08 volumio systemd[1]: Stopped UPnP Renderer front-end to MPD. Oct 07 21:57:08 volumio sudo[2636]: pam_unix(sudo:session): session closed for user root Oct 07 21:57:08 volumio volumio[1025]: info: Disabling plugin upnp Oct 07 21:57:08 volumio volumio[1025]: info: Done. Oct 07 21:57:09 volumio volumio[1025]: info: Enabling MyMusic plugin upnp Oct 07 21:57:09 volumio volumio[1025]: info: Enabling plugin upnp Oct 07 21:57:09 volumio volumio[1025]: info: Loading plugin "upnp"... Oct 07 21:57:09 volumio volumio[1025]: info: [1728356229957] Starting Upmpd Daemon Oct 07 21:57:09 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 07 21:57:09 volumio volumio[1025]: info: Done. Oct 07 21:57:10 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 07 21:57:13 volumio volumio[1025]: info: MPD Database updated - AlbumList cache refreshed Oct 07 21:57:13 volumio volumio[1025]: info: Oct 07 21:57:13 volumio volumio[1025]: ---------------------------- MPD announces state update: database Oct 07 21:57:13 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:13 volumio volumio[1025]: info: Oct 07 21:57:13 volumio volumio[1025]: ---------------------------- MPD announces state update: update Oct 07 21:57:13 volumio volumio[1025]: info: ControllerMpd::getState Oct 07 21:57:13 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:13 volumio volumio[1025]: info: ControllerMpd::getState Oct 07 21:57:13 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:13 volumio volumio[1025]: info: MPD Database updated - AlbumList cache refreshed Oct 07 21:57:13 volumio volumio[1025]: info: Oct 07 21:57:13 volumio volumio[1025]: ---------------------------- MPD announces state update: database Oct 07 21:57:13 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:13 volumio volumio[1025]: info: Oct 07 21:57:13 volumio volumio[1025]: ---------------------------- MPD announces state update: update Oct 07 21:57:13 volumio volumio[1025]: info: ControllerMpd::getState Oct 07 21:57:13 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:13 volumio volumio[1025]: info: ControllerMpd::getState Oct 07 21:57:13 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:13 volumio volumio[1025]: info: MPD Database updated - AlbumList cache refreshed Oct 07 21:57:13 volumio volumio[1025]: info: Oct 07 21:57:13 volumio volumio[1025]: ---------------------------- MPD announces state update: database Oct 07 21:57:13 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:13 volumio volumio[1025]: info: Oct 07 21:57:13 volumio volumio[1025]: ---------------------------- MPD announces state update: update Oct 07 21:57:13 volumio volumio[1025]: info: ControllerMpd::getState Oct 07 21:57:13 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:13 volumio volumio[1025]: info: ControllerMpd::getState Oct 07 21:57:13 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:13 volumio volumio[1025]: info: sendMpdCommand status took 415 milliseconds Oct 07 21:57:13 volumio volumio[1025]: info: sendMpdCommand status took 413 milliseconds Oct 07 21:57:13 volumio volumio[1025]: info: sendMpdCommand status took 414 milliseconds Oct 07 21:57:13 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:13 volumio volumio[1025]: info: Command Router : Notfying DB Updatefalse Oct 07 21:57:13 volumio volumio[1025]: info: CoreCommandRouter::Close All Modals sent Oct 07 21:57:13 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:13 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:13 volumio volumio[1025]: info: ControllerMpd::pushState Oct 07 21:57:13 volumio volumio[1025]: info: CoreCommandRouter::servicePushState Oct 07 21:57:13 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:13 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:13 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:13 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:13 volumio volumio[1025]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 07 21:57:13 volumio volumio[1025]: verbose: CURRENT POSITION 0 Oct 07 21:57:13 volumio volumio[1025]: info: CoreStateMachine::syncState stateService stop Oct 07 21:57:13 volumio volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Oct 07 21:57:13 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:13 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:13 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 21:57:13 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:13 volumio volumio[1025]: info: No code Oct 07 21:57:13 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:13 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:13 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:13 volumio volumio[1025]: info: ControllerMpd::pushState Oct 07 21:57:13 volumio volumio[1025]: info: CoreCommandRouter::servicePushState Oct 07 21:57:13 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:13 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:13 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:13 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:13 volumio volumio[1025]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 07 21:57:13 volumio volumio[1025]: verbose: CURRENT POSITION 0 Oct 07 21:57:13 volumio volumio[1025]: info: CoreStateMachine::syncState stateService stop Oct 07 21:57:13 volumio volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Oct 07 21:57:13 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:13 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:13 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:13 volumio volumio[1025]: info: No code Oct 07 21:57:13 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:13 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:13 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:13 volumio volumio[1025]: info: ------------------------------ 636ms Oct 07 21:57:13 volumio volumio[1025]: info: ------------------------------ 637ms Oct 07 21:57:13 volumio volumio[1025]: info: sendMpdCommand status took 882 milliseconds Oct 07 21:57:13 volumio volumio[1025]: info: sendMpdCommand status took 882 milliseconds Oct 07 21:57:13 volumio volumio[1025]: info: sendMpdCommand status took 880 milliseconds Oct 07 21:57:13 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:13 volumio volumio[1025]: info: Command Router : Notfying DB Updatefalse Oct 07 21:57:13 volumio volumio[1025]: info: CoreCommandRouter::Close All Modals sent Oct 07 21:57:13 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:13 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:13 volumio volumio[1025]: info: ControllerMpd::pushState Oct 07 21:57:13 volumio volumio[1025]: info: CoreCommandRouter::servicePushState Oct 07 21:57:13 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:13 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:13 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:13 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:13 volumio volumio[1025]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 07 21:57:13 volumio volumio[1025]: verbose: CURRENT POSITION 0 Oct 07 21:57:13 volumio volumio[1025]: info: CoreStateMachine::syncState stateService stop Oct 07 21:57:13 volumio volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Oct 07 21:57:13 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:13 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:13 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:13 volumio volumio[1025]: info: No code Oct 07 21:57:13 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:13 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:13 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:13 volumio volumio[1025]: info: ControllerMpd::pushState Oct 07 21:57:13 volumio volumio[1025]: info: CoreCommandRouter::servicePushState Oct 07 21:57:14 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:14 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:14 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:14 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:14 volumio volumio[1025]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 07 21:57:14 volumio volumio[1025]: verbose: CURRENT POSITION 0 Oct 07 21:57:14 volumio volumio[1025]: info: CoreStateMachine::syncState stateService stop Oct 07 21:57:14 volumio volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Oct 07 21:57:14 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:14 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:14 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:14 volumio volumio[1025]: info: No code Oct 07 21:57:14 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:14 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:14 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:14 volumio volumio[1025]: info: ------------------------------ 1097ms Oct 07 21:57:14 volumio volumio[1025]: info: ------------------------------ 1097ms Oct 07 21:57:14 volumio volumio[1025]: info: sendMpdCommand status took 1349 milliseconds Oct 07 21:57:14 volumio volumio[1025]: info: sendMpdCommand status took 1349 milliseconds Oct 07 21:57:14 volumio volumio[1025]: info: sendMpdCommand status took 1348 milliseconds Oct 07 21:57:14 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:14 volumio volumio[1025]: info: Command Router : Notfying DB Updatefalse Oct 07 21:57:14 volumio volumio[1025]: info: CoreCommandRouter::Close All Modals sent Oct 07 21:57:14 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:14 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:14 volumio volumio[1025]: info: ControllerMpd::pushState Oct 07 21:57:14 volumio volumio[1025]: info: CoreCommandRouter::servicePushState Oct 07 21:57:14 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:14 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:14 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:14 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:14 volumio volumio[1025]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 07 21:57:14 volumio volumio[1025]: verbose: CURRENT POSITION 0 Oct 07 21:57:14 volumio volumio[1025]: info: CoreStateMachine::syncState stateService stop Oct 07 21:57:14 volumio volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Oct 07 21:57:14 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:14 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:14 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:14 volumio volumio[1025]: info: No code Oct 07 21:57:14 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:14 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:14 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:14 volumio volumio[1025]: info: ControllerMpd::pushState Oct 07 21:57:14 volumio volumio[1025]: info: CoreCommandRouter::servicePushState Oct 07 21:57:14 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:14 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:14 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:14 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:14 volumio volumio[1025]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 07 21:57:14 volumio volumio[1025]: verbose: CURRENT POSITION 0 Oct 07 21:57:14 volumio volumio[1025]: info: CoreStateMachine::syncState stateService stop Oct 07 21:57:14 volumio volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Oct 07 21:57:14 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:14 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:14 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:14 volumio volumio[1025]: info: No code Oct 07 21:57:14 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:14 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:14 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:14 volumio volumio[1025]: info: ------------------------------ 1465ms Oct 07 21:57:14 volumio volumio[1025]: info: ------------------------------ 1464ms Oct 07 21:57:14 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 07 21:57:18 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 07 21:57:20 volumio sudo[2652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 07 21:57:20 volumio sudo[2652]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 21:57:20 volumio sudo[2652]: pam_unix(sudo:session): session closed for user root Oct 07 21:57:20 volumio sudo[2655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 07 21:57:20 volumio sudo[2655]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 21:57:20 volumio sudo[2655]: pam_unix(sudo:session): session closed for user root Oct 07 21:57:20 volumio sudo[2658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 07 21:57:20 volumio sudo[2658]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 07 21:57:20 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 07 21:57:20 volumio sudo[2658]: pam_unix(sudo:session): session closed for user root Oct 07 21:57:20 volumio volumio[1025]: info: Upmpdcli Daemon Started Oct 07 21:57:20 volumio volumio[2660]: writing RSA key Oct 07 21:57:22 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 07 21:57:22 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 07 21:57:22 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand rescan Oct 07 21:57:22 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:22 volumio volumio[1025]: info: Oct 07 21:57:22 volumio volumio[1025]: ---------------------------- MPD announces state update: update Oct 07 21:57:22 volumio volumio[1025]: info: sendMpdCommand rescan took 5 milliseconds Oct 07 21:57:22 volumio volumio[1025]: info: ControllerMpd::getState Oct 07 21:57:22 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:22 volumio volumio[1025]: info: sendMpdCommand status took 3 milliseconds Oct 07 21:57:22 volumio volumio[1025]: info: sendMpdCommand status took 1 milliseconds Oct 07 21:57:22 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:22 volumio volumio[1025]: info: Command Router : Notfying DB Updatetrue Oct 07 21:57:22 volumio volumio[1025]: info: CoreCommandRouter::Close All Modals sent Oct 07 21:57:22 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:22 volumio volumio[1025]: info: ControllerMpd::pushState Oct 07 21:57:22 volumio volumio[1025]: info: CoreCommandRouter::servicePushState Oct 07 21:57:22 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:22 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:22 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:22 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:22 volumio volumio[1025]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 07 21:57:22 volumio volumio[1025]: verbose: CURRENT POSITION 0 Oct 07 21:57:22 volumio volumio[1025]: info: CoreStateMachine::syncState stateService stop Oct 07 21:57:22 volumio volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Oct 07 21:57:22 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:22 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:22 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 21:57:22 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:22 volumio volumio[1025]: info: No code Oct 07 21:57:22 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:22 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:22 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:22 volumio volumio[1025]: info: ------------------------------ 163ms Oct 07 21:57:22 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:22 volumio volumio[1025]: info: Oct 07 21:57:22 volumio volumio[1025]: ---------------------------- MPD announces state update: update Oct 07 21:57:22 volumio volumio[1025]: info: ControllerMpd::getState Oct 07 21:57:22 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:22 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:22 volumio volumio[1025]: info: Oct 07 21:57:22 volumio volumio[1025]: ---------------------------- MPD announces state update: update Oct 07 21:57:22 volumio volumio[1025]: info: ControllerMpd::getState Oct 07 21:57:22 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:22 volumio volumio[1025]: info: sendMpdCommand status took 7 milliseconds Oct 07 21:57:22 volumio volumio[1025]: info: sendMpdCommand status took 6 milliseconds Oct 07 21:57:22 volumio volumio[1025]: info: sendMpdCommand status took 4 milliseconds Oct 07 21:57:22 volumio volumio[1025]: info: sendMpdCommand status took 4 milliseconds Oct 07 21:57:22 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:23 volumio volumio[1025]: info: Command Router : Notfying DB Updatetrue Oct 07 21:57:23 volumio volumio[1025]: info: CoreCommandRouter::Close All Modals sent Oct 07 21:57:23 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:23 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:23 volumio volumio[1025]: info: Command Router : Notfying DB Updatetrue Oct 07 21:57:23 volumio volumio[1025]: info: CoreCommandRouter::Close All Modals sent Oct 07 21:57:23 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:23 volumio volumio[1025]: info: ControllerMpd::pushState Oct 07 21:57:23 volumio volumio[1025]: info: CoreCommandRouter::servicePushState Oct 07 21:57:23 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:23 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:23 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:23 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:23 volumio volumio[1025]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 07 21:57:23 volumio volumio[1025]: verbose: CURRENT POSITION 0 Oct 07 21:57:23 volumio volumio[1025]: info: CoreStateMachine::syncState stateService stop Oct 07 21:57:23 volumio volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Oct 07 21:57:23 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:23 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:23 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:23 volumio volumio[1025]: info: No code Oct 07 21:57:23 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:23 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:23 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:23 volumio volumio[1025]: info: ControllerMpd::pushState Oct 07 21:57:23 volumio volumio[1025]: info: CoreCommandRouter::servicePushState Oct 07 21:57:23 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:23 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:23 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:23 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:23 volumio volumio[1025]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 07 21:57:23 volumio volumio[1025]: verbose: CURRENT POSITION 0 Oct 07 21:57:23 volumio volumio[1025]: info: CoreStateMachine::syncState stateService stop Oct 07 21:57:23 volumio volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Oct 07 21:57:23 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:23 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:23 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:23 volumio volumio[1025]: info: No code Oct 07 21:57:23 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:23 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:23 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:23 volumio volumio[1025]: info: ------------------------------ 279ms Oct 07 21:57:23 volumio volumio[1025]: info: ------------------------------ 276ms Oct 07 21:57:24 volumio volumio[1025]: info: MPD Database updated - AlbumList cache refreshed Oct 07 21:57:24 volumio volumio[1025]: info: Oct 07 21:57:24 volumio volumio[1025]: ---------------------------- MPD announces state update: database Oct 07 21:57:24 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:24 volumio volumio[1025]: info: Oct 07 21:57:24 volumio volumio[1025]: ---------------------------- MPD announces state update: update Oct 07 21:57:24 volumio volumio[1025]: info: ControllerMpd::getState Oct 07 21:57:24 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:24 volumio volumio[1025]: info: ControllerMpd::getState Oct 07 21:57:24 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:24 volumio volumio[1025]: info: MPD Database updated - AlbumList cache refreshed Oct 07 21:57:24 volumio volumio[1025]: info: Oct 07 21:57:24 volumio volumio[1025]: ---------------------------- MPD announces state update: database Oct 07 21:57:24 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:24 volumio volumio[1025]: info: Oct 07 21:57:24 volumio volumio[1025]: ---------------------------- MPD announces state update: update Oct 07 21:57:24 volumio volumio[1025]: info: ControllerMpd::getState Oct 07 21:57:24 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:24 volumio volumio[1025]: info: ControllerMpd::getState Oct 07 21:57:24 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:24 volumio volumio[1025]: info: MPD Database updated - AlbumList cache refreshed Oct 07 21:57:24 volumio volumio[1025]: info: Oct 07 21:57:24 volumio volumio[1025]: ---------------------------- MPD announces state update: database Oct 07 21:57:24 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:24 volumio volumio[1025]: info: Oct 07 21:57:24 volumio volumio[1025]: ---------------------------- MPD announces state update: update Oct 07 21:57:24 volumio volumio[1025]: info: ControllerMpd::getState Oct 07 21:57:24 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:24 volumio volumio[1025]: info: ControllerMpd::getState Oct 07 21:57:24 volumio volumio[1025]: verbose: ControllerMpd::sendMpdCommand status Oct 07 21:57:25 volumio volumio[1025]: info: sendMpdCommand status took 298 milliseconds Oct 07 21:57:25 volumio volumio[1025]: info: sendMpdCommand status took 297 milliseconds Oct 07 21:57:25 volumio volumio[1025]: info: sendMpdCommand status took 296 milliseconds Oct 07 21:57:25 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:25 volumio volumio[1025]: info: Command Router : Notfying DB Updatefalse Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::Close All Modals sent Oct 07 21:57:25 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:25 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:25 volumio volumio[1025]: info: ControllerMpd::pushState Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::servicePushState Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:25 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:25 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:25 volumio volumio[1025]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 07 21:57:25 volumio volumio[1025]: verbose: CURRENT POSITION 0 Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::syncState stateService stop Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:25 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:25 volumio volumio[1025]: info: No code Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:25 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:25 volumio volumio[1025]: info: ControllerMpd::pushState Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::servicePushState Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:25 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:25 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:25 volumio volumio[1025]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 07 21:57:25 volumio volumio[1025]: verbose: CURRENT POSITION 0 Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::syncState stateService stop Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:25 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:25 volumio volumio[1025]: info: No code Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:25 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:25 volumio volumio[1025]: info: ------------------------------ 427ms Oct 07 21:57:25 volumio volumio[1025]: info: ------------------------------ 427ms Oct 07 21:57:25 volumio volumio[1025]: info: sendMpdCommand status took 763 milliseconds Oct 07 21:57:25 volumio volumio[1025]: info: sendMpdCommand status took 762 milliseconds Oct 07 21:57:25 volumio volumio[1025]: info: sendMpdCommand status took 761 milliseconds Oct 07 21:57:25 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:25 volumio volumio[1025]: info: Command Router : Notfying DB Updatefalse Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::Close All Modals sent Oct 07 21:57:25 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:25 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:25 volumio volumio[1025]: info: ControllerMpd::pushState Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::servicePushState Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:25 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:25 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:25 volumio volumio[1025]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 07 21:57:25 volumio volumio[1025]: verbose: CURRENT POSITION 0 Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::syncState stateService stop Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:25 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:25 volumio volumio[1025]: info: No code Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:25 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:25 volumio volumio[1025]: info: ControllerMpd::pushState Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::servicePushState Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:25 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:25 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:25 volumio volumio[1025]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 07 21:57:25 volumio volumio[1025]: verbose: CURRENT POSITION 0 Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::syncState stateService stop Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:25 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:25 volumio volumio[1025]: info: No code Oct 07 21:57:25 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:25 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:25 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:25 volumio volumio[1025]: info: ------------------------------ 891ms Oct 07 21:57:25 volumio volumio[1025]: info: ------------------------------ 892ms Oct 07 21:57:26 volumio volumio[1025]: info: sendMpdCommand status took 1063 milliseconds Oct 07 21:57:26 volumio volumio[1025]: info: sendMpdCommand status took 1063 milliseconds Oct 07 21:57:26 volumio volumio[1025]: info: sendMpdCommand status took 1062 milliseconds Oct 07 21:57:26 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:26 volumio volumio[1025]: info: Command Router : Notfying DB Updatefalse Oct 07 21:57:26 volumio volumio[1025]: info: CoreCommandRouter::Close All Modals sent Oct 07 21:57:26 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:26 volumio volumio[1025]: verbose: ControllerMpd::parseState Oct 07 21:57:26 volumio volumio[1025]: info: ControllerMpd::pushState Oct 07 21:57:26 volumio volumio[1025]: info: CoreCommandRouter::servicePushState Oct 07 21:57:26 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:26 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:26 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:26 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:26 volumio volumio[1025]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 07 21:57:26 volumio volumio[1025]: verbose: CURRENT POSITION 0 Oct 07 21:57:26 volumio volumio[1025]: info: CoreStateMachine::syncState stateService stop Oct 07 21:57:26 volumio volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Oct 07 21:57:26 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:26 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:26 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:26 volumio volumio[1025]: info: No code Oct 07 21:57:26 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:26 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:26 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:26 volumio volumio[1025]: info: ControllerMpd::pushState Oct 07 21:57:26 volumio volumio[1025]: info: CoreCommandRouter::servicePushState Oct 07 21:57:26 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:26 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:26 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:26 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:26 volumio volumio[1025]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 07 21:57:26 volumio volumio[1025]: verbose: CURRENT POSITION 0 Oct 07 21:57:26 volumio volumio[1025]: info: CoreStateMachine::syncState stateService stop Oct 07 21:57:26 volumio volumio[1025]: info: CoreStateMachine::syncState currentStatus stop Oct 07 21:57:26 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:26 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:26 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:26 volumio volumio[1025]: info: No code Oct 07 21:57:26 volumio volumio[1025]: info: CoreStateMachine::pushState Oct 07 21:57:26 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:26 volumio volumio[1025]: info: CoreCommandRouter::volumioPushState Oct 07 21:57:26 volumio volumio[1025]: info: ------------------------------ 1166ms Oct 07 21:57:26 volumio volumio[1025]: info: ------------------------------ 1166ms Oct 07 21:57:26 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 07 21:57:26 volumio wpa_supplicant[926]: RRM: Ignoring radio measurement request: Not RRM network Oct 07 21:57:27 volumio dhcpcd[938]: wlan0: carrier lost Oct 07 21:57:27 volumio wpa_supplicant[926]: wlan0: Associated with c2:15:a2:6c:44:94 Oct 07 21:57:27 volumio wpa_supplicant[926]: wlan0: CTRL-EVENT-CONNECTED - Connection to c2:15:a2:6c:44:94 completed [id=0 id_str=] Oct 07 21:57:27 volumio wpa_supplicant[926]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 07 21:57:27 volumio avahi-daemon[693]: Withdrawing address record for 192.168.1.177 on wlan0. Oct 07 21:57:27 volumio avahi-daemon[693]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.177. Oct 07 21:57:27 volumio avahi-daemon[693]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 07 21:57:27 volumio dhcpcd[938]: wlan0: deleting route to 192.168.1.0/24 Oct 07 21:57:27 volumio volumio[1025]: info: Discovery: A device disappeared from network Oct 07 21:57:27 volumio volumio[1025]: info: Discovery: Device volumio disappeared from network Oct 07 21:57:27 volumio dhcpcd[938]: wlan0: carrier acquired Oct 07 21:57:27 volumio dhcpcd[938]: wlan0: IAID 01:1a:26:20 Oct 07 21:57:27 volumio dhcpcd[938]: wlan0: soliciting a DHCP lease Oct 07 21:57:28 volumio dhcpcd[938]: wlan0: soliciting an IPv6 router Oct 07 21:57:29 volumio ntpd[818]: Deleting interface #7 wlan0, 192.168.1.177#123, interface stats: received=100, sent=102, dropped=0, active_time=211 secs Oct 07 21:57:29 volumio ntpd[818]: 23.186.168.1 local addr 192.168.1.177 -> Oct 07 21:57:29 volumio ntpd[818]: 12.203.31.102 local addr 192.168.1.177 -> Oct 07 21:57:29 volumio ntpd[818]: 23.168.136.132 local addr 192.168.1.177 -> Oct 07 21:57:29 volumio ntpd[818]: 144.34.193.110 local addr 192.168.1.177 -> Oct 07 21:57:29 volumio ntpd[818]: 208.67.75.242 local addr 192.168.1.177 -> Oct 07 21:57:29 volumio ntpd[818]: 159.203.82.102 local addr 192.168.1.177 -> Oct 07 21:57:29 volumio ntpd[818]: 23.150.41.122 local addr 192.168.1.177 -> Oct 07 21:57:29 volumio ntpd[818]: 69.164.213.136 local addr 192.168.1.177 -> Oct 07 21:57:29 volumio ntpd[818]: 135.134.111.122 local addr 192.168.1.177 -> Oct 07 21:57:29 volumio ntpd[818]: 152.70.159.102 local addr 192.168.1.177 -> Oct 07 21:57:31 volumio dhcpcd[938]: wlan0: offered 192.168.1.177 from 192.168.1.1 Oct 07 21:57:31 volumio dhcpcd[938]: wlan0: probing address 192.168.1.177/24 Oct 07 21:57:36 volumio dhcpcd[938]: wlan0: leased 192.168.1.177 for 7200 seconds Oct 07 21:57:36 volumio avahi-daemon[693]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.177. Oct 07 21:57:36 volumio avahi-daemon[693]: New relevant interface wlan0.IPv4 for mDNS. Oct 07 21:57:36 volumio dhcpcd[938]: wlan0: adding route to 192.168.1.0/24 Oct 07 21:57:36 volumio avahi-daemon[693]: Registering new address record for 192.168.1.177 on wlan0.IPv4. Oct 07 21:57:36 volumio dhcpcd[938]: wlan0: adding default route via 192.168.1.1 Oct 07 21:57:37 volumio volumio[1025]: verbose: New Socket.io Connection to 192.168.1.177 from 192.168.1.247 UA: Mozilla/5.0 (Linux; Android 14; SM-S928U Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.70 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 07 21:57:37 volumio volumio[1025]: verbose: New Socket.io Connection to 192.168.1.177 from 192.168.1.247 UA: Mozilla/5.0 (Linux; Android 14; SM-S928U Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.70 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 07 21:57:37 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 21:57:37 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 21:57:37 volumio volumio[1025]: info: Discovery: Getting this device information Oct 07 21:57:37 volumio volumio[1025]: info: CoreCommandRouter::volumioGetState Oct 07 21:57:37 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:37 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 21:57:38 volumio ntpd[818]: Listen normally on 8 wlan0 192.168.1.177:123 Oct 07 21:57:38 volumio ntpd[818]: new interface(s) found: waking up resolver Oct 07 21:57:39 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 21:57:39 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 21:57:39 volumio volumio[1025]: info: Discovery: Getting this device information Oct 07 21:57:39 volumio volumio[1025]: info: CoreCommandRouter::volumioGetState Oct 07 21:57:39 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:39 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 21:57:40 volumio volumio[1025]: verbose: New Socket.io Connection to 192.168.1.177 from 192.168.1.247 UA: Mozilla/5.0 (Linux; Android 14; SM-S928U Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.70 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Oct 07 21:57:40 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 07 21:57:40 volumio volumio[1025]: info: CoreCommandRouter::volumioGetVisibleSources Oct 07 21:57:40 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 07 21:57:40 volumio volumio[1025]: info: CoreCommandRouter::volumioGetState Oct 07 21:57:40 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:40 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 07 21:57:40 volumio volumio[1025]: info: CoreCommandRouter::volumioGetQueue Oct 07 21:57:40 volumio volumio[1025]: info: CoreStateMachine::getQueue Oct 07 21:57:40 volumio volumio[1025]: info: CorePlayQueue::getQueue Oct 07 21:57:40 volumio volumio[1025]: info: Listing playlists Oct 07 21:57:40 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 07 21:57:40 volumio volumio[1025]: info: Received Get System Info Oct 07 21:57:40 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 21:57:40 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 21:57:40 volumio volumio[1025]: info: Discovery: Getting this device information Oct 07 21:57:40 volumio volumio[1025]: info: CoreCommandRouter::volumioGetState Oct 07 21:57:41 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:41 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 21:57:41 volumio volumio[1025]: info: CoreCommandRouter::volumioGetState Oct 07 21:57:41 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:41 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 07 21:57:47 volumio volumio[1025]: info: Discovery: adding 1e6d4173-f78a-46c1-a329-d0d0ca004d6b Oct 07 21:57:47 volumio volumio[1025]: info: Discovery: Found device Volumio Oct 07 21:57:47 volumio volumio[1025]: info: CoreCommandRouter::volumioGetState Oct 07 21:57:47 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:57:49 volumio ntpd[818]: Soliciting pool server 2001:470:f1c4:1::42 Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 21:57:56 volumio volumio[1025]: info: Retrieving Cloud Streaming UI Oct 07 21:57:56 volumio volumio[1025]: info: Getting Tidal Cloud Configuration Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 21:57:56 volumio volumio[1025]: info: Getting Qobuz Cloud Configuration Oct 07 21:57:56 volumio volumio[1025]: info: Asking plugin for UI Config Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 21:57:56 volumio volumio[1025]: info: Getting Spotify Cloud Configuration Oct 07 21:57:56 volumio volumio[1025]: info: Asking plugin for UI Config Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 21:57:56 volumio volumio[1025]: info: Saving Spotify Acccount Oct 07 21:57:56 volumio volumio[1025]: info: Got Tidal Cloud Configuration Oct 07 21:57:56 volumio volumio[1025]: info: Got it Oct 07 21:57:56 volumio volumio[1025]: info: Got it Oct 07 21:57:56 volumio volumio[1025]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 07 21:57:56 volumio volumio[1025]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::volumioGetBrowseSources Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::volumioGetBrowseSources Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::volumioGetBrowseSources Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 07 21:57:56 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 07 21:58:00 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 07 21:58:02 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 21:58:02 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 21:58:02 volumio volumio[1025]: info: Discovery: Getting this device information Oct 07 21:58:02 volumio volumio[1025]: info: CoreCommandRouter::volumioGetState Oct 07 21:58:02 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:58:02 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 21:58:04 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 07 21:58:08 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 07 21:58:10 volumio volumio[1025]: info: Enabling MyMusic plugin metavolumio Oct 07 21:58:10 volumio volumio[1025]: info: [MyVolumio PluginManager] Enabling and starting plugin miscellanea metavolumio Oct 07 21:58:10 volumio volumio[1025]: info: [MyVolumio PluginManager] Plugin miscellanea metavolumio not in user plan, not enabling Oct 07 21:58:10 volumio volumio[1025]: info: Error: Plugin miscellanea metavolumio not in user plan, not enabling Oct 07 21:58:10 volumio volumio[1025]: error: Could not Enable MyMusic Plugin: Error Oct 07 21:58:11 volumio volumio[1025]: info: [now-playing] Fetching API key... Oct 07 21:58:12 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 07 21:58:16 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 07 21:58:16 volumio volumio[1025]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not fetch weather: Could not obtain API key Oct 07 21:58:16 volumio volumio[1025]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:114:19) Oct 07 21:58:16 volumio volumio[1025]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 07 21:58:16 volumio volumio[1025]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:113:28) Oct 07 21:58:16 volumio volumio[1025]: (node:1025) UnhandledPromiseRejectionWarning: Error: Could not obtain API key Oct 07 21:58:16 volumio volumio[1025]: at doGet (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:124:19) Oct 07 21:58:16 volumio volumio[1025]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Oct 07 21:58:16 volumio volumio[1025]: (node:1025) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 7) Oct 07 21:58:20 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 07 21:58:24 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 07 21:58:28 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 07 21:58:32 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 07 21:58:32 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 07 21:58:32 volumio volumio[1025]: info: Discovery: Getting this device information Oct 07 21:58:32 volumio volumio[1025]: info: CoreCommandRouter::volumioGetState Oct 07 21:58:32 volumio volumio[1025]: info: CorePlayQueue::getTrack 0 Oct 07 21:58:32 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 07 21:58:33 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 07 21:58:33 volumio volumio[1025]: info: Preload queue cleared Oct 07 21:58:35 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 07 21:58:35 volumio volumio[1025]: info: Preload queue cleared Oct 07 21:58:37 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 07 21:58:37 volumio volumio[1025]: info: Preload queue cleared Oct 07 21:58:38 volumio ntpd[818]: Soliciting pool server 74.208.117.38 Oct 07 21:58:39 volumio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 07 21:58:39 volumio volumio[1025]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 21:58:39 volumio volumio[1025]: TypeError: Cannot read property 'length' of undefined Oct 07 21:58:39 volumio volumio[1025]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 07 21:58:39 volumio volumio[1025]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 07 21:58:39 volumio volumio[1025]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 07 21:58:39 volumio volumio[1025]: at Parser.emit (events.js:315:20) Oct 07 21:58:39 volumio volumio[1025]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 07 21:58:39 volumio volumio[1025]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 07 21:58:39 volumio volumio[1025]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 07 21:58:39 volumio volumio[1025]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 07 21:58:39 volumio volumio[1025]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 07 21:58:39 volumio volumio[1025]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 07 21:58:39 volumio volumio[1025]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 07 21:58:39 volumio volumio[1025]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 07 21:58:39 volumio volumio[1025]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 07 21:58:39 volumio volumio[1025]: at IncomingMessage.emit (events.js:327:22) Oct 07 21:58:39 volumio volumio[1025]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 07 21:58:39 volumio volumio[1025]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 07 21:58:39 volumio volumio[1025]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 07 21:58:39 volumio sudo[2869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-07 21:57 Oct 07 21:58:39 volumio sudo[2869]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"