-- Logs begin at Tue 2026-10-13 19:14:31 CEST, end at Fri 2026-10-16 22:46:57 CEST. -- Oct 16 22:45:09 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 22:45:09 volumio volumio[945]: info: Received Get System Info Oct 16 22:45:09 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 22:45:09 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 22:45:09 volumio volumio[945]: info: Discovery: Getting this device information Oct 16 22:45:09 volumio volumio[945]: info: CoreCommandRouter::volumioGetState Oct 16 22:45:09 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 22:45:48 volumio volumio[1197]: 99 Oct 16 22:45:48 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:48 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:48 volumio volumio[945]: info: Oct 16 22:45:48 volumio volumio[945]: ---------------------------- Client requests Volume 99 Oct 16 22:45:48 volumio volumio[945]: info: VolumeController::SetAlsaVolume99 Oct 16 22:45:48 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:48 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:48 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:48 volumio volumio[1197]: [158B blob data] Oct 16 22:45:48 volumio volumio[1197]: {"time":1792183548262,"response":"volume Success"}99 Oct 16 22:45:48 volumio volumio[1197]: 98 Oct 16 22:45:48 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:48 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:48 volumio volumio[945]: info: Oct 16 22:45:48 volumio volumio[945]: ---------------------------- Client requests Volume 98 Oct 16 22:45:48 volumio volumio[945]: info: VolumeController::SetAlsaVolume98 Oct 16 22:45:48 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:48 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:48 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:48 volumio volumio[1197]: [158B blob data] Oct 16 22:45:48 volumio volumio[1197]: {"time":1792183548490,"response":"volume Success"}98 Oct 16 22:45:48 volumio volumio[1197]: 97 Oct 16 22:45:48 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:48 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:48 volumio volumio[945]: info: Oct 16 22:45:48 volumio volumio[945]: ---------------------------- Client requests Volume 97 Oct 16 22:45:48 volumio volumio[945]: info: VolumeController::SetAlsaVolume97 Oct 16 22:45:48 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:48 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:48 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:48 volumio volumio[1197]: [158B blob data] Oct 16 22:45:48 volumio volumio[1197]: {"time":1792183548721,"response":"volume Success"}97 Oct 16 22:45:49 volumio volumio[1197]: 96 Oct 16 22:45:49 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:49 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:49 volumio volumio[945]: info: Oct 16 22:45:49 volumio volumio[945]: ---------------------------- Client requests Volume 96 Oct 16 22:45:49 volumio volumio[945]: info: VolumeController::SetAlsaVolume96 Oct 16 22:45:49 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:49 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:49 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:49 volumio volumio[1197]: [158B blob data] Oct 16 22:45:49 volumio volumio[1197]: {"time":1792183549448,"response":"volume Success"}96 Oct 16 22:45:51 volumio volumio[1197]: 95 Oct 16 22:45:51 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:51 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:51 volumio volumio[945]: info: Oct 16 22:45:51 volumio volumio[945]: ---------------------------- Client requests Volume 95 Oct 16 22:45:51 volumio volumio[945]: info: VolumeController::SetAlsaVolume95 Oct 16 22:45:51 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:51 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:51 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:51 volumio volumio[1197]: [158B blob data] Oct 16 22:45:51 volumio volumio[1197]: {"time":1792183551481,"response":"volume Success"}95 Oct 16 22:45:51 volumio volumio[1197]: 94 Oct 16 22:45:51 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:51 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:51 volumio volumio[945]: info: Oct 16 22:45:51 volumio volumio[945]: ---------------------------- Client requests Volume 94 Oct 16 22:45:51 volumio volumio[945]: info: VolumeController::SetAlsaVolume94 Oct 16 22:45:52 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:52 volumio volumio[1197]: [158B blob data] Oct 16 22:45:52 volumio volumio[1197]: {"time":1792183551986,"response":"volume Success"}94 Oct 16 22:45:52 volumio volumio[1197]: 93 Oct 16 22:45:52 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:52 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:52 volumio volumio[945]: info: Oct 16 22:45:52 volumio volumio[945]: ---------------------------- Client requests Volume 93 Oct 16 22:45:52 volumio volumio[945]: info: VolumeController::SetAlsaVolume93 Oct 16 22:45:52 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:52 volumio volumio[1197]: [158B blob data] Oct 16 22:45:52 volumio volumio[1197]: {"time":1792183552070,"response":"volume Success"}93 Oct 16 22:45:52 volumio volumio[1197]: 92 Oct 16 22:45:52 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:52 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:52 volumio volumio[945]: info: Oct 16 22:45:52 volumio volumio[945]: ---------------------------- Client requests Volume 92 Oct 16 22:45:52 volumio volumio[945]: info: VolumeController::SetAlsaVolume92 Oct 16 22:45:52 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:52 volumio volumio[1197]: [158B blob data] Oct 16 22:45:52 volumio volumio[1197]: {"time":1792183552153,"response":"volume Success"}92 Oct 16 22:45:52 volumio volumio[1197]: 91 Oct 16 22:45:52 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:52 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:52 volumio volumio[945]: info: Oct 16 22:45:52 volumio volumio[945]: ---------------------------- Client requests Volume 91 Oct 16 22:45:52 volumio volumio[945]: info: VolumeController::SetAlsaVolume91 Oct 16 22:45:52 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:52 volumio volumio[1197]: [158B blob data] Oct 16 22:45:52 volumio volumio[1197]: {"time":1792183552235,"response":"volume Success"}91 Oct 16 22:45:52 volumio volumio[1197]: 90 Oct 16 22:45:52 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:52 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:52 volumio volumio[945]: info: Oct 16 22:45:52 volumio volumio[945]: ---------------------------- Client requests Volume 90 Oct 16 22:45:52 volumio volumio[945]: info: VolumeController::SetAlsaVolume90 Oct 16 22:45:52 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:52 volumio volumio[1197]: [158B blob data] Oct 16 22:45:52 volumio volumio[1197]: {"time":1792183552324,"response":"volume Success"}90 Oct 16 22:45:52 volumio volumio[1197]: 89 Oct 16 22:45:52 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:52 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:52 volumio volumio[945]: info: Oct 16 22:45:52 volumio volumio[945]: ---------------------------- Client requests Volume 89 Oct 16 22:45:52 volumio volumio[945]: info: VolumeController::SetAlsaVolume89 Oct 16 22:45:52 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:52 volumio volumio[1197]: [158B blob data] Oct 16 22:45:52 volumio volumio[1197]: {"time":1792183552408,"response":"volume Success"}89 Oct 16 22:45:52 volumio volumio[1197]: 88 Oct 16 22:45:52 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:52 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:52 volumio volumio[945]: info: Oct 16 22:45:52 volumio volumio[945]: ---------------------------- Client requests Volume 88 Oct 16 22:45:52 volumio volumio[945]: info: VolumeController::SetAlsaVolume88 Oct 16 22:45:52 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:52 volumio volumio[1197]: [158B blob data] Oct 16 22:45:52 volumio volumio[1197]: {"time":1792183552492,"response":"volume Success"}88 Oct 16 22:45:52 volumio volumio[1197]: 87 Oct 16 22:45:52 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:52 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:52 volumio volumio[945]: info: Oct 16 22:45:52 volumio volumio[945]: ---------------------------- Client requests Volume 87 Oct 16 22:45:52 volumio volumio[945]: info: VolumeController::SetAlsaVolume87 Oct 16 22:45:52 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:52 volumio volumio[1197]: [158B blob data] Oct 16 22:45:52 volumio volumio[1197]: {"time":1792183552576,"response":"volume Success"}87 Oct 16 22:45:52 volumio volumio[1197]: 86 Oct 16 22:45:52 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:52 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:52 volumio volumio[945]: info: Oct 16 22:45:52 volumio volumio[945]: ---------------------------- Client requests Volume 86 Oct 16 22:45:52 volumio volumio[945]: info: VolumeController::SetAlsaVolume86 Oct 16 22:45:52 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:52 volumio volumio[1197]: [158B blob data] Oct 16 22:45:52 volumio volumio[1197]: {"time":1792183552660,"response":"volume Success"}86 Oct 16 22:45:52 volumio volumio[1197]: 85 Oct 16 22:45:52 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:52 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:52 volumio volumio[945]: info: Oct 16 22:45:52 volumio volumio[945]: ---------------------------- Client requests Volume 85 Oct 16 22:45:52 volumio volumio[945]: info: VolumeController::SetAlsaVolume85 Oct 16 22:45:52 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:52 volumio volumio[1197]: [158B blob data] Oct 16 22:45:52 volumio volumio[1197]: {"time":1792183552744,"response":"volume Success"}85 Oct 16 22:45:52 volumio volumio[1197]: 84 Oct 16 22:45:52 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:52 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:52 volumio volumio[945]: info: Oct 16 22:45:52 volumio volumio[945]: ---------------------------- Client requests Volume 84 Oct 16 22:45:52 volumio volumio[945]: info: VolumeController::SetAlsaVolume84 Oct 16 22:45:52 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:52 volumio volumio[1197]: [158B blob data] Oct 16 22:45:52 volumio volumio[1197]: {"time":1792183552830,"response":"volume Success"}84 Oct 16 22:45:52 volumio volumio[1197]: 83 Oct 16 22:45:52 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:52 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:52 volumio volumio[945]: info: Oct 16 22:45:52 volumio volumio[945]: ---------------------------- Client requests Volume 83 Oct 16 22:45:52 volumio volumio[945]: info: VolumeController::SetAlsaVolume83 Oct 16 22:45:52 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:52 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:52 volumio volumio[1197]: [158B blob data] Oct 16 22:45:52 volumio volumio[1197]: {"time":1792183552916,"response":"volume Success"}83 Oct 16 22:45:52 volumio volumio[1197]: 82 Oct 16 22:45:52 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:52 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:53 volumio volumio[945]: info: Oct 16 22:45:53 volumio volumio[945]: ---------------------------- Client requests Volume 82 Oct 16 22:45:53 volumio volumio[945]: info: VolumeController::SetAlsaVolume82 Oct 16 22:45:53 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:53 volumio volumio[1197]: [158B blob data] Oct 16 22:45:53 volumio volumio[1197]: {"time":1792183553001,"response":"volume Success"}82 Oct 16 22:45:53 volumio volumio[1197]: 81 Oct 16 22:45:53 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:53 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:53 volumio volumio[945]: info: Oct 16 22:45:53 volumio volumio[945]: ---------------------------- Client requests Volume 81 Oct 16 22:45:53 volumio volumio[945]: info: VolumeController::SetAlsaVolume81 Oct 16 22:45:53 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:53 volumio volumio[1197]: [158B blob data] Oct 16 22:45:53 volumio volumio[1197]: {"time":1792183553087,"response":"volume Success"}81 Oct 16 22:45:53 volumio volumio[1197]: 80 Oct 16 22:45:53 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:53 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:53 volumio volumio[945]: info: Oct 16 22:45:53 volumio volumio[945]: ---------------------------- Client requests Volume 80 Oct 16 22:45:53 volumio volumio[945]: info: VolumeController::SetAlsaVolume80 Oct 16 22:45:53 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:53 volumio volumio[1197]: [158B blob data] Oct 16 22:45:53 volumio volumio[1197]: {"time":1792183553176,"response":"volume Success"}80 Oct 16 22:45:53 volumio volumio[1197]: 79 Oct 16 22:45:53 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:53 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:53 volumio volumio[945]: info: Oct 16 22:45:53 volumio volumio[945]: ---------------------------- Client requests Volume 79 Oct 16 22:45:53 volumio volumio[945]: info: VolumeController::SetAlsaVolume79 Oct 16 22:45:53 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:53 volumio volumio[1197]: [158B blob data] Oct 16 22:45:53 volumio volumio[1197]: {"time":1792183553260,"response":"volume Success"}79 Oct 16 22:45:53 volumio volumio[1197]: 78 Oct 16 22:45:53 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:53 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:53 volumio volumio[945]: info: Oct 16 22:45:53 volumio volumio[945]: ---------------------------- Client requests Volume 78 Oct 16 22:45:53 volumio volumio[945]: info: VolumeController::SetAlsaVolume78 Oct 16 22:45:53 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:53 volumio volumio[1197]: [158B blob data] Oct 16 22:45:53 volumio volumio[1197]: {"time":1792183553345,"response":"volume Success"}78 Oct 16 22:45:53 volumio volumio[1197]: 77 Oct 16 22:45:53 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:53 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:53 volumio volumio[945]: info: Oct 16 22:45:53 volumio volumio[945]: ---------------------------- Client requests Volume 77 Oct 16 22:45:53 volumio volumio[945]: info: VolumeController::SetAlsaVolume77 Oct 16 22:45:53 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:53 volumio volumio[1197]: [158B blob data] Oct 16 22:45:53 volumio volumio[1197]: {"time":1792183553429,"response":"volume Success"}77 Oct 16 22:45:53 volumio volumio[1197]: 76 Oct 16 22:45:53 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:53 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:53 volumio volumio[945]: info: Oct 16 22:45:53 volumio volumio[945]: ---------------------------- Client requests Volume 76 Oct 16 22:45:53 volumio volumio[945]: info: VolumeController::SetAlsaVolume76 Oct 16 22:45:53 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:53 volumio volumio[1197]: [158B blob data] Oct 16 22:45:53 volumio volumio[1197]: {"time":1792183553518,"response":"volume Success"}76 Oct 16 22:45:53 volumio volumio[1197]: 75 Oct 16 22:45:53 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:53 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:53 volumio volumio[945]: info: Oct 16 22:45:53 volumio volumio[945]: ---------------------------- Client requests Volume 75 Oct 16 22:45:53 volumio volumio[945]: info: VolumeController::SetAlsaVolume75 Oct 16 22:45:53 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:53 volumio volumio[1197]: [158B blob data] Oct 16 22:45:53 volumio volumio[1197]: {"time":1792183553600,"response":"volume Success"}75 Oct 16 22:45:53 volumio volumio[1197]: 74 Oct 16 22:45:53 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:53 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:53 volumio volumio[945]: info: Oct 16 22:45:53 volumio volumio[945]: ---------------------------- Client requests Volume 74 Oct 16 22:45:53 volumio volumio[945]: info: VolumeController::SetAlsaVolume74 Oct 16 22:45:53 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:53 volumio volumio[1197]: [158B blob data] Oct 16 22:45:53 volumio volumio[1197]: {"time":1792183553685,"response":"volume Success"}74 Oct 16 22:45:53 volumio volumio[1197]: 73 Oct 16 22:45:53 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:53 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:53 volumio volumio[945]: info: Oct 16 22:45:53 volumio volumio[945]: ---------------------------- Client requests Volume 73 Oct 16 22:45:53 volumio volumio[945]: info: VolumeController::SetAlsaVolume73 Oct 16 22:45:53 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:53 volumio volumio[1197]: [158B blob data] Oct 16 22:45:53 volumio volumio[1197]: {"time":1792183553776,"response":"volume Success"}73 Oct 16 22:45:53 volumio volumio[1197]: 72 Oct 16 22:45:53 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:53 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:53 volumio volumio[945]: info: Oct 16 22:45:53 volumio volumio[945]: ---------------------------- Client requests Volume 72 Oct 16 22:45:53 volumio volumio[945]: info: VolumeController::SetAlsaVolume72 Oct 16 22:45:53 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:53 volumio volumio[1197]: [158B blob data] Oct 16 22:45:53 volumio volumio[1197]: {"time":1792183553862,"response":"volume Success"}72 Oct 16 22:45:53 volumio volumio[1197]: 71 Oct 16 22:45:53 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:53 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:53 volumio volumio[945]: info: Oct 16 22:45:53 volumio volumio[945]: ---------------------------- Client requests Volume 71 Oct 16 22:45:53 volumio volumio[945]: info: VolumeController::SetAlsaVolume71 Oct 16 22:45:53 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:53 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:53 volumio volumio[1197]: [158B blob data] Oct 16 22:45:53 volumio volumio[1197]: {"time":1792183553948,"response":"volume Success"}71 Oct 16 22:45:53 volumio volumio[1197]: 70 Oct 16 22:45:54 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:54 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:54 volumio volumio[945]: info: Oct 16 22:45:54 volumio volumio[945]: ---------------------------- Client requests Volume 70 Oct 16 22:45:54 volumio volumio[945]: info: VolumeController::SetAlsaVolume70 Oct 16 22:45:54 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:54 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:54 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:54 volumio volumio[1197]: [158B blob data] Oct 16 22:45:54 volumio volumio[1197]: {"time":1792183554032,"response":"volume Success"}70 Oct 16 22:45:54 volumio volumio[1197]: 69 Oct 16 22:45:54 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:54 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:54 volumio volumio[945]: info: Oct 16 22:45:54 volumio volumio[945]: ---------------------------- Client requests Volume 69 Oct 16 22:45:54 volumio volumio[945]: info: VolumeController::SetAlsaVolume69 Oct 16 22:45:54 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:54 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:54 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:54 volumio volumio[1197]: [158B blob data] Oct 16 22:45:54 volumio volumio[1197]: {"time":1792183554115,"response":"volume Success"}69 Oct 16 22:45:54 volumio volumio[1197]: 68 Oct 16 22:45:54 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:45:54 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:45:54 volumio volumio[945]: info: Oct 16 22:45:54 volumio volumio[945]: ---------------------------- Client requests Volume 68 Oct 16 22:45:54 volumio volumio[945]: info: VolumeController::SetAlsaVolume68 Oct 16 22:45:54 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:45:54 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:45:54 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:54 volumio volumio[1197]: [158B blob data] Oct 16 22:45:54 volumio volumio[1197]: {"time":1792183554200,"response":"volume Success"}68 Oct 16 22:45:56 volumio volumio[945]: error: Upnp client error: Error: This socket has been ended by the other party Oct 16 22:45:59 volumio volumio[945]: info: Clearing queue after UPNP request Oct 16 22:45:59 volumio volumio[945]: verbose: UNSET VOLATILE: Service: airplay_emulation Oct 16 22:45:59 volumio volumio[945]: info: Stopping Airplay Playback and sending pause command to client via USR2 Oct 16 22:45:59 volumio sudo[21060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 6255 Oct 16 22:45:59 volumio sudo[21060]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 22:45:59 volumio sudo[21060]: pam_unix(sudo:session): session closed for user root Oct 16 22:45:59 volumio volumio[945]: info: Shairport-Sync paused with USR2 Oct 16 22:45:59 volumio volumio[945]: info: CoreStateMachine::ClearQueue Oct 16 22:45:59 volumio volumio[945]: info: CoreStateMachine::stop Oct 16 22:45:59 volumio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 22:45:59 volumio volumio[945]: info: CorePlayQueue::clearPlayQueue Oct 16 22:45:59 volumio volumio[945]: info: CorePlayQueue::saveQueue Oct 16 22:45:59 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:45:59 volumio volumio[945]: info: CoreCommandRouter::volumioPushQueue Oct 16 22:45:59 volumio volumio[945]: info: Oct 16 22:45:59 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:45:59 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:45:59 volumio volumio[945]: error: updateQueue error: null Oct 16 22:45:59 volumio volumio[945]: info: ------------------------------ 4ms Oct 16 22:45:59 volumio volumio[945]: info: Starting UPNP Playback Oct 16 22:45:59 volumio volumio[945]: info: Preparing playback through UPNP Oct 16 22:45:59 volumio volumio[945]: info: CoreCommandRouter::volumioGetState Oct 16 22:45:59 volumio volumio[945]: info: CorePlayQueue::getTrack 0 Oct 16 22:45:59 volumio volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 22:46:00 volumio volumio[945]: info: Oct 16 22:46:00 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:00 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:00 volumio volumio[945]: info: Oct 16 22:46:00 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:00 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:00 volumio volumio[945]: info: ------------------------------ 3ms Oct 16 22:46:00 volumio volumio[945]: info: ------------------------------ 2ms Oct 16 22:46:00 volumio volumio[945]: info: Oct 16 22:46:00 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:00 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:00 volumio volumio[945]: info: ------------------------------ 1ms Oct 16 22:46:00 volumio volumio[945]: info: Oct 16 22:46:00 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:00 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:00 volumio volumio[945]: info: ------------------------------ 1ms Oct 16 22:46:00 volumio volumio[945]: info: Oct 16 22:46:00 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:00 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:00 volumio volumio[945]: info: ------------------------------ 1ms Oct 16 22:46:00 volumio volumio[945]: info: Oct 16 22:46:00 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:00 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:00 volumio volumio[945]: info: ------------------------------ 1ms Oct 16 22:46:00 volumio volumio[945]: info: Oct 16 22:46:00 volumio volumio[945]: ---------------------------- MPD announces state update: player Oct 16 22:46:00 volumio volumio[945]: info: ControllerMpd::getState Oct 16 22:46:00 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Oct 16 22:46:00 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 16 22:46:00 volumio volumio[945]: info: sendMpdCommand status took 5 milliseconds Oct 16 22:46:00 volumio volumio[945]: verbose: ControllerMpd::parseState Oct 16 22:46:00 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 22:46:00 volumio volumio[945]: info: sendMpdCommand clearerror took 4 milliseconds Oct 16 22:46:00 volumio volumio[945]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 22:46:00 volumio volumio[945]: verbose: ControllerMpd::parseTrackInfo Oct 16 22:46:00 volumio volumio[945]: info: ControllerMpd::pushState Oct 16 22:46:00 volumio volumio[945]: info: CoreCommandRouter::servicePushState Oct 16 22:46:00 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:00 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:00 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:00 volumio volumio[945]: info: CorePlayQueue::getTrack 0 Oct 16 22:46:00 volumio volumio[945]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.50.103:52906/4DEA4067-7044-9342-3CA8-E969BFF17A23/stream.wav","trackType":"wav"} Oct 16 22:46:00 volumio volumio[945]: verbose: CURRENT POSITION 0 Oct 16 22:46:00 volumio volumio[945]: info: CoreStateMachine::syncState stateService stop Oct 16 22:46:00 volumio volumio[945]: info: CoreStateMachine::syncState currentStatus stop Oct 16 22:46:00 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:00 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:00 volumio volumio[945]: info: No code Oct 16 22:46:00 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:00 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:00 volumio volumio[945]: info: ------------------------------ 23ms Oct 16 22:46:00 volumio volumio[945]: info: Clearing queue after UPNP request Oct 16 22:46:00 volumio volumio[945]: info: Restarting Shairport-Sync after stop Oct 16 22:46:00 volumio sudo[21073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 22:46:00 volumio sudo[21073]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 22:46:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 16 22:46:00 volumio shairport-sync[6255]: {"time":1792178636595,"response":"stopAirplayPlayback Success"} Oct 16 22:46:00 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 16 22:46:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 16 22:46:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 16 22:46:00 volumio sudo[21073]: pam_unix(sudo:session): session closed for user root Oct 16 22:46:00 volumio volumio[945]: info: Shairport-Sync retarted Oct 16 22:46:00 volumio volumio[945]: info: CoreStateMachine::ClearQueue Oct 16 22:46:00 volumio volumio[945]: info: CoreStateMachine::stop Oct 16 22:46:00 volumio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 22:46:00 volumio volumio[945]: info: CorePlayQueue::clearPlayQueue Oct 16 22:46:00 volumio volumio[945]: info: CorePlayQueue::saveQueue Oct 16 22:46:00 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:00 volumio volumio[945]: info: CoreCommandRouter::volumioPushQueue Oct 16 22:46:00 volumio volumio[945]: info: Oct 16 22:46:00 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:00 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:00 volumio volumio[945]: error: updateQueue error: null Oct 16 22:46:00 volumio volumio[945]: info: ------------------------------ 2ms Oct 16 22:46:00 volumio volumio[945]: info: Starting UPNP Playback Oct 16 22:46:00 volumio volumio[945]: info: Preparing playback through UPNP Oct 16 22:46:00 volumio volumio[945]: info: CoreCommandRouter::volumioGetState Oct 16 22:46:00 volumio volumio[945]: info: CorePlayQueue::getTrack 0 Oct 16 22:46:00 volumio volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 22:46:01 volumio volumio[945]: info: Oct 16 22:46:01 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:01 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:01 volumio volumio[945]: info: Oct 16 22:46:01 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:01 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:01 volumio volumio[945]: info: ------------------------------ 2ms Oct 16 22:46:01 volumio volumio[945]: info: ------------------------------ 2ms Oct 16 22:46:01 volumio volumio[945]: info: Oct 16 22:46:01 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:01 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:01 volumio volumio[945]: info: ------------------------------ 2ms Oct 16 22:46:01 volumio volumio[945]: info: Oct 16 22:46:01 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:01 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:01 volumio volumio[945]: info: ------------------------------ 2ms Oct 16 22:46:01 volumio volumio[945]: info: Oct 16 22:46:01 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:01 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:01 volumio volumio[945]: info: ------------------------------ 2ms Oct 16 22:46:01 volumio volumio[945]: info: Oct 16 22:46:01 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:01 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:01 volumio volumio[945]: info: ------------------------------ 1ms Oct 16 22:46:01 volumio volumio[945]: info: Oct 16 22:46:01 volumio volumio[945]: ---------------------------- MPD announces state update: player Oct 16 22:46:01 volumio volumio[945]: info: ControllerMpd::getState Oct 16 22:46:01 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Oct 16 22:46:01 volumio volumio[945]: info: Oct 16 22:46:01 volumio volumio[945]: ---------------------------- MPD announces state update: player Oct 16 22:46:01 volumio volumio[945]: info: sendMpdCommand status took 16 milliseconds Oct 16 22:46:01 volumio volumio[945]: info: ControllerMpd::getState Oct 16 22:46:01 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Oct 16 22:46:01 volumio volumio[945]: verbose: ControllerMpd::parseState Oct 16 22:46:01 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 22:46:01 volumio volumio[945]: info: sendMpdCommand status took 2 milliseconds Oct 16 22:46:01 volumio volumio[945]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 22:46:01 volumio volumio[945]: verbose: ControllerMpd::parseState Oct 16 22:46:01 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 22:46:01 volumio volumio[945]: verbose: ControllerMpd::parseTrackInfo Oct 16 22:46:01 volumio volumio[945]: info: ControllerMpd::pushState Oct 16 22:46:01 volumio volumio[945]: info: CoreCommandRouter::servicePushState Oct 16 22:46:01 volumio volumio[945]: verbose: In UPNP mode Oct 16 22:46:01 volumio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":48696,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.50.103:52912/07F01675-E06E-185D-75F8-46B479D214CC/stream.wav","trackType":"wav"} Oct 16 22:46:01 volumio volumio[945]: verbose: CURRENT POSITION 0 Oct 16 22:46:01 volumio volumio[945]: info: CoreStateMachine::syncState stateService play Oct 16 22:46:01 volumio volumio[945]: info: CoreStateMachine::syncState currentStatus stop Oct 16 22:46:01 volumio volumio[945]: info: ------------------------------ 22ms Oct 16 22:46:01 volumio volumio[945]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 22:46:01 volumio volumio[945]: verbose: ControllerMpd::parseTrackInfo Oct 16 22:46:01 volumio volumio[945]: info: ControllerMpd::pushState Oct 16 22:46:01 volumio volumio[945]: info: CoreCommandRouter::servicePushState Oct 16 22:46:01 volumio volumio[945]: verbose: In UPNP mode Oct 16 22:46:01 volumio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":48696,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.50.103:52912/07F01675-E06E-185D-75F8-46B479D214CC/stream.wav","trackType":"wav"} Oct 16 22:46:01 volumio volumio[945]: verbose: CURRENT POSITION 0 Oct 16 22:46:01 volumio volumio[945]: info: CoreStateMachine::syncState stateService play Oct 16 22:46:01 volumio volumio[945]: info: CoreStateMachine::syncState currentStatus play Oct 16 22:46:01 volumio volumio[945]: info: Received an update from plugin. extracting info from payload Oct 16 22:46:01 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:01 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:01 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:01 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:01 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:01 volumio volumio[945]: info: ------------------------------ 13ms Oct 16 22:46:03 volumio volumio[1197]: 67 Oct 16 22:46:03 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:46:03 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:46:03 volumio volumio[945]: info: Oct 16 22:46:03 volumio volumio[945]: ---------------------------- Client requests Volume 67 Oct 16 22:46:03 volumio volumio[945]: info: VolumeController::SetAlsaVolume67 Oct 16 22:46:03 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:03 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:03 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:03 volumio volumio[1197]: [158B blob data] Oct 16 22:46:03 volumio volumio[1197]: {"time":1792183563414,"response":"volume Success"}67 Oct 16 22:46:03 volumio volumio[1197]: 66 Oct 16 22:46:03 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:46:03 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:46:03 volumio volumio[945]: info: Oct 16 22:46:03 volumio volumio[945]: ---------------------------- Client requests Volume 66 Oct 16 22:46:03 volumio volumio[945]: info: VolumeController::SetAlsaVolume66 Oct 16 22:46:03 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:03 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:03 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:03 volumio volumio[1197]: [158B blob data] Oct 16 22:46:03 volumio volumio[1197]: {"time":1792183563638,"response":"volume Success"}66 Oct 16 22:46:03 volumio volumio[1197]: 65 Oct 16 22:46:03 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:46:03 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:46:03 volumio volumio[945]: info: Oct 16 22:46:03 volumio volumio[945]: ---------------------------- Client requests Volume 65 Oct 16 22:46:03 volumio volumio[945]: info: VolumeController::SetAlsaVolume65 Oct 16 22:46:03 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:03 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:03 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:03 volumio volumio[1197]: [158B blob data] Oct 16 22:46:03 volumio volumio[1197]: {"time":1792183563847,"response":"volume Success"}65 Oct 16 22:46:04 volumio volumio[1197]: 64 Oct 16 22:46:04 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:46:04 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:46:04 volumio volumio[945]: info: Oct 16 22:46:04 volumio volumio[945]: ---------------------------- Client requests Volume 64 Oct 16 22:46:04 volumio volumio[945]: info: VolumeController::SetAlsaVolume64 Oct 16 22:46:04 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:04 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:04 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:04 volumio volumio[1197]: [158B blob data] Oct 16 22:46:04 volumio volumio[1197]: {"time":1792183564054,"response":"volume Success"}64 Oct 16 22:46:04 volumio volumio[1197]: 63 Oct 16 22:46:04 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:46:04 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:46:04 volumio volumio[945]: info: Oct 16 22:46:04 volumio volumio[945]: ---------------------------- Client requests Volume 63 Oct 16 22:46:04 volumio volumio[945]: info: VolumeController::SetAlsaVolume63 Oct 16 22:46:04 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:04 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:04 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:04 volumio volumio[1197]: [158B blob data] Oct 16 22:46:04 volumio volumio[1197]: {"time":1792183564271,"response":"volume Success"}63 Oct 16 22:46:04 volumio volumio[1197]: 62 Oct 16 22:46:04 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:46:04 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:46:04 volumio volumio[945]: info: Oct 16 22:46:04 volumio volumio[945]: ---------------------------- Client requests Volume 62 Oct 16 22:46:04 volumio volumio[945]: info: VolumeController::SetAlsaVolume62 Oct 16 22:46:04 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:04 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:04 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:04 volumio volumio[1197]: [158B blob data] Oct 16 22:46:04 volumio volumio[1197]: {"time":1792183564464,"response":"volume Success"}62 Oct 16 22:46:04 volumio volumio[1197]: 61 Oct 16 22:46:04 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:46:04 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:46:04 volumio volumio[945]: info: Oct 16 22:46:04 volumio volumio[945]: ---------------------------- Client requests Volume 61 Oct 16 22:46:04 volumio volumio[945]: info: VolumeController::SetAlsaVolume61 Oct 16 22:46:04 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:04 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:04 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:04 volumio volumio[1197]: [158B blob data] Oct 16 22:46:04 volumio volumio[1197]: {"time":1792183564661,"response":"volume Success"}61 Oct 16 22:46:04 volumio volumio[1197]: 60 Oct 16 22:46:04 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:46:04 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:46:04 volumio volumio[945]: info: Oct 16 22:46:04 volumio volumio[945]: ---------------------------- Client requests Volume 60 Oct 16 22:46:04 volumio volumio[945]: info: VolumeController::SetAlsaVolume60 Oct 16 22:46:04 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:04 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:04 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:04 volumio volumio[1197]: [158B blob data] Oct 16 22:46:04 volumio volumio[1197]: {"time":1792183564868,"response":"volume Success"}60 Oct 16 22:46:05 volumio volumio[1197]: 59 Oct 16 22:46:05 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:46:05 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:46:05 volumio volumio[945]: info: Oct 16 22:46:05 volumio volumio[945]: ---------------------------- Client requests Volume 59 Oct 16 22:46:05 volumio volumio[945]: info: VolumeController::SetAlsaVolume59 Oct 16 22:46:05 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:05 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:05 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:05 volumio volumio[1197]: [158B blob data] Oct 16 22:46:05 volumio volumio[1197]: {"time":1792183565095,"response":"volume Success"}59 Oct 16 22:46:05 volumio volumio[1197]: 58 Oct 16 22:46:05 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:46:05 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:46:05 volumio volumio[945]: info: Oct 16 22:46:05 volumio volumio[945]: ---------------------------- Client requests Volume 58 Oct 16 22:46:05 volumio volumio[945]: info: VolumeController::SetAlsaVolume58 Oct 16 22:46:05 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:05 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:05 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:05 volumio volumio[1197]: [158B blob data] Oct 16 22:46:05 volumio volumio[1197]: {"time":1792183565280,"response":"volume Success"}58 Oct 16 22:46:05 volumio volumio[1197]: 57 Oct 16 22:46:05 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:46:05 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:46:05 volumio volumio[945]: info: Oct 16 22:46:05 volumio volumio[945]: ---------------------------- Client requests Volume 57 Oct 16 22:46:05 volumio volumio[945]: info: VolumeController::SetAlsaVolume57 Oct 16 22:46:05 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:05 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:05 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:05 volumio volumio[1197]: [158B blob data] Oct 16 22:46:05 volumio volumio[1197]: {"time":1792183565495,"response":"volume Success"}57 Oct 16 22:46:05 volumio volumio[1197]: 56 Oct 16 22:46:05 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:46:05 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:46:05 volumio volumio[945]: info: Oct 16 22:46:05 volumio volumio[945]: ---------------------------- Client requests Volume 56 Oct 16 22:46:05 volumio volumio[945]: info: VolumeController::SetAlsaVolume56 Oct 16 22:46:05 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:05 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:05 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:05 volumio volumio[1197]: [158B blob data] Oct 16 22:46:05 volumio volumio[1197]: {"time":1792183565673,"response":"volume Success"}56 Oct 16 22:46:05 volumio volumio[1197]: 55 Oct 16 22:46:05 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:46:05 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:46:05 volumio volumio[945]: info: Oct 16 22:46:05 volumio volumio[945]: ---------------------------- Client requests Volume 55 Oct 16 22:46:05 volumio volumio[945]: info: VolumeController::SetAlsaVolume55 Oct 16 22:46:05 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:05 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:05 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:05 volumio volumio[1197]: [158B blob data] Oct 16 22:46:05 volumio volumio[1197]: {"time":1792183565868,"response":"volume Success"}55 Oct 16 22:46:06 volumio volumio[1197]: 54 Oct 16 22:46:06 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:46:06 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:46:06 volumio volumio[945]: info: Oct 16 22:46:06 volumio volumio[945]: ---------------------------- Client requests Volume 54 Oct 16 22:46:06 volumio volumio[945]: info: VolumeController::SetAlsaVolume54 Oct 16 22:46:06 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:06 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:06 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:06 volumio volumio[1197]: [158B blob data] Oct 16 22:46:06 volumio volumio[1197]: {"time":1792183566093,"response":"volume Success"}54 Oct 16 22:46:06 volumio volumio[1197]: 53 Oct 16 22:46:06 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:46:06 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:46:06 volumio volumio[945]: info: Oct 16 22:46:06 volumio volumio[945]: ---------------------------- Client requests Volume 53 Oct 16 22:46:06 volumio volumio[945]: info: VolumeController::SetAlsaVolume53 Oct 16 22:46:06 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:06 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:06 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:06 volumio volumio[1197]: [158B blob data] Oct 16 22:46:06 volumio volumio[1197]: {"time":1792183566303,"response":"volume Success"}53 Oct 16 22:46:06 volumio volumio[1197]: 52 Oct 16 22:46:06 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:46:06 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:46:06 volumio volumio[945]: info: Oct 16 22:46:06 volumio volumio[945]: ---------------------------- Client requests Volume 52 Oct 16 22:46:06 volumio volumio[945]: info: VolumeController::SetAlsaVolume52 Oct 16 22:46:06 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:06 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:06 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:06 volumio volumio[1197]: [158B blob data] Oct 16 22:46:06 volumio volumio[1197]: {"time":1792183566483,"response":"volume Success"}52 Oct 16 22:46:06 volumio volumio[1197]: 51 Oct 16 22:46:06 volumio volumio[1197]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 16 22:46:06 volumio volumio[1197]: Dload Upload Total Spent Left Speed Oct 16 22:46:06 volumio volumio[945]: info: Oct 16 22:46:06 volumio volumio[945]: ---------------------------- Client requests Volume 51 Oct 16 22:46:06 volumio volumio[945]: info: VolumeController::SetAlsaVolume51 Oct 16 22:46:06 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:06 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:06 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:06 volumio volumio[1197]: [158B blob data] Oct 16 22:46:06 volumio volumio[1197]: {"time":1792183566671,"response":"volume Success"}51 Oct 16 22:46:21 volumio volumio[945]: info: Oct 16 22:46:21 volumio volumio[945]: ---------------------------- MPD announces state update: player Oct 16 22:46:21 volumio volumio[945]: info: ControllerMpd::getState Oct 16 22:46:21 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Oct 16 22:46:21 volumio volumio[945]: info: sendMpdCommand status took 1 milliseconds Oct 16 22:46:21 volumio volumio[945]: verbose: ControllerMpd::parseState Oct 16 22:46:21 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 22:46:21 volumio volumio[945]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 22:46:21 volumio volumio[945]: verbose: ControllerMpd::parseTrackInfo Oct 16 22:46:21 volumio volumio[945]: info: ControllerMpd::pushState Oct 16 22:46:21 volumio volumio[945]: info: CoreCommandRouter::servicePushState Oct 16 22:46:21 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:21 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:21 volumio volumio[945]: info: CorePlayQueue::getTrack 0 Oct 16 22:46:21 volumio volumio[945]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.50.103:52912/07F01675-E06E-185D-75F8-46B479D214CC/stream.wav","trackType":"wav"} Oct 16 22:46:21 volumio volumio[945]: verbose: CURRENT POSITION 0 Oct 16 22:46:21 volumio volumio[945]: info: CoreStateMachine::syncState stateService stop Oct 16 22:46:21 volumio volumio[945]: info: CoreStateMachine::syncState currentStatus play Oct 16 22:46:21 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:21 volumio volumio[945]: info: CoreStateMachine::stPlaybackTimer Oct 16 22:46:21 volumio volumio[945]: info: ------------------------------ 11ms Oct 16 22:46:21 volumio volumio[945]: info: Clearing queue after UPNP request Oct 16 22:46:22 volumio volumio[945]: info: CoreStateMachine::ClearQueue Oct 16 22:46:22 volumio volumio[945]: info: CoreStateMachine::stop Oct 16 22:46:22 volumio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 22:46:22 volumio volumio[945]: info: CorePlayQueue::clearPlayQueue Oct 16 22:46:22 volumio volumio[945]: info: CorePlayQueue::saveQueue Oct 16 22:46:22 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:22 volumio volumio[945]: info: CoreCommandRouter::volumioPushQueue Oct 16 22:46:22 volumio volumio[945]: info: Oct 16 22:46:22 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:22 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:22 volumio volumio[945]: error: updateQueue error: null Oct 16 22:46:22 volumio volumio[945]: info: ------------------------------ 1ms Oct 16 22:46:22 volumio volumio[945]: info: Starting UPNP Playback Oct 16 22:46:22 volumio volumio[945]: info: Preparing playback through UPNP Oct 16 22:46:22 volumio volumio[945]: info: CoreCommandRouter::volumioGetState Oct 16 22:46:22 volumio volumio[945]: info: CorePlayQueue::getTrack 0 Oct 16 22:46:22 volumio volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 16 22:46:22 volumio volumio[945]: info: Oct 16 22:46:22 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:22 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:22 volumio volumio[945]: info: ------------------------------ 2ms Oct 16 22:46:22 volumio volumio[945]: info: Oct 16 22:46:22 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:22 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:22 volumio volumio[945]: info: Oct 16 22:46:22 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:22 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:22 volumio volumio[945]: info: ------------------------------ 2ms Oct 16 22:46:22 volumio volumio[945]: info: ------------------------------ 2ms Oct 16 22:46:22 volumio volumio[945]: info: Oct 16 22:46:22 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:22 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:22 volumio volumio[945]: info: ------------------------------ 1ms Oct 16 22:46:22 volumio volumio[945]: info: Oct 16 22:46:22 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:22 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:22 volumio volumio[945]: info: ------------------------------ 2ms Oct 16 22:46:22 volumio volumio[945]: info: Oct 16 22:46:22 volumio volumio[945]: ---------------------------- MPD announces system playlist update Oct 16 22:46:22 volumio volumio[945]: info: Ignoring MPD Status Update Oct 16 22:46:22 volumio volumio[945]: info: ------------------------------ 1ms Oct 16 22:46:22 volumio volumio[945]: info: Oct 16 22:46:22 volumio volumio[945]: ---------------------------- MPD announces state update: player Oct 16 22:46:22 volumio volumio[945]: info: ControllerMpd::getState Oct 16 22:46:22 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Oct 16 22:46:22 volumio volumio[945]: info: Oct 16 22:46:22 volumio volumio[945]: ---------------------------- MPD announces state update: player Oct 16 22:46:22 volumio volumio[945]: info: sendMpdCommand status took 18 milliseconds Oct 16 22:46:22 volumio volumio[945]: info: ControllerMpd::getState Oct 16 22:46:22 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Oct 16 22:46:22 volumio volumio[945]: verbose: ControllerMpd::parseState Oct 16 22:46:22 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 22:46:22 volumio volumio[945]: info: sendMpdCommand status took 2 milliseconds Oct 16 22:46:22 volumio volumio[945]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 22:46:22 volumio volumio[945]: verbose: ControllerMpd::parseState Oct 16 22:46:22 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 22:46:22 volumio volumio[945]: verbose: ControllerMpd::parseTrackInfo Oct 16 22:46:22 volumio volumio[945]: info: ControllerMpd::pushState Oct 16 22:46:22 volumio volumio[945]: info: CoreCommandRouter::servicePushState Oct 16 22:46:22 volumio volumio[945]: verbose: In UPNP mode Oct 16 22:46:22 volumio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":24348,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.50.103:52962/26BAEC48-84DB-4503-A462-256F5B973BB3/stream.wav","trackType":"wav"} Oct 16 22:46:22 volumio volumio[945]: verbose: CURRENT POSITION 0 Oct 16 22:46:22 volumio volumio[945]: info: CoreStateMachine::syncState stateService play Oct 16 22:46:22 volumio volumio[945]: info: CoreStateMachine::syncState currentStatus stop Oct 16 22:46:22 volumio volumio[945]: info: ------------------------------ 23ms Oct 16 22:46:22 volumio volumio[945]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 22:46:22 volumio volumio[945]: verbose: ControllerMpd::parseTrackInfo Oct 16 22:46:22 volumio volumio[945]: info: ControllerMpd::pushState Oct 16 22:46:22 volumio volumio[945]: info: CoreCommandRouter::servicePushState Oct 16 22:46:22 volumio volumio[945]: verbose: In UPNP mode Oct 16 22:46:22 volumio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":24348,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"foobar2000 audio stream","artist":null,"album":null,"uri":"http://192.168.50.103:52962/26BAEC48-84DB-4503-A462-256F5B973BB3/stream.wav","trackType":"wav"} Oct 16 22:46:22 volumio volumio[945]: verbose: CURRENT POSITION 0 Oct 16 22:46:22 volumio volumio[945]: info: CoreStateMachine::syncState stateService play Oct 16 22:46:22 volumio volumio[945]: info: CoreStateMachine::syncState currentStatus play Oct 16 22:46:22 volumio volumio[945]: info: Received an update from plugin. extracting info from payload Oct 16 22:46:22 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:22 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 22:46:22 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:22 volumio volumio[945]: info: CoreStateMachine::pushState Oct 16 22:46:22 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Oct 16 22:46:22 volumio volumio[945]: info: ------------------------------ 12ms Oct 16 22:46:44 volumio volumio[945]: verbose: New Socket.io Connection to 192.168.50.104 from 192.168.50.103 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/27.0.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 5 Oct 16 22:46:44 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 22:46:44 volumio volumio[945]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 22:46:44 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 22:46:44 volumio volumio[945]: info: CoreCommandRouter::volumioGetState Oct 16 22:46:44 volumio volumio[945]: info: CoreCommandRouter::volumioGetState Oct 16 22:46:44 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 16 22:46:44 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 22:46:44 volumio volumio[945]: info: Received Get System Info Oct 16 22:46:44 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 22:46:44 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 22:46:44 volumio volumio[945]: info: Discovery: Getting this device information Oct 16 22:46:44 volumio volumio[945]: info: CoreCommandRouter::volumioGetState Oct 16 22:46:44 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 22:46:44 volumio volumio[945]: info: CoreCommandRouter::volumioGetState Oct 16 22:46:44 volumio volumio[945]: info: Listing playlists Oct 16 22:46:44 volumio volumio[945]: info: CoreCommandRouter::volumioGetQueue Oct 16 22:46:44 volumio volumio[945]: info: CoreStateMachine::getQueue Oct 16 22:46:44 volumio volumio[945]: info: CorePlayQueue::getQueue Oct 16 22:46:44 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 16 22:46:49 volumio volumio[945]: info: CoreCommandRouter::volumioGetState Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 22:46:54 volumio volumio[945]: info: Retrieving Cloud Streaming UI Oct 16 22:46:54 volumio volumio[945]: info: Getting Tidal Cloud Configuration Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 22:46:54 volumio volumio[945]: info: Getting Qobuz Cloud Configuration Oct 16 22:46:54 volumio volumio[945]: info: Asking plugin for UI Config Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 22:46:54 volumio volumio[945]: info: Getting Spotify Cloud Configuration Oct 16 22:46:54 volumio volumio[945]: info: Asking plugin for UI Config Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 22:46:54 volumio volumio[945]: info: Saving Spotify Acccount Oct 16 22:46:54 volumio volumio[945]: info: Got Tidal Cloud Configuration Oct 16 22:46:54 volumio volumio[945]: info: Got it Oct 16 22:46:54 volumio volumio[945]: info: Got it Oct 16 22:46:54 volumio volumio[945]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 22:46:54 volumio volumio[945]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 22:46:54 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 16 22:46:55 volumio volumio[945]: info: Disabling MyMusic plugin upnp Oct 16 22:46:55 volumio sudo[21326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 16 22:46:55 volumio sudo[21326]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 22:46:55 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 16 22:46:55 volumio volumio[945]: error: Upnp client error: Error: This socket has been ended by the other party Oct 16 22:46:56 volumio volumio[945]: info: Enabling MyMusic plugin upnp Oct 16 22:46:56 volumio volumio[945]: info: Enabling plugin upnp Oct 16 22:46:56 volumio volumio[945]: info: Loading plugin "upnp"... Oct 16 22:46:56 volumio volumio[945]: info: [1792183616922] Starting Upmpd Daemon Oct 16 22:46:56 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 22:46:56 volumio volumio[945]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 22:46:56 volumio volumio[945]: Error: listen EADDRINUSE: address already in use :::6599 Oct 16 22:46:56 volumio volumio[945]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Oct 16 22:46:56 volumio volumio[945]: at listenInCluster (net.js:1366:12) Oct 16 22:46:56 volumio volumio[945]: at Server.listen (net.js:1452:7) Oct 16 22:46:56 volumio volumio[945]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 16 22:46:56 volumio volumio[945]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 16 22:46:56 volumio volumio[945]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 16 22:46:56 volumio volumio[945]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 16 22:46:56 volumio volumio[945]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 16 22:46:56 volumio volumio[945]: code: 'EADDRINUSE', Oct 16 22:46:56 volumio volumio[945]: errno: -98, Oct 16 22:46:56 volumio volumio[945]: syscall: 'listen', Oct 16 22:46:56 volumio volumio[945]: address: '::', Oct 16 22:46:56 volumio volumio[945]: port: 6599 Oct 16 22:46:56 volumio volumio[945]: } Oct 16 22:46:56 volumio volumio[945]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 22:46:57 volumio sudo[21337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-16 22:45 Oct 16 22:46:57 volumio sudo[21337]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"