-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Sat 2024-08-24 08:51:44 CST. -- Aug 24 08:50:05 localhost volumio[945]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 24 08:50:05 localhost volumio[945]: info: Preload queue cleared Aug 24 08:50:10 localhost volumio[945]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 24 08:50:11 localhost volumio[945]: info: Preload queue cleared Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@ac9012ae2ae4a0597933 Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@3b651ef213182eab3072 Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@45b437b5cf3c9c83a0a2 Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@9232864d1d2676df5f2e Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@05eda15f919ebe548d17 Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@f7994cad3fc65b4585b2 Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@c61a24bccd774d8c2237 Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@188250cdbbfe2cc2e11e Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@d56fe336cb621c1fffae Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@9738e7052475dcce7571 Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@87793893ee102524569b Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@672121b41cc60f82c3b7 Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@5c8a7c365a984a6f9938 Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@e33918b3bf08a48283f7 Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@84680543c97809c6300d Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@9dd124f7a6badc263766 Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@5ac5e94b66b3697ad1de Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@c1ad307d84484c4e8df0 Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@fc859472c9e87e07e646 Aug 24 08:50:11 localhost volumio[945]: info: Preloading song: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@796410a2ff09e7693a1f Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@ac9012ae2ae4a0597933 in service upnp_browser Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@3b651ef213182eab3072 in service upnp_browser Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@45b437b5cf3c9c83a0a2 in service upnp_browser Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@9232864d1d2676df5f2e in service upnp_browser Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@05eda15f919ebe548d17 in service upnp_browser Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@f7994cad3fc65b4585b2 in service upnp_browser Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@c61a24bccd774d8c2237 in service upnp_browser Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@188250cdbbfe2cc2e11e in service upnp_browser Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@d56fe336cb621c1fffae in service upnp_browser Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@9738e7052475dcce7571 in service upnp_browser Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@87793893ee102524569b in service upnp_browser Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@672121b41cc60f82c3b7 in service upnp_browser Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@5c8a7c365a984a6f9938 in service upnp_browser Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@e33918b3bf08a48283f7 in service upnp_browser Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@84680543c97809c6300d in service upnp_browser Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@9dd124f7a6badc263766 in service upnp_browser Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@5ac5e94b66b3697ad1de in service upnp_browser Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@c1ad307d84484c4e8df0 in service upnp_browser Aug 24 08:50:11 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@fc859472c9e87e07e646 in service upnp_browser Aug 24 08:50:12 localhost volumio[945]: info: Exploding uri upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@796410a2ff09e7693a1f in service upnp_browser Aug 24 08:50:14 localhost volumio[945]: info: Preload queue cleared Aug 24 08:50:14 localhost volumio[945]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::ClearQueue Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::stop Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::stPlaybackTimer Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::updateTrackBlock Aug 24 08:50:14 localhost volumio[945]: info: CorePlayQueue::getTrackBlock Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::pushState Aug 24 08:50:14 localhost volumio[945]: info: CorePlayQueue::getTrack 2 Aug 24 08:50:14 localhost volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 08:50:14 localhost volumio[945]: info: CoreCommandRouter::volumioPushState Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::serviceStop Aug 24 08:50:14 localhost volumio[945]: info: CorePlayQueue::getTrack 2 Aug 24 08:50:14 localhost volumio[945]: info: CoreCommandRouter::serviceStop Aug 24 08:50:14 localhost volumio[945]: info: [1724460614130] ControllerUPNPBrowser::stop Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 08:50:14 localhost volumio[945]: info: CorePlayQueue::clearPlayQueue Aug 24 08:50:14 localhost volumio[945]: info: CorePlayQueue::saveQueue Aug 24 08:50:14 localhost volumio[945]: info: CoreCommandRouter::volumioPushQueue Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::addQueueItems Aug 24 08:50:14 localhost volumio[945]: info: CorePlayQueue::addQueueItems Aug 24 08:50:14 localhost volumio[945]: info: Preload queue cleared Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@ac9012ae2ae4a0597933 Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@ac9012ae2ae4a0597933 Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@3b651ef213182eab3072 Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@3b651ef213182eab3072 Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@45b437b5cf3c9c83a0a2 Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@45b437b5cf3c9c83a0a2 Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@9232864d1d2676df5f2e Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@9232864d1d2676df5f2e Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@05eda15f919ebe548d17 Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@05eda15f919ebe548d17 Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@f7994cad3fc65b4585b2 Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@f7994cad3fc65b4585b2 Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@c61a24bccd774d8c2237 Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@c61a24bccd774d8c2237 Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@188250cdbbfe2cc2e11e Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@188250cdbbfe2cc2e11e Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@d56fe336cb621c1fffae Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@d56fe336cb621c1fffae Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@9738e7052475dcce7571 Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@9738e7052475dcce7571 Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@87793893ee102524569b Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@87793893ee102524569b Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@672121b41cc60f82c3b7 Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@672121b41cc60f82c3b7 Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@5c8a7c365a984a6f9938 Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@5c8a7c365a984a6f9938 Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@e33918b3bf08a48283f7 Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@e33918b3bf08a48283f7 Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@84680543c97809c6300d Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@84680543c97809c6300d Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@9dd124f7a6badc263766 Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@9dd124f7a6badc263766 Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@5ac5e94b66b3697ad1de Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@5ac5e94b66b3697ad1de Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@c1ad307d84484c4e8df0 Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@c1ad307d84484c4e8df0 Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@fc859472c9e87e07e646 Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@fc859472c9e87e07e646 Aug 24 08:50:14 localhost volumio[945]: info: Adding Item to queue: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@796410a2ff09e7693a1f Aug 24 08:50:14 localhost volumio[945]: info: Using cached record of: upnp/http://192.168.31.116:32469/ContentDirectory/4125eb04-724b-dc07-67cd-a48c60c8da8a/control.xml@796410a2ff09e7693a1f Aug 24 08:50:14 localhost volumio[945]: info: CoreCommandRouter::volumioPushQueue Aug 24 08:50:14 localhost volumio[945]: info: CorePlayQueue::saveQueue Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::updateTrackBlock Aug 24 08:50:14 localhost volumio[945]: info: CorePlayQueue::getTrackBlock Aug 24 08:50:14 localhost volumio[945]: info: CoreCommandRouter::volumioPlay Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::play index 1 Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::stop Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::play index undefined Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 08:50:14 localhost volumio[945]: info: CorePlayQueue::getTrack 1 Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::startPlaybackTimer Aug 24 08:50:14 localhost volumio[945]: info: CorePlayQueue::getTrack 1 Aug 24 08:50:14 localhost volumio[945]: info: [1724460614141] ControllerUPNPBrowser::clearAddPlayTrack Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 08:50:14 localhost volumio[945]: info: Aug 24 08:50:14 localhost volumio[945]: ---------------------------- MPD announces state update: player Aug 24 08:50:14 localhost volumio[945]: info: sendMpdCommand stop took 24 milliseconds Aug 24 08:50:14 localhost volumio[945]: info: ControllerMpd::getState Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::sendMpdCommand status Aug 24 08:50:14 localhost volumio[945]: info: sendMpdCommand stop took 13 milliseconds Aug 24 08:50:14 localhost volumio[945]: info: sendMpdCommand status took 1 milliseconds Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::sendMpdCommand clear Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::parseState Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 08:50:14 localhost volumio[945]: info: Aug 24 08:50:14 localhost volumio[945]: ---------------------------- MPD announces system playlist update Aug 24 08:50:14 localhost volumio[945]: info: Ignoring MPD Status Update Aug 24 08:50:14 localhost volumio[945]: info: sendMpdCommand clear took 1 milliseconds Aug 24 08:50:14 localhost volumio[945]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.116:32469/object/3b651ef213182eab3072/file.flac" Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::parseTrackInfo Aug 24 08:50:14 localhost volumio[945]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 24 08:50:14 localhost volumio[945]: info: ------------------------------ 3ms Aug 24 08:50:14 localhost volumio[945]: error: updateQueue error: null Aug 24 08:50:14 localhost volumio[945]: info: ------------------------------ 2ms Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.116:32469/object/3b651ef213182eab3072/file.flac" Aug 24 08:50:14 localhost volumio[945]: info: Aug 24 08:50:14 localhost volumio[945]: ---------------------------- MPD announces system playlist update Aug 24 08:50:14 localhost volumio[945]: info: Ignoring MPD Status Update Aug 24 08:50:14 localhost volumio[945]: info: sendMpdCommand add "http://192.168.31.116:32469/object/3b651ef213182eab3072/file.flac" took 0 milliseconds Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::sendMpdCommand play Aug 24 08:50:14 localhost volumio[945]: info: ------------------------------ 2ms Aug 24 08:50:14 localhost volumio[945]: info: sendMpdCommand play took 1 milliseconds Aug 24 08:50:14 localhost volumio[945]: info: Aug 24 08:50:14 localhost volumio[945]: ---------------------------- MPD announces state update: player Aug 24 08:50:14 localhost volumio[945]: info: ControllerMpd::getState Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::sendMpdCommand status Aug 24 08:50:14 localhost volumio[945]: info: Aug 24 08:50:14 localhost volumio[945]: ---------------------------- MPD announces state update: player Aug 24 08:50:14 localhost volumio[945]: info: sendMpdCommand status took 3 milliseconds Aug 24 08:50:14 localhost volumio[945]: info: ControllerMpd::getState Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::sendMpdCommand status Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::parseState Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 08:50:14 localhost volumio[945]: info: sendMpdCommand status took 1 milliseconds Aug 24 08:50:14 localhost volumio[945]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::parseState Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::parseTrackInfo Aug 24 08:50:14 localhost volumio[945]: info: ControllerMpd::pushState Aug 24 08:50:14 localhost volumio[945]: info: CoreCommandRouter::servicePushState Aug 24 08:50:14 localhost volumio[945]: info: CorePlayQueue::getTrack 1 Aug 24 08:50:14 localhost volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.flac","artist":null,"album":null,"uri":"http://192.168.31.116:32469/object/3b651ef213182eab3072/file.flac","trackType":"flac"} Aug 24 08:50:14 localhost volumio[945]: verbose: CURRENT POSITION 1 Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::syncState stateService play Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::syncState currentStatus stop Aug 24 08:50:14 localhost volumio[945]: info: ------------------------------ 5ms Aug 24 08:50:14 localhost volumio[945]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::parseTrackInfo Aug 24 08:50:14 localhost volumio[945]: info: ControllerMpd::pushState Aug 24 08:50:14 localhost volumio[945]: info: CoreCommandRouter::servicePushState Aug 24 08:50:14 localhost volumio[945]: info: CorePlayQueue::getTrack 1 Aug 24 08:50:14 localhost volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file.flac","artist":null,"album":null,"uri":"http://192.168.31.116:32469/object/3b651ef213182eab3072/file.flac","trackType":"flac"} Aug 24 08:50:14 localhost volumio[945]: verbose: CURRENT POSITION 1 Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::syncState stateService play Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::syncState currentStatus play Aug 24 08:50:14 localhost volumio[945]: info: Received an update from plugin. extracting info from payload Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::pushState Aug 24 08:50:14 localhost volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 08:50:14 localhost volumio[945]: info: CoreCommandRouter::volumioPushState Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::pushState Aug 24 08:50:14 localhost volumio[945]: info: CoreCommandRouter::volumioPushState Aug 24 08:50:14 localhost volumio[945]: info: ------------------------------ 9ms Aug 24 08:50:14 localhost volumio[945]: info: Aug 24 08:50:14 localhost volumio[945]: ---------------------------- MPD announces system playlist update Aug 24 08:50:14 localhost volumio[945]: info: Ignoring MPD Status Update Aug 24 08:50:14 localhost volumio[945]: info: Aug 24 08:50:14 localhost volumio[945]: ---------------------------- MPD announces state update: player Aug 24 08:50:14 localhost volumio[945]: info: ControllerMpd::getState Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::sendMpdCommand status Aug 24 08:50:14 localhost volumio[945]: info: ------------------------------ 2ms Aug 24 08:50:14 localhost volumio[945]: info: sendMpdCommand status took 1 milliseconds Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::parseState Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 08:50:14 localhost volumio[945]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 24 08:50:14 localhost volumio[945]: verbose: ControllerMpd::parseTrackInfo Aug 24 08:50:14 localhost volumio[945]: info: ControllerMpd::pushState Aug 24 08:50:14 localhost volumio[945]: info: CoreCommandRouter::servicePushState Aug 24 08:50:14 localhost volumio[945]: info: CorePlayQueue::getTrack 1 Aug 24 08:50:14 localhost volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":73,"duration":237,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"听不到","artist":"梁静茹","album":"恋爱的力量","uri":"http://192.168.31.116:32469/object/3b651ef213182eab3072/file.flac","trackType":"flac"} Aug 24 08:50:14 localhost volumio[945]: verbose: CURRENT POSITION 1 Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::syncState stateService play Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::syncState currentStatus play Aug 24 08:50:14 localhost volumio[945]: info: Received an update from plugin. extracting info from payload Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::pushState Aug 24 08:50:14 localhost volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 08:50:14 localhost volumio[945]: info: CoreCommandRouter::volumioPushState Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::pushState Aug 24 08:50:14 localhost volumio[945]: info: CoreCommandRouter::volumioPushState Aug 24 08:50:14 localhost volumio[945]: info: ------------------------------ 10ms Aug 24 08:50:14 localhost volumio[945]: info: CoreCommandRouter::volumioGetQueue Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::getQueue Aug 24 08:50:14 localhost volumio[945]: info: CorePlayQueue::getQueue Aug 24 08:50:14 localhost volumio[945]: info: CoreCommandRouter::volumioGetQueue Aug 24 08:50:14 localhost volumio[945]: info: CoreStateMachine::getQueue Aug 24 08:50:14 localhost volumio[945]: info: CorePlayQueue::getQueue Aug 24 08:50:27 localhost volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 24 08:50:27 localhost volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 24 08:50:27 localhost volumio[945]: info: Discovery: Getting this device information Aug 24 08:50:27 localhost volumio[945]: info: CoreCommandRouter::volumioGetState Aug 24 08:50:27 localhost volumio[945]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 24 08:50:57 localhost volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 24 08:50:57 localhost volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 24 08:50:57 localhost volumio[945]: info: Discovery: Getting this device information Aug 24 08:50:57 localhost volumio[945]: info: CoreCommandRouter::volumioGetState Aug 24 08:50:57 localhost volumio[945]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 24 08:51:27 localhost volumio[945]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 24 08:51:27 localhost volumio[945]: info: Preload queue cleared Aug 24 08:51:27 localhost volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 24 08:51:27 localhost volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 24 08:51:27 localhost volumio[945]: info: Discovery: Getting this device information Aug 24 08:51:27 localhost volumio[945]: info: CoreCommandRouter::volumioGetState Aug 24 08:51:27 localhost volumio[945]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 24 08:51:44 localhost volumio[945]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 24 08:51:44 localhost volumio[945]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 08:51:44 localhost volumio[945]: TypeError: Cannot read property 'length' of undefined Aug 24 08:51:44 localhost volumio[945]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Aug 24 08:51:44 localhost volumio[945]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Aug 24 08:51:44 localhost volumio[945]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Aug 24 08:51:44 localhost volumio[945]: at Parser.emit (events.js:315:20) Aug 24 08:51:44 localhost volumio[945]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Aug 24 08:51:44 localhost volumio[945]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Aug 24 08:51:44 localhost volumio[945]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Aug 24 08:51:44 localhost volumio[945]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Aug 24 08:51:44 localhost volumio[945]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Aug 24 08:51:44 localhost volumio[945]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Aug 24 08:51:44 localhost volumio[945]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Aug 24 08:51:44 localhost volumio[945]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Aug 24 08:51:44 localhost volumio[945]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Aug 24 08:51:44 localhost volumio[945]: at IncomingMessage.emit (events.js:327:22) Aug 24 08:51:44 localhost volumio[945]: at endReadableNT (internal/streams/readable.js:1327:12) Aug 24 08:51:44 localhost volumio[945]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 24 08:51:44 localhost volumio[945]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 08:51:44 localhost sudo[8522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-24 08:50 Aug 24 08:51:44 localhost sudo[8522]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"