-- Logs begin at Wed 2025-06-25 11:17:01 UTC, end at Thu 2025-06-26 02:13:53 UTC. -- Jun 26 02:12:04 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:04 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:04 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:04.796 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:04 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:04.796 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:04 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:04.796 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:04 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:04.801 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:04 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:04 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:04 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:04 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:04 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:04.947 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:04 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:04.947 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:05 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:05 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:05 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:05.095 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:05 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:05.095 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:05 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:05.096 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:05 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:05 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:05 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:05 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:05 ifi-phmidsd-lsq-test ethernet-detect[445]: No change in avahi-daemon interfaces. Skipping restart. Jun 26 02:12:05 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:05.245 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:05 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:05.245 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:06 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:06 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:06 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:06.305 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:06 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:06.305 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:06 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:06.305 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:06 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:06 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:06 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:06 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:06 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:06.454 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:06 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:06.454 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:06 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:06 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:06.906 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:06 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:06.906 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:06 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:06.906 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:06 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:07 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:07 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:07 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:07 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:07 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:07.056 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:07 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:07.057 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:08 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:08 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:08 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:08.202 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:08 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:08.202 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:08 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:08.202 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:08 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:08 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:08 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:08 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:08 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:08.352 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:08 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:08.352 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:08 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:08 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:08 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:08.493 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:08 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:08.494 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:08 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:08.494 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:08 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:08 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:08 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:08 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:08 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:08.643 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:08 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:08.643 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:09 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:09 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:09 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:09.110 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:09 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:09.110 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:09 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:09.110 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:09 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:09 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:09 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:09 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:09 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:09.261 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:09 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:09.261 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:09 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:09 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:09 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:09.411 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:09 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:09.411 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:09 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:09.411 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:09 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:09 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:09 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:09 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:09 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:09.561 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:09 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:09.562 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:09 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:09 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:10 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:10.012 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:10 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:10.012 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:10 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:10.012 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:10 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:10 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:10 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:10 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:10 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:10.162 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:10 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:10.162 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:10 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:10 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:10 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:10.313 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:10 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:10.313 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:10 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:10.313 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:10 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:10 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:10 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:10 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:10 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:10.464 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:10 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:10.464 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:15 ifi-phmidsd-lsq-test ethernet-detect[445]: No change in avahi-daemon interfaces. Skipping restart. Jun 26 02:12:15 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:15 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:15 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:15.819 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:15 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:15.819 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:15 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:15.819 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:15 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:15 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:15 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:15 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:15 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:15.970 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:15 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:15.970 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:16 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:16 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:16 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:16.120 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:16 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:16.120 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:16 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:16.121 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:16 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:16 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:16 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:16 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:16 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:16.270 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:16 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:16.270 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:17 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:17 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:17 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:17.699 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:17 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:17.699 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:17 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:17.700 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:17 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:17 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:17 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:17 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:17 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:17.849 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:17 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:17.850 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:19 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:19 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:19 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:19.253 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:19 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:19.253 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:19 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:19.253 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:19 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:19.253 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:19 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:19 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:19 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:19 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:19 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:19.404 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:19 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:19.404 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:19 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:19 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:19 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:19.525 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:19 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:19.526 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:19 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:19.526 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:19 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:19 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:19 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:19 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:19 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:19.675 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:19 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:19.675 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:19 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:19 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:19 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:19.830 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:19 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:19.830 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:19 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:19.830 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:19 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:19 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:19 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:19 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:19 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:19.980 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:19 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:19.980 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:21 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:21 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:21 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:21.989 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:21 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:21.989 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:21 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:21.989 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:22 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:22.139 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:22 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:22.139 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:22 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:22.289 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:22 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:22.289 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:22 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:22.289 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:22 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:22.439 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:22 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:22.439 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:22 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:22.621 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:22 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:22.621 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:22 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:22.621 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:22 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:22.772 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:22 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:22.772 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:22 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:22.910 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:22 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:22.910 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:22 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:22.910 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:22 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:22.910 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:22 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:23 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:23.060 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:23 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:23.060 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:25 ifi-phmidsd-lsq-test ethernet-detect[445]: No change in avahi-daemon interfaces. Skipping restart. Jun 26 02:12:27 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:27 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:27 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:27.833 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:27 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:27.833 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:27 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:27.833 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:27 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:27 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:27 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:27 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:27 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:27.983 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:27 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:27.983 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:28 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:28 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:28 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:28 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:28 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:28.142 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:28 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:28.142 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:28 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:28.142 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:28 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:28 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:28 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:28 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:28 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:28 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:28 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:28 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:28.292 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:28 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:28.292 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:29 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:29 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:29 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:29.043 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:29 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:29.044 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:29 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:29.044 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:29 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:29 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:29 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:29 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:29 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:29.193 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:29 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:29.193 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:29 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:29 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:29 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:29.345 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:29 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:29.345 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:29 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:29.345 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:29 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:29 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:29 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:29 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:29 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:29.494 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:29 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:29.494 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:29 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:29 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:29 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:29.946 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:29 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:29.946 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:29 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:29.946 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:30 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:30 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:30 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:30 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:30 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:30.095 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:30 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:30.096 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:30 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:30 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:30 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:30.247 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:30 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:30.247 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:30 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:30.247 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:30 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:30 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:30 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:30 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:30 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:30.397 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:30 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:30.398 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:30 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:30 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:30 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:30.900 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:30 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:30.901 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:30 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:30.901 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:30 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:30 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:30 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:30 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:31 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:31.050 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:31 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:31.051 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:31 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:31 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:31 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:31.239 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:31 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:31.240 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:31 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:31.240 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:31 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:31 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:31 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:31 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:31 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:31.389 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:31 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:31.390 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:31 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:31 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:31 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:31.815 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:31 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:31.816 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:31 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:31.816 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:31 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:31 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:31 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:31 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:31 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:31.965 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:31 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:31.966 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:32 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:32 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:32 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:32.736 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:32 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:32.736 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:32 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:32.736 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:32 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:32.737 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:32 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:32 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:32 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:32 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:32 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:32.887 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:32 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:32.887 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:35 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:35 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:35 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:35.247 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:35 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:35.247 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:35 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:35.247 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:35 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:35 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:35 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:35 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:35 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:35.397 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:35 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:35.397 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:35 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:35 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:35 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:35.514 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:35 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:35.514 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:35 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:35.514 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:35 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:35 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:35 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:35 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:35 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:35.665 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:35 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:35.665 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:35 ifi-phmidsd-lsq-test ethernet-detect[445]: No change in avahi-daemon interfaces. Skipping restart. Jun 26 02:12:36 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:36 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:36 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:36.129 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:36 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:36.129 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:36 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:36.129 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:36 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:36 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:36 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:36 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:36 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:36.279 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:36 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:36.279 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:36 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:36 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:36 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:36.420 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:36 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:36.420 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:36 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:36.420 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:36 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:36 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:36 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:36 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:36 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:36.570 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:36 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:36.570 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:37 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:37 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:37 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:37.961 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:37 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:37.961 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:37 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:37.961 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:38 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:38 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:38 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:38 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:38 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:38.111 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:38 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:38.112 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:38 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:38 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:38 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:38.262 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:38 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:38.262 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:38 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:38.262 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:38 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:38 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:38 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:38 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:38 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:38.412 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:38 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:38.412 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:38 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:38 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:38 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:38.863 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:38 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:38.863 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:38 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:38.863 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:38 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:38 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:38 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:38 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:39 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:39.013 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:39 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:39.013 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:39 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:39 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:39 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:39.164 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:39 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:39.164 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:39 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:39.164 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:39 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:39 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:39 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:39 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:39 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:39 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:39 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:39.314 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:39 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:39.314 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:39 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:39 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:39 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:40 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:40 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:40 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:40.406 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:40 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:40.406 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:40 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:40.406 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:40 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:40 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:40 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:40 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:40 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:40.556 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:40 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:40.556 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:40 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:40 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:40 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:40.720 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:40 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:40.720 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:40 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:40.720 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:40 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:40 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:40 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:40 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:40 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:40.870 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:40 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:40.870 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:41 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:41 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:41 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:41.323 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:41 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:41.324 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:41 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:41.324 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:41 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:41 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:41 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:41 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:41 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:41.473 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:41 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:41.473 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:41 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:41 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:41 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:41.646 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:41 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:41.646 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:41 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:41.646 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:41 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:41 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:41 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:41 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:41 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:41.796 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:41 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:41.796 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume syncing... Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume syncing... Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume syncing... Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume syncing... Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: get alsa volume 8 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: sync volume vol success! Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:9 Jun 26 02:12:42 ifi-phmidsd-lsq-test demo[1017]: update vol for server:8 Jun 26 02:12:42 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:12:42 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:12:42 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:42.859 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:12:42 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:42.860 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:12:42 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:42.860 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:42 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:42 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:42 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:42 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:43 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:43.009 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:12:43 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:43.009 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:12:43 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:43 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:43 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:43.171 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:12:43 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:43.171 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:12:43 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:43.171 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:12:43 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:43 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:43 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:43 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:43 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:12:43 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:12:43 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:12:43 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:12:43 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:12:43 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:43.321 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:12:43 ifi-phmidsd-lsq-test play[16158]: 20250626 02:12:43.321 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:12:45 ifi-phmidsd-lsq-test ethernet-detect[445]: No change in avahi-daemon interfaces. Skipping restart. Jun 26 02:12:56 ifi-phmidsd-lsq-test ethernet-detect[445]: No change in avahi-daemon interfaces. Skipping restart. Jun 26 02:13:02 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:9,0 Jun 26 02:13:02 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume9 Jun 26 02:13:02 ifi-phmidsd-lsq-test play[16158]: 20250626 02:13:02.095 [16209.16209] TRACE SampleApp: Volume changed: 9 Jun 26 02:13:02 ifi-phmidsd-lsq-test play[16158]: 20250626 02:13:02.096 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 9 Jun 26 02:13:02 ifi-phmidsd-lsq-test play[16158]: 20250626 02:13:02.096 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:13:02 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:13:02 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:13:02 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:13:02 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:13:02 ifi-phmidsd-lsq-test play[16158]: 20250626 02:13:02.245 [16209.16209] INFO SampleApp: Playback volume changed: 9 Jun 26 02:13:02 ifi-phmidsd-lsq-test play[16158]: 20250626 02:13:02.246 [16209.16209] TRACE SampleApp: Setting volume: 9 Jun 26 02:13:02 ifi-phmidsd-lsq-test volumio[15311]: info: MetaClient: Get message: request:setvolume:8,0 Jun 26 02:13:02 ifi-phmidsd-lsq-test volumio[15311]: info: VolumeController::SetAlsaVolume8 Jun 26 02:13:02 ifi-phmidsd-lsq-test play[16158]: 20250626 02:13:02.396 [16209.16209] TRACE SampleApp: Volume changed: 8 Jun 26 02:13:02 ifi-phmidsd-lsq-test play[16158]: 20250626 02:13:02.397 [16209.16209] INFO VolumeManager: [0xab149e68]: Setting new playback volume: 8 Jun 26 02:13:02 ifi-phmidsd-lsq-test play[16158]: 20250626 02:13:02.397 [16209.16209] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:13:02 ifi-phmidsd-lsq-test volumio[15311]: info: CoreStateMachine::pushState Jun 26 02:13:02 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:13:02 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::volumioPushState Jun 26 02:13:02 ifi-phmidsd-lsq-test volumio[15311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:13:02 ifi-phmidsd-lsq-test play[16158]: 20250626 02:13:02.546 [16209.16209] INFO SampleApp: Playback volume changed: 8 Jun 26 02:13:02 ifi-phmidsd-lsq-test play[16158]: 20250626 02:13:02.546 [16209.16209] TRACE SampleApp: Setting volume: 8 Jun 26 02:13:06 ifi-phmidsd-lsq-test ethernet-detect[445]: No change in avahi-daemon interfaces. Skipping restart. Jun 26 02:13:16 ifi-phmidsd-lsq-test ethernet-detect[445]: No change in avahi-daemon interfaces. Skipping restart. Jun 26 02:13:26 ifi-phmidsd-lsq-test ethernet-detect[445]: No change in avahi-daemon interfaces. Skipping restart. Jun 26 02:13:28 ifi-phmidsd-lsq-test sshd[20954]: Accepted password for volumio from 192.168.1.159 port 54090 ssh2 Jun 26 02:13:28 ifi-phmidsd-lsq-test sshd[20954]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jun 26 02:13:28 ifi-phmidsd-lsq-test systemd-logind[447]: New session 21 of user volumio. Jun 26 02:13:28 ifi-phmidsd-lsq-test systemd[1]: Started Session 21 of user volumio. Jun 26 02:13:28 ifi-phmidsd-lsq-test sshd[21023]: Received disconnect from 192.168.1.159 port 54090:11: disconnected by user Jun 26 02:13:28 ifi-phmidsd-lsq-test sshd[21023]: Disconnected from user volumio 192.168.1.159 port 54090 Jun 26 02:13:28 ifi-phmidsd-lsq-test sshd[20954]: pam_unix(sshd:session): session closed for user volumio Jun 26 02:13:29 ifi-phmidsd-lsq-test systemd[1]: session-21.scope: Succeeded. Jun 26 02:13:29 ifi-phmidsd-lsq-test systemd-logind[447]: Session 21 logged out. Waiting for processes to exit. Jun 26 02:13:29 ifi-phmidsd-lsq-test systemd-logind[447]: Removed session 21. Jun 26 02:13:33 ifi-phmidsd-lsq-test sudo[21152]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl restart volumio Jun 26 02:13:33 ifi-phmidsd-lsq-test sudo[21152]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Jun 26 02:13:33 ifi-phmidsd-lsq-test systemd[1]: Stopping Volumio Backend Module... Jun 26 02:13:33 ifi-phmidsd-lsq-test systemd[1]: Started dynamicswap service. Jun 26 02:13:33 ifi-phmidsd-lsq-test volumio-remote-updater[491]: [2025-06-26 02:13:33] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 26 02:13:33 ifi-phmidsd-lsq-test volumio-remote-updater[491]: [2025-06-26 02:13:33] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 26 02:13:33 ifi-phmidsd-lsq-test node[513]: Closing connection with the client volumio Jun 26 02:13:33 ifi-phmidsd-lsq-test systemd[1]: volumio.service: Main process exited, code=killed, status=15/TERM Jun 26 02:13:33 ifi-phmidsd-lsq-test systemd[1]: volumio.service: Succeeded. Jun 26 02:13:33 ifi-phmidsd-lsq-test systemd[1]: Stopped Volumio Backend Module. Jun 26 02:13:33 ifi-phmidsd-lsq-test systemd[1]: dynamicswap.service: Succeeded. Jun 26 02:13:33 ifi-phmidsd-lsq-test systemd[1]: Started Volumio Backend Module. Jun 26 02:13:33 ifi-phmidsd-lsq-test sudo[21152]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:36 ifi-phmidsd-lsq-test volumio[21161]: info: ------------------------------------------- Jun 26 02:13:36 ifi-phmidsd-lsq-test volumio[21161]: info: ----- Volumio3 ---- Jun 26 02:13:36 ifi-phmidsd-lsq-test volumio[21161]: info: ------------------------------------------- Jun 26 02:13:36 ifi-phmidsd-lsq-test volumio[21161]: info: ----- System startup ---- Jun 26 02:13:36 ifi-phmidsd-lsq-test volumio[21161]: info: ------------------------------------------- Jun 26 02:13:36 ifi-phmidsd-lsq-test volumio[21161]: info: ConvSwitch: Client Start Jun 26 02:13:36 ifi-phmidsd-lsq-test volumio[21161]: info: MetaClient: Client Start Jun 26 02:13:36 ifi-phmidsd-lsq-test node[513]: A new connection has been established. Jun 26 02:13:36 ifi-phmidsd-lsq-test ethernet-detect[445]: No change in avahi-daemon interfaces. Skipping restart. Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Plugin folders cleanup Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Scanning into folder /volumio/app/plugins/ Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Scanning category audio_interface Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Scanning category miscellanea Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Scanning category music_service Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Scanning category plugins.json Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Scanning category system_controller Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Scanning category user_interface Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Scanning into folder /data/plugins/ Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Scanning category .git Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Scanning category music_service Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Plugin folders cleanup completed Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: ------------------------------------------- Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: ----- Core plugins startup ---- Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: ------------------------------------------- Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugins from folder /volumio/app/plugins/ Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Adding plugin networkaudiod to MyMusic Plugins Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Adding plugin qobuz_connect to MyMusic Plugins Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Adding plugin spotify_connect to MyMusic Plugins Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Adding plugin tidal_connect to MyMusic Plugins Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Adding plugin upnp to MyMusic Plugins Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Adding plugin airplay1_switch to MyMusic Plugins Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Adding plugin airplay2_switch to MyMusic Plugins Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Adding plugin roon_ready to MyMusic Plugins Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugins from folder /data/plugins/ Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "system"... Jun 26 02:13:37 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "appearance"... Jun 26 02:13:38 ifi-phmidsd-lsq-test volumio-remote-updater[491]: [2025-06-26 02:13:38] [connect] Successful connection Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "network"... Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: Refreshing Cached IP Addresses Jun 26 02:13:39 ifi-phmidsd-lsq-test sudo[21307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 26 02:13:39 ifi-phmidsd-lsq-test sudo[21307]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:39 ifi-phmidsd-lsq-test sudo[21307]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:39 ifi-phmidsd-lsq-test sudo[21309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 26 02:13:39 ifi-phmidsd-lsq-test sudo[21309]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:39 ifi-phmidsd-lsq-test sudo[21309]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "services"... Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "alsa_controller"... Jun 26 02:13:39 ifi-phmidsd-lsq-test sudo[21322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 26 02:13:39 ifi-phmidsd-lsq-test sudo[21322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "wizard"... Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "networkfs"... Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: Starting Udev Watcher for removable devices Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: error: Cannot associate FS Label, not mounting Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: error: Cannot associate FS Label, not mounting Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: Ignoring mount for partition: BOOT Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: Ignoring mount for partition: archivefs Jun 26 02:13:39 ifi-phmidsd-lsq-test sudo[21322]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: Ignoring mount for partition: rootfs Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "volumio_command_line_client"... Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "networkaudiod"... Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: [1750904019784] Starting NAA Service Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "tidal_connect"... Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: [1750904019818] Starting Tidal Connect Service Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "upnp"... Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: [1750904019831] Starting Upmpd Daemon Jun 26 02:13:39 ifi-phmidsd-lsq-test sudo[21353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart networkaudiod.service Jun 26 02:13:39 ifi-phmidsd-lsq-test sudo[21353]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: Starting Upmpd Daemon Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: error: ATTENTION!!!: Plugin upnp does not return adequate promise from onVolumioStart: please update! Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "my_music"... Jun 26 02:13:39 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "mpd"... Jun 26 02:13:39 ifi-phmidsd-lsq-test systemd[1]: Stopping Network Audio Adapter daemon... Jun 26 02:13:39 ifi-phmidsd-lsq-test systemd[1]: networkaudiod.service: Succeeded. Jun 26 02:13:39 ifi-phmidsd-lsq-test systemd[1]: Stopped Network Audio Adapter daemon. Jun 26 02:13:39 ifi-phmidsd-lsq-test systemd[1]: Started Network Audio Adapter daemon. Jun 26 02:13:39 ifi-phmidsd-lsq-test sudo[21353]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:39 ifi-phmidsd-lsq-test networkaudiod[21357]: [/usr/sbin/networkaudiod] (21357): networkaudiod Copyright (C) 2011-2020 Jussi Laako / Signalyst. All rights reserved. Jun 26 02:13:39 ifi-phmidsd-lsq-test networkaudiod[21357]: [/usr/sbin/networkaudiod] (21357): create alsa engine Jun 26 02:13:39 ifi-phmidsd-lsq-test networkaudiod[21357]: [/usr/sbin/networkaudiod] (21357): listen discovery on :: Jun 26 02:13:39 ifi-phmidsd-lsq-test networkaudiod[21357]: [/usr/sbin/networkaudiod] (21357): clSocket::SetOption(): setsockopt(..., 41,20, ...): No such device Jun 26 02:13:39 ifi-phmidsd-lsq-test networkaudiod[21357]: [/usr/sbin/networkaudiod] (21357): Socket failure, falling back to IPv4-only! Jun 26 02:13:40 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "upnp_browser"... Jun 26 02:13:40 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "qobuz_connect"... Jun 26 02:13:40 ifi-phmidsd-lsq-test volumio[21161]: info: [1750904020859] Starting Qobuz Connect Service Jun 26 02:13:40 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "alarm-clock"... Jun 26 02:13:40 ifi-phmidsd-lsq-test networkaudiod[21357]: [/usr/sbin/networkaudiod] (21357): listen discovery on 0.0.0.0 Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "last_100"... Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "roon_ready"... Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Starting RoonReady Sync Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "webradio"... Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "i2s_dacs"... Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: I2S DAC not set, start Auto-detection Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "volumiodiscovery"... Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: *** WARNING *** For more information see Jun 26 02:13:41 ifi-phmidsd-lsq-test node[21161]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: *** WARNING *** For more information see Jun 26 02:13:41 ifi-phmidsd-lsq-test node[21161]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 26 02:13:41 ifi-phmidsd-lsq-test node[21161]: *** WARNING *** For more information see Jun 26 02:13:41 ifi-phmidsd-lsq-test node[21161]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 26 02:13:41 ifi-phmidsd-lsq-test node[21161]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 26 02:13:41 ifi-phmidsd-lsq-test node[21161]: *** WARNING *** For more information see Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Discovery: Started advertising with name: lsq-test Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "spotify_connect"... Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: [1750904021430] Starting Spotify Connect Service Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Plugin airplay1_switch is not enabled Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "airplay2_switch"... Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "airplay_emulation"... Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Starting Shairport Sync Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "outputs"... Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "albumart"... Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Plugin example_plugin is not enabled Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "inputs"... Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "updater_comm"... Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Plugin mpdemulation is not enabled Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "rest_api"... Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "websocket"... Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Starting Socket.io Server version 2.3.0 Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Plugin cdplayer is not enabled Jun 26 02:13:41 ifi-phmidsd-lsq-test volumio[21161]: info: Loading plugin "ifi-cdplayer"... Jun 26 02:13:42 ifi-phmidsd-lsq-test volumio[21161]: Forking 3 albumart workers Jun 26 02:13:42 ifi-phmidsd-lsq-test volumio[21161]: info: Applying required configuration parameters for plugin ifi-cdplayer Jun 26 02:13:42 ifi-phmidsd-lsq-test volumio[21161]: info: Loading i18n strings for locale en Jun 26 02:13:42 ifi-phmidsd-lsq-test volumio[21161]: Updating browse sources language Jun 26 02:13:42 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 26 02:13:42 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 02:13:42 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::initPlayerControls Jun 26 02:13:42 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:42 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: Removing existing socket file at /tmp/meta.sock Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: Express server listening on port 3000 Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: [Metrics] WebUI: 7s 580.85ms Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: CoreStateMachine::resetVolumioState Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: CoreStateMachine::getcurrentVolume Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::volumioRetrievevolume Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: Meta server listening Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: Server listening on port 6599 Jun 26 02:13:43 ifi-phmidsd-lsq-test sudo[21459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Jun 26 02:13:43 ifi-phmidsd-lsq-test sudo[21459]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: Completed loading Core Plugins Jun 26 02:13:43 ifi-phmidsd-lsq-test sudo[21461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Jun 26 02:13:43 ifi-phmidsd-lsq-test sudo[21461]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: Preparing to generate the ALSA configuration file Jun 26 02:13:43 ifi-phmidsd-lsq-test node[513]: Closing connection with the client tidal-connect Jun 26 02:13:43 ifi-phmidsd-lsq-test systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Jun 26 02:13:43 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Jun 26 02:13:43 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Jun 26 02:13:43 ifi-phmidsd-lsq-test systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: ConvSwitch: Connected to server Jun 26 02:13:43 ifi-phmidsd-lsq-test node[505]: ConvSwitch: Drop client tidal Jun 26 02:13:43 ifi-phmidsd-lsq-test systemd[1]: Stopping iFi Streamer Tidal Connect Service... Jun 26 02:13:43 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Jun 26 02:13:43 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: MetaClient: Connected to server Jun 26 02:13:43 ifi-phmidsd-lsq-test systemd[1]: Stopped iFi Streamer Tidal Connect Service. Jun 26 02:13:43 ifi-phmidsd-lsq-test systemd[1]: Started iFi Streamer Tidal Connect Service. Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: Volumio Network Manager: Network status updated: 3 Jun 26 02:13:43 ifi-phmidsd-lsq-test sudo[21459]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:43 ifi-phmidsd-lsq-test systemd[1]: Started iFi Streamer Tidal Metadata Service. Jun 26 02:13:43 ifi-phmidsd-lsq-test node[513]: A new connection has been established. Jun 26 02:13:43 ifi-phmidsd-lsq-test sudo[21461]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: CoreStateMachine::pushState Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: CorePlayQueue::getTrack 0 Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::volumioPushState Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: CoreStateMachine::updateTrackBlock Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: CorePlayQueue::getTrackBlock Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::volumioRetrievevolume Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: NAA Service Started Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 26 02:13:43 ifi-phmidsd-lsq-test volumio[21161]: info: Reading ALSA contributions from plugins. Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: ConvSwitch: Get message: success Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: MetaClient: Get message: success Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: Starting albumart workers Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: verbose: New Socket.io Connection to 192.168.1.169 from 192.168.1.189 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: verbose: New Socket.io Connection to 192.168.1.174 from 192.168.1.189 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: Starting albumart workers Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: verbose: New Socket.io Connection to 192.168.1.169 from 192.168.1.189 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: verbose: New Socket.io Connection to 192.168.1.174 from 192.168.1.189 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: verbose: New Socket.io Connection to 192.168.1.169:3000 from 192.168.1.149 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: verbose: New Socket.io Connection to 192.168.1.169 from 192.168.1.189 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: Starting albumart workers Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: verbose: New Socket.io Connection to 192.168.1.174 from 192.168.1.189 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: verbose: New Socket.io Connection to 192.168.1.169 from 192.168.1.189 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: verbose: New Socket.io Connection to 192.168.1.174 from 192.168.1.189 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: verbose: New Socket.io Connection to 192.168.1.169 from 192.168.1.189 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: verbose: New Socket.io Connection to 192.168.1.174 from 192.168.1.189 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: CoreStateMachine::pushState Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: CorePlayQueue::getTrack 0 Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::volumioPushState Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: Qobuz Connect Service Started Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: Tidal Connect Service Started Jun 26 02:13:44 ifi-phmidsd-lsq-test sudo[21495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: verbose: New Socket.io Connection to 192.168.1.169 from 192.168.1.189 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jun 26 02:13:44 ifi-phmidsd-lsq-test sudo[21495]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:44 ifi-phmidsd-lsq-test play[16212]: 2025-06-26 02:13:44.680 E esdk select error: 4 -> Interrupted system call Jun 26 02:13:44 ifi-phmidsd-lsq-test play[16212]: 2025-06-26 02:13:44.682 I app Exiting with error = 0 Jun 26 02:13:44 ifi-phmidsd-lsq-test play[16212]: 2025-06-26 02:13:44.682 I api SpFree() Jun 26 02:13:44 ifi-phmidsd-lsq-test play[16212]: 2025-06-26 02:13:44.683 I api SpFree [returned value: 0] Jun 26 02:13:44 ifi-phmidsd-lsq-test play[16212]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Jun 26 02:13:44 ifi-phmidsd-lsq-test play[16212]: [Info] ConvSwitch: ConvSwitchInit Jun 26 02:13:44 ifi-phmidsd-lsq-test play[16212]: [Info] MetaClient: MetaClient SDK -- v1.02 Jun 26 02:13:44 ifi-phmidsd-lsq-test play[16212]: [Info] MetaClient: MetaClientInit Jun 26 02:13:44 ifi-phmidsd-lsq-test play[16212]: [Info] ConvSwitch: ConvSwitchExit Jun 26 02:13:44 ifi-phmidsd-lsq-test play[16212]: [Info] MetaClient: MetaClientExit Jun 26 02:13:44 ifi-phmidsd-lsq-test node[505]: ConvSwitch: Drop client spotify Jun 26 02:13:44 ifi-phmidsd-lsq-test node[513]: Closing connection with the client spotify Jun 26 02:13:44 ifi-phmidsd-lsq-test systemd[1]: Stopping iFi Streamer Spotify Connect Service... Jun 26 02:13:44 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Jun 26 02:13:44 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Jun 26 02:13:44 ifi-phmidsd-lsq-test systemd[1]: Stopped iFi Streamer Spotify Connect Service. Jun 26 02:13:44 ifi-phmidsd-lsq-test systemd[1]: Started iFi Streamer Spotify Connect Service. Jun 26 02:13:44 ifi-phmidsd-lsq-test sudo[21495]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: Reloading queue from file Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: verbose: New Socket.io Connection to 192.168.1.169 from 192.168.1.189 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: verbose: New Socket.io Connection to 192.168.1.174 from 192.168.1.189 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: CoreStateMachine::setRepeat null single undefined Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: CoreStateMachine::pushState Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: CorePlayQueue::getTrack 0 Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::volumioPushState Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: CoreStateMachine::setRandom null Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: CoreStateMachine::pushState Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: CorePlayQueue::getTrack 0 Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::volumioPushState Jun 26 02:13:44 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: Spotify Connect Service Started Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: Discovery: adding 0a764952-50a3-46c5-aace-8fe6aecec920 Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: Discovery: Found device iFi-neo-eriri Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: output: volumio mixer: hw:0 Jun 26 02:13:45 ifi-phmidsd-lsq-test node[505]: ConvSwitch: Add client spotify Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: Discovery: Connecting to remote: 192.168.1.229 Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: ++++--++++ getBrowseSources: null Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::volumioGetVisibleSources Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: ++++--++++ getState Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::volumioGetState Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.286 I api SpRegisterDebugCallbacks [returned value: 0] Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.286 I api SpInit(0xffb7ae20, API v66) Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.287 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.287 I esdk Successfully registered socket callbacks: cre, set_opt, cl, bind, list, con, acc, rd, wr, rd_from, wr_to, err, readable, writable, local_addresses, address, pump Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.287 I esdk Successfully registered dns callback: dns_lookup_callback Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.288 I download Using 128 kB for cache buffer Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.288 I download Using 621 kB for compressed audio buffer Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.288 I esdk Successfully registered error callback Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: CorePlayQueue::getTrack 0 Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.305 I esdk Creating IPv4 socket (domain 2) Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.306 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.306 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.306 I esdk Creating IPv4 socket (domain 2) Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.306 I api SpInit [returned value: 0] Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.306 I api SpRegisterConnectionCallbacks(0xffb7ae08, (nil)) Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.306 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.306 I api SpRegisterConnectionCallbacks [returned value: 0] Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.306 I api SpRegisterPlaybackCallbacks(0xffb7adf0, (nil)) Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.306 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.306 I api SpRegisterPlaybackCallbacks [returned value: 0] Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: ++++--++++ getQueue Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::volumioGetQueue Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: CoreStateMachine::getQueue Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: CorePlayQueue::getQueue Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: ++++--++++ listPlaylist: null Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: Listing playlists Jun 26 02:13:45 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:45.324 I app Initialized! Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: ++++--++++ getMultiRoomDevices: null Jun 26 02:13:45 ifi-phmidsd-lsq-test node[513]: A new connection has been established. Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: ++++--++++ getMultiroom: null Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: error: Could not retrieve plugin audio_interface multiroom Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: ++++--++++ getMenuItems Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: Discovery: adding 26417df3-38a8-4f75-ba8a-55b45544821c Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: Discovery: Found device iFi-2 Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: Discovery: Connecting to remote: 192.168.1.221 Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: Asound.conf file unchanged, so no further update is needed Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: Tidal connect: Output device has changed Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: Output device has changed, restarting MPD Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: Qobuz connect: Output device has changed Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: Roon Ready: Output device has changed Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: Spotify: Output device has changed Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: ___________ START PLUGINS ___________ Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: ControllerMpd::onStart: Initializing MPD Jun 26 02:13:45 ifi-phmidsd-lsq-test sudo[21563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: Creating MPD Configuration file Jun 26 02:13:45 ifi-phmidsd-lsq-test sudo[21563]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:45 ifi-phmidsd-lsq-test sudo[21563]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 26 02:13:45 ifi-phmidsd-lsq-test sudo[21566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 26 02:13:45 ifi-phmidsd-lsq-test sudo[21566]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: [1750904025742] CoreMusicLibrary::Adding element Media Servers Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 26 02:13:45 ifi-phmidsd-lsq-test sudo[21566]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:45 ifi-phmidsd-lsq-test sudo[21568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Jun 26 02:13:45 ifi-phmidsd-lsq-test sudo[21568]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 26 02:13:45 ifi-phmidsd-lsq-test systemd[1]: mpd.socket: Succeeded. Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: [1750904025810] CoreMusicLibrary::Adding element Last_100 Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 26 02:13:45 ifi-phmidsd-lsq-test systemd[1]: Closed mpd.socket. Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: Starting RoonReady Sync Jun 26 02:13:45 ifi-phmidsd-lsq-test systemd[1]: Stopping Music Player Daemon... Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: [1750904025851] CoreMusicLibrary::Adding element Webradio Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 26 02:13:45 ifi-phmidsd-lsq-test systemd[1]: mpd.service: Succeeded. Jun 26 02:13:45 ifi-phmidsd-lsq-test volumio[21161]: info: Initializing BBC Radios Jun 26 02:13:45 ifi-phmidsd-lsq-test systemd[1]: Stopped Music Player Daemon. Jun 26 02:13:45 ifi-phmidsd-lsq-test demo[1017]: get alsa voMPD error: Connection refused Jun 26 02:13:45 ifi-phmidsd-lsq-test sudo[21580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Jun 26 02:13:45 ifi-phmidsd-lsq-test sudo[21580]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:45 ifi-phmidsd-lsq-test demo[1017]: MPD error: Connection refused Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test sudo[21568]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: input: iFi (by AMR) iDSD PHANTOM as /devices/platform/usb@ff600000/ff600000.dwc3/xhci-hcd.0.auto/usb4/4-1/4-1.2/4-1.2:1.4/0003:20B1:3008.0016/input/input21 Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test node[505]: ConvSwitch: Drop client roon-ready Jun 26 02:13:46 ifi-phmidsd-lsq-test systemd[1]: Stopping iFi Streamer Roon Ready Service... Jun 26 02:13:46 ifi-phmidsd-lsq-test node[513]: Closing connection with the client roon-ready Jun 26 02:13:46 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=killed, status=15/TERM Jun 26 02:13:46 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-roon-ready.service: Succeeded. Jun 26 02:13:46 ifi-phmidsd-lsq-test systemd[1]: Stopped iFi Streamer Roon Ready Service. Jun 26 02:13:46 ifi-phmidsd-lsq-test systemd[1]: Started iFi Streamer Roon Ready Service. Jun 26 02:13:46 ifi-phmidsd-lsq-test sudo[21580]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: hid-generic 0003:20B1:3008.0016: input,hidraw0: USB HID v1.11 Device [iFi (by AMR) iDSD PHANTOM] on usb-xhci-hcd.0.auto-1.2/input4 Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test systemd-udevd[21606]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 26 02:13:46 ifi-phmidsd-lsq-test sudo[21623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -snf /usr/local/bin/airplay2 /usr/local/bin/shairport-sync Jun 26 02:13:46 ifi-phmidsd-lsq-test sudo[21623]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 02:13:46 ifi-phmidsd-lsq-test sudo[21623]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test play[21608]: /usr/ifi/ifi-roonready-release/play: line 18: warning: command substitution: ignored null byte in input Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: [1750904026310] CoreMusicLibrary::Adding element Audio CD/DVD Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: Cannot find translation for source Audio CD/DVD Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: CDClient: Client Start Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: Volumio Calling Home Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:46 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:46 ifi-phmidsd-lsq-test node[505]: ConvSwitch: Add client roon-ready Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: Discovery: adding 0d539ddb-fc7f-4b66-9dce-f627e4347672 Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: Discovery: Found device lsq NEO Stream Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: Discovery: Connecting to remote: 192.168.1.149 Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: Starting RoonReady Sync Jun 26 02:13:46 ifi-phmidsd-lsq-test sudo[21690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop ifi-streamer-qobuz-connect.service Jun 26 02:13:46 ifi-phmidsd-lsq-test sudo[21690]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:46 ifi-phmidsd-lsq-test sudo[21692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-roon-ready.service Jun 26 02:13:46 ifi-phmidsd-lsq-test sudo[21692]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:46 ifi-phmidsd-lsq-test systemd[1]: Stopping iFi Streamer Qobuz Connect Service... Jun 26 02:13:46 ifi-phmidsd-lsq-test node[505]: ConvSwitch: Drop client qobuz-connect Jun 26 02:13:46 ifi-phmidsd-lsq-test node[513]: Closing connection with the client qobuz-connect Jun 26 02:13:46 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-qobuz-connect.service: Main process exited, code=killed, status=15/TERM Jun 26 02:13:46 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-qobuz-connect.service: Succeeded. Jun 26 02:13:46 ifi-phmidsd-lsq-test systemd[1]: Stopped iFi Streamer Qobuz Connect Service. Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: error: Could not retrieve plugin audio_interface multiroom Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: Discovery: Connected to remote: 192.168.1.229 Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: error: Could not retrieve plugin audio_interface multiroom Jun 26 02:13:46 ifi-phmidsd-lsq-test sudo[21690]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:46 ifi-phmidsd-lsq-test demo[1017]: MPD error: Connection refused Jun 26 02:13:46 ifi-phmidsd-lsq-test ifi_metadata[21465]: [Info] MetaClient: MetaClient SDK -- v1.02 Jun 26 02:13:46 ifi-phmidsd-lsq-test ifi_metadata[21465]: [Info] MetaClient: MetaClientInit Jun 26 02:13:46 ifi-phmidsd-lsq-test ifi_metadata[21465]: Could not connect: Reason::CONNECTION_ERROR Jun 26 02:13:46 ifi-phmidsd-lsq-test node[505]: ConvSwitch: Add client tidal Jun 26 02:13:46 ifi-phmidsd-lsq-test node[505]: ConvSwitch: Drop client roon-ready Jun 26 02:13:46 ifi-phmidsd-lsq-test systemd[1]: Stopping iFi Streamer Roon Ready Service... Jun 26 02:13:46 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-roon-ready.service: Main process exited, code=killed, status=15/TERM Jun 26 02:13:46 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-roon-ready.service: Succeeded. Jun 26 02:13:46 ifi-phmidsd-lsq-test systemd[1]: Stopped iFi Streamer Roon Ready Service. Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: CDClient: Connected to server Jun 26 02:13:46 ifi-phmidsd-lsq-test demo[1017]: MPD error: Connection refused Jun 26 02:13:46 ifi-phmidsd-lsq-test systemd[1]: Started iFi Streamer Roon Ready Service. Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: Airplay2 Started Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: error: Could not retrieve plugin music_service airplay1_switch Jun 26 02:13:46 ifi-phmidsd-lsq-test sudo[21692]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:46 ifi-phmidsd-lsq-test tidal_connect_application[21677]: *** WARNING *** The program 'tidal_connect_application' uses the Apple Bonjour compatibility layer of Avahi. Jun 26 02:13:46 ifi-phmidsd-lsq-test tidal_connect_application[21677]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 26 02:13:46 ifi-phmidsd-lsq-test tidal_connect_application[21677]: *** WARNING *** For more information see Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: Disabling plugin airplay1_switch Jun 26 02:13:46 ifi-phmidsd-lsq-test volumio[21161]: info: Done. Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: RoonReady Started Jun 26 02:13:47 ifi-phmidsd-lsq-test sudo[21727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync Jun 26 02:13:47 ifi-phmidsd-lsq-test sudo[21727]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: MPD Permissions set Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: MPD Permissions set Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: Discovery: adding 7a2a87ae-9864-4896-b72d-e2751dd260de Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: Discovery: Found device lsq-test Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::volumioGetState Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CorePlayQueue::getTrack 0 Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: error: Could not retrieve plugin audio_interface multiroom Jun 26 02:13:47 ifi-phmidsd-lsq-test ifi_metadata[21465]: Connected Jun 26 02:13:47 ifi-phmidsd-lsq-test systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 26 02:13:47 ifi-phmidsd-lsq-test sudo[21744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Jun 26 02:13:47 ifi-phmidsd-lsq-test sudo[21744]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:47 ifi-phmidsd-lsq-test systemd[1]: shairport-sync.service: Succeeded. Jun 26 02:13:47 ifi-phmidsd-lsq-test systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CDClient: Get message Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: Disc loaded: /dev/sr0 Jun 26 02:13:47 ifi-phmidsd-lsq-test ethernet-detect[445]: No change in avahi-daemon interfaces. Skipping restart. Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: error: Could not retrieve plugin audio_interface multiroom Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: Discovery: Connected to remote: 192.168.1.149 Jun 26 02:13:47 ifi-phmidsd-lsq-test sudo[21727]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: error: Could not retrieve plugin audio_interface multiroom Jun 26 02:13:47 ifi-phmidsd-lsq-test systemd[1]: Listening on mpd.socket. Jun 26 02:13:47 ifi-phmidsd-lsq-test systemd[1]: Starting Music Player Daemon... Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: verbose: New Socket.io Connection to 192.168.1.169:3000 from 192.168.1.149 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: RoonReady Started Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: ---------------------------- Client requests Start RoonReady PlaybackRoutine Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: roon_ready , startRoonReadyPlayback Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: Roon Ready playback start Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::volumioStop Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreStateMachine::stop Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: Starting Roon Ready metadata receiver Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: Error adding Membership: Error: addMembership EINVAL Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: error: Could not retrieve plugin audio_interface multiroom Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: Discovery: Connected to remote: 192.168.1.221 Jun 26 02:13:47 ifi-phmidsd-lsq-test sudo[21760]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: error: Could not retrieve plugin audio_interface multiroom Jun 26 02:13:47 ifi-phmidsd-lsq-test sudo[21760]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:47 ifi-phmidsd-lsq-test sudo[21760]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: Shairport-Sync Stopped Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: ++++--++++ browseLibrary: {"uri":"cdrom"} Jun 26 02:13:47 ifi-phmidsd-lsq-test play[21711]: /usr/ifi/ifi-roonready-release/play: line 18: warning: command substitution: ignored null byte in input Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: ifi-cdplayer , handleBrowseUri Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: cdrom Jun 26 02:13:47 ifi-phmidsd-lsq-test sudo[21793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 664 /dev/sr0 Jun 26 02:13:47 ifi-phmidsd-lsq-test sudo[21793]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:47 ifi-phmidsd-lsq-test sudo[21793]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: ++++--++++ initSocket: {"id":"0d539ddb-fc7f-4b66-9dce-f627e4347672"} Jun 26 02:13:47 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 26 02:13:48 ifi-phmidsd-lsq-test sudo[21822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd.socket mpd.service Jun 26 02:13:48 ifi-phmidsd-lsq-test sudo[21822]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:48 ifi-phmidsd-lsq-test sudo[21828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-tidal-connect.service Jun 26 02:13:48 ifi-phmidsd-lsq-test sudo[21744]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:48 ifi-phmidsd-lsq-test sudo[21828]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:48 ifi-phmidsd-lsq-test systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 26 02:13:48 ifi-phmidsd-lsq-test systemd[1]: mpd.service: Succeeded. Jun 26 02:13:48 ifi-phmidsd-lsq-test systemd[1]: Stopped Music Player Daemon. Jun 26 02:13:48 ifi-phmidsd-lsq-test demo[1017]: MPD error: Connection closed by the server Jun 26 02:13:48 ifi-phmidsd-lsq-test demo[1017]: MPD error: Connection closed by the server Jun 26 02:13:48 ifi-phmidsd-lsq-test node[513]: Closing connection with the client tidal-connect Jun 26 02:13:48 ifi-phmidsd-lsq-test systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Jun 26 02:13:48 ifi-phmidsd-lsq-test systemd[1]: mpd.socket: Succeeded. Jun 26 02:13:48 ifi-phmidsd-lsq-test systemd[1]: Closed mpd.socket. Jun 26 02:13:48 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Jun 26 02:13:48 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Jun 26 02:13:48 ifi-phmidsd-lsq-test systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Jun 26 02:13:48 ifi-phmidsd-lsq-test node[505]: ConvSwitch: Add client roon-ready Jun 26 02:13:48 ifi-phmidsd-lsq-test sudo[21822]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:48 ifi-phmidsd-lsq-test volumio[21161]: info: Volumio called home Jun 26 02:13:48 ifi-phmidsd-lsq-test systemd[1]: Stopping iFi Streamer Tidal Connect Service... Jun 26 02:13:48 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=killed, status=15/TERM Jun 26 02:13:48 ifi-phmidsd-lsq-test volumio[21161]: info: Preload queue cleared Jun 26 02:13:48 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-tidal-connect.service: Succeeded. Jun 26 02:13:48 ifi-phmidsd-lsq-test node[505]: ConvSwitch: Error: Error: read ECONNRESET Jun 26 02:13:48 ifi-phmidsd-lsq-test systemd[1]: Stopped iFi Streamer Tidal Connect Service. Jun 26 02:13:48 ifi-phmidsd-lsq-test systemd[1]: Started iFi Streamer Tidal Connect Service. Jun 26 02:13:48 ifi-phmidsd-lsq-test sudo[21828]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:48 ifi-phmidsd-lsq-test systemd[1]: Started iFi Streamer Tidal Metadata Service. Jun 26 02:13:48 ifi-phmidsd-lsq-test node[513]: A new connection has been established. Jun 26 02:13:48 ifi-phmidsd-lsq-test volumio[21161]: info: ++++--++++ getState Jun 26 02:13:48 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::volumioGetState Jun 26 02:13:48 ifi-phmidsd-lsq-test volumio[21161]: info: CorePlayQueue::getTrack 0 Jun 26 02:13:48 ifi-phmidsd-lsq-test volumio[21161]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.socket mpd.service Jun 26 02:13:48 ifi-phmidsd-lsq-test volumio[21161]: Job for mpd.service canceled. Jun 26 02:13:48 ifi-phmidsd-lsq-test volumio[21161]: info: Completed starting Core Plugins Jun 26 02:13:48 ifi-phmidsd-lsq-test volumio[21161]: [Metrics] CommandRouter: 12s 58.91ms Jun 26 02:13:48 ifi-phmidsd-lsq-test sudo[21859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.socket mpd.service Jun 26 02:13:48 ifi-phmidsd-lsq-test sudo[21859]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:48 ifi-phmidsd-lsq-test systemd[1]: Listening on mpd.socket. Jun 26 02:13:48 ifi-phmidsd-lsq-test systemd[1]: Starting Music Player Daemon... Jun 26 02:13:48 ifi-phmidsd-lsq-test sudo[21863]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 26 02:13:48 ifi-phmidsd-lsq-test sudo[21863]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:48 ifi-phmidsd-lsq-test sudo[21863]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:49 ifi-phmidsd-lsq-test volumio[21161]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jun 26 02:13:49 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::volumiosetStartupVolume Jun 26 02:13:49 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 26 02:13:49 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 26 02:13:49 ifi-phmidsd-lsq-test node[513]: A new connection has been established. Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: input: iFi (by AMR) iDSD PHANTOM as /devices/platform/usb@ff600000/ff600000.dwc3/xhci-hcd.0.auto/usb4/4-1/4-1.2/4-1.2:1.4/0003:20B1:3008.0017/input/input22 Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: hid-generic 0003:20B1:3008.0017: input,hidraw0: USB HID v1.11 Device [iFi (by AMR) iDSD PHANTOM] on usb-xhci-hcd.0.auto-1.2/input4 Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test systemd-udevd[21923]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio1-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc4-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio2-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply from device tree Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rk808 1-0018: Looking up vcc5-supply property in node /i2c@ff160000/rk805@18 failed Jun 26 02:13:50 ifi-phmidsd-lsq-test kernel: rockchip-iodomain ff100000.syscon:io-domains: Looking up vccio3-supply from device tree Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::Close All Modals sent Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::Close All Modals sent Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio-remote-updater[491]: [2025-06-26 02:13:51] [connect] Successful connection Jun 26 02:13:51 ifi-phmidsd-lsq-test tidal_connect_application[21944]: *** WARNING *** The program 'tidal_connect_application' uses the Apple Bonjour compatibility layer of Avahi. Jun 26 02:13:51 ifi-phmidsd-lsq-test tidal_connect_application[21944]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 26 02:13:51 ifi-phmidsd-lsq-test tidal_connect_application[21944]: *** WARNING *** For more information see Jun 26 02:13:51 ifi-phmidsd-lsq-test sudo[21957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ifi-streamer-qobuz-connect.service Jun 26 02:13:51 ifi-phmidsd-lsq-test sudo[21957]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:51 ifi-phmidsd-lsq-test node[505]: ConvSwitch: Error: Cannot set startRoonReadyPlayback Jun 26 02:13:51 ifi-phmidsd-lsq-test ifi_metadata[21843]: [Info] MetaClient: MetaClient SDK -- v1.02 Jun 26 02:13:51 ifi-phmidsd-lsq-test ifi_metadata[21843]: [Info] MetaClient: MetaClientInit Jun 26 02:13:51 ifi-phmidsd-lsq-test ifi_metadata[21843]: Connected Jun 26 02:13:51 ifi-phmidsd-lsq-test systemd[1]: Started iFi Streamer Qobuz Connect Service. Jun 26 02:13:51 ifi-phmidsd-lsq-test sudo[21957]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:51 ifi-phmidsd-lsq-test sudo[21965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart ifi-streamer-spotify-connect.service Jun 26 02:13:51 ifi-phmidsd-lsq-test sudo[21965]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: error: MPD error: Error: connect ECONNREFUSED /run/mpd/socket Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: error: connect ECONNREFUSED /run/mpd/socket {"errno":-111,"code":"ECONNREFUSED","syscall":"connect","address":"/run/mpd/socket"} Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: Starting Shairport Sync Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21842]: /usr/ifi/ifi-tidal-release/play: line 70: 21944 Segmentation fault ${SAME_DIR}bin/tidal_connect_application --netif-for-deviceid eth0 -f "${BROADCAST_NAME}" --codec-mpegh true --codec-mqa true --model-name "iFi Streamer" --disable-app-security false --disable-web-security false --enable-mqa-passthrough "${PASSTHROUGH_MQA}" --playback-device "${OUTPUT}" --log-level ${LOG_LEVEL} >> /var/log/tidal.log 2>&1 Jun 26 02:13:51 ifi-phmidsd-lsq-test ifi_metadata[21843]: Disconnected: Reason::DISCONNECTED Jun 26 02:13:51 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-tidal-connect.service: Main process exited, code=exited, status=139/n/a Jun 26 02:13:51 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-tidal-connect.service: Failed with result 'exit-code'. Jun 26 02:13:51 ifi-phmidsd-lsq-test sudo[21972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 26 02:13:51 ifi-phmidsd-lsq-test sudo[21972]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:51 ifi-phmidsd-lsq-test sudo[21978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 26 02:13:51 ifi-phmidsd-lsq-test sudo[21978]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:51 ifi-phmidsd-lsq-test sudo[21972]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: Starting Shairport Sync Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:51.432 E esdk select error: 4 -> Interrupted system call Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:51.434 I app Exiting with error = 0 Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:51.434 I api SpFree() Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21501]: 2025-06-26 02:13:51.435 I api SpFree [returned value: 0] Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21501]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21501]: [Info] ConvSwitch: ConvSwitchInit Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21501]: [Info] MetaClient: MetaClient SDK -- v1.02 Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21501]: [Info] MetaClient: MetaClientInit Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21501]: [Info] ConvSwitch: ConvSwitchExit Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21501]: [Info] MetaClient: MetaClientExit Jun 26 02:13:51 ifi-phmidsd-lsq-test node[513]: Closing connection with the client spotify Jun 26 02:13:51 ifi-phmidsd-lsq-test node[505]: ConvSwitch: Drop client spotify Jun 26 02:13:51 ifi-phmidsd-lsq-test sudo[21978]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:51 ifi-phmidsd-lsq-test systemd[1]: Stopping iFi Streamer Spotify Connect Service... Jun 26 02:13:51 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-spotify-connect.service: Main process exited, code=killed, status=15/TERM Jun 26 02:13:51 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-spotify-connect.service: Succeeded. Jun 26 02:13:51 ifi-phmidsd-lsq-test systemd[1]: Stopped iFi Streamer Spotify Connect Service. Jun 26 02:13:51 ifi-phmidsd-lsq-test sudo[21990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 26 02:13:51 ifi-phmidsd-lsq-test sudo[21990]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:51 ifi-phmidsd-lsq-test systemd[1]: Started iFi Streamer Spotify Connect Service. Jun 26 02:13:51 ifi-phmidsd-lsq-test sudo[21965]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:51 ifi-phmidsd-lsq-test sudo[21994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: ++++--++++ getState Jun 26 02:13:51 ifi-phmidsd-lsq-test sudo[21994]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::volumioGetState Jun 26 02:13:51 ifi-phmidsd-lsq-test systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 26 02:13:51 ifi-phmidsd-lsq-test sudo[21990]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:51 ifi-phmidsd-lsq-test systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 26 02:13:51 ifi-phmidsd-lsq-test systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 26 02:13:51 ifi-phmidsd-lsq-test systemd[1]: shairport-sync.service: Succeeded. Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: ++++--++++ browseLibrary: {"uri":"cdrom/tracks/dev/sr0","prevUri":"cdrom"} Jun 26 02:13:51 ifi-phmidsd-lsq-test systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: CoreCommandRouter::executeOnPlugin: ifi-cdplayer , handleBrowseUri Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: cdrom/tracks/dev/sr0 Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: +++++++++++++++++++uri: cdrom/tracks/dev/sr0 Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: [ Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: "dev", Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: "sr0" Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: ] Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: request tracks for /dev/sr0 Jun 26 02:13:51 ifi-phmidsd-lsq-test systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: ++++++++++++cdDrives: {"loaded":true,"disc":{"dev":"/dev/sr0","mode":0,"total_tracks":10,"audio_length":200477,"tracks":[{"track_num":1,"start_lsn":30,"end_lsn":20917,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 1"},{"track_num":2,"start_lsn":20918,"end_lsn":42653,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 2"},{"track_num":3,"start_lsn":42654,"end_lsn":60715,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 3"},{"track_num":4,"start_lsn":60716,"end_lsn":82750,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 4"},{"track_num":5,"start_lsn":82751,"end_lsn":100523,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 5"},{"track_num":6,"start_lsn":100524,"end_lsn":120224,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 6"},{"track_num":7,"start_lsn":120225,"end_lsn":138352,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 7"},{"track_num":8,"start_lsn":138353,"end_lsn":157625,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 8"},{"track_num":9,"start_lsn":157626,"end_lsn":178389,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 9"},{"track_num":10,"start_lsn":178390,"end_lsn":200506,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 10"}]}} Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: ++++++++++++++++song: {"service":"ifi-cdplayer","type":"song","trackType":"CD","title":"Track 1","uri":"cdrom/tracks/dev/sr0/0","icon":"fa fa-music"} Jun 26 02:13:51 ifi-phmidsd-lsq-test sudo[21994]: pam_unix(sudo:session): session closed for user root Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: ++++++++++++++++song: {"service":"ifi-cdplayer","type":"song","trackType":"CD","title":"Track 2","uri":"cdrom/tracks/dev/sr0/1","icon":"fa fa-music"} Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: ++++++++++++++++song: {"service":"ifi-cdplayer","type":"song","trackType":"CD","title":"Track 3","uri":"cdrom/tracks/dev/sr0/2","icon":"fa fa-music"} Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: ++++++++++++++++song: {"service":"ifi-cdplayer","type":"song","trackType":"CD","title":"Track 4","uri":"cdrom/tracks/dev/sr0/3","icon":"fa fa-music"} Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: ++++++++++++++++song: {"service":"ifi-cdplayer","type":"song","trackType":"CD","title":"Track 5","uri":"cdrom/tracks/dev/sr0/4","icon":"fa fa-music"} Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: ++++++++++++++++song: {"service":"ifi-cdplayer","type":"song","trackType":"CD","title":"Track 6","uri":"cdrom/tracks/dev/sr0/5","icon":"fa fa-music"} Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: ++++++++++++++++song: {"service":"ifi-cdplayer","type":"song","trackType":"CD","title":"Track 7","uri":"cdrom/tracks/dev/sr0/6","icon":"fa fa-music"} Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: ++++++++++++++++song: {"service":"ifi-cdplayer","type":"song","trackType":"CD","title":"Track 8","uri":"cdrom/tracks/dev/sr0/7","icon":"fa fa-music"} Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: ++++++++++++++++song: {"service":"ifi-cdplayer","type":"song","trackType":"CD","title":"Track 9","uri":"cdrom/tracks/dev/sr0/8","icon":"fa fa-music"} Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: ++++++++++++++++song: {"service":"ifi-cdplayer","type":"song","trackType":"CD","title":"Track 10","uri":"cdrom/tracks/dev/sr0/9","icon":"fa fa-music"} Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: Qobuz Connect Service Started Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: Tidal Connect Service Started Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.793 [22024.22024] DEBUG SampleApp: Starting sample application Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.793 [22024.22024] DEBUG SampleApp: Device Name: lsq-test Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.794 [22024.22024] DEBUG SampleApp: Manufacturer: Sample manufacturer Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.794 [22024.22024] DEBUG SampleApp: Model: iFi Streamer Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.794 [22024.22024] DEBUG SampleApp: Serial Number: ifi:32:ef:95:fd:98:7f Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.794 [22024.22024] DEBUG SampleApp: Application ID: 679318972 Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.794 [22024.22024] DEBUG SampleApp: Application Secret: a32fe515ff9d11ffafcb2b2a1426c244 Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.794 [22024.22024] DEBUG SampleApp: Audio output device: volumio Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.794 [22024.22024] DEBUG SampleApp: Volume control device: hw:0 Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.794 [22024.22024] DEBUG SampleApp: Volume control element: SoftMaster Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.794 [22024.22024] DEBUG SampleApp: Local config server port: 8000 Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.800 [22024.22024] ERROR SampleApp: ALSA volume element 'SoftMaster' does not support playback mute Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.801 [22024.22024] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.801 [22024.22024] DEBUG SampleApp: Maximum supported audio quality: HIRES_L3 Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: [Info] MetaClient: MetaClient SDK -- v1.02 Jun 26 02:13:51 ifi-phmidsd-lsq-test node[513]: A new connection has been established. Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: Preload queue cleared Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: Preloading song: cdrom/tracks/dev/sr0/0 Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: Preloading song: cdrom/tracks/dev/sr0/1 Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: Preloading song: cdrom/tracks/dev/sr0/2 Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: Preloading song: cdrom/tracks/dev/sr0/3 Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: Preloading song: cdrom/tracks/dev/sr0/4 Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: Preloading song: cdrom/tracks/dev/sr0/5 Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: Preloading song: cdrom/tracks/dev/sr0/6 Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: Preloading song: cdrom/tracks/dev/sr0/7 Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: Preloading song: cdrom/tracks/dev/sr0/8 Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: info: Preloading song: cdrom/tracks/dev/sr0/9 Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21995]: output: volumio mixer: hw:0 Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: [Info] MetaClient: MetaClientInit Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: [Info] ConvSwitch: ConvSwitch SDK -- v1.03 Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: error: Could not retrieve plugin audio_interface multiroom Jun 26 02:13:51 ifi-phmidsd-lsq-test node[505]: ConvSwitch: Add client qobuz-connect Jun 26 02:13:51 ifi-phmidsd-lsq-test node[505]: ConvSwitch: Add client spotify Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio[21161]: error: Could not retrieve plugin audio_interface multiroom Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: [Info] ConvSwitch: ConvSwitchInit Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.909 [22024.22024] DEBUG ActiveStateManager: [0xab6f0390]: Creating new Active State Manager Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.910 [22024.22024] DEBUG NetworkActivityTracker: [0xab6f0400]: Creating new Network Activity Tracker Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.912 [22024.22024] DEBUG EndpointManager: [0xab6f1378]: Creating Endpoint Manager Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.913 [22024.22024] DEBUG PlaybackSessionManager: [0xab6f1768]: Creating Playback Session Manager Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.914 [22024.22024] DEBUG BackoffTimer: [0xab6f18e0]: Creating Backoff Timer Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.915 [22024.22024] DEBUG MediaEngine: [0xab6f1a00]: Creating new Media Engine Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.916 [22024.22024] DEBUG AudioRenderer: [0xab6f1c20]: Creating Audio Renderer Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.917 [22024.22024] TRACE MediaEngineCommandSequencer: [0xab6f1d78]: Creating Media Engine Command Sequencer Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.917 [22024.22024] DEBUG PlaybackControlsManager: [0xab6f1dd8]: Creating new Playback Controls Manager Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.917 [22024.22024] DEBUG VolumeManager: [0xab6f1e68]: Creating new Volume Manager Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.917 [22024.22024] DEBUG ProtocolHandler: [0xab6f2140]: Creating Protocol Handler Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.917 [22024.22024] DEBUG MessageReceiver: [0xab6f21e0]: Creating Message Receiver Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.917 [22024.22024] DEBUG RendererActivationController: [0xab6f2358]: Creating new Renderer Activation Controller Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.917 [22024.22024] TRACE RendererActivationCommandSequencer: [0xab6f2400]: Creating Renderer Activation Command Sequencer Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.917 [22024.22024] DEBUG ReportingManager: [0xab6f2510]: Creating new Reporting Manager Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.917 [22024.22024] DEBUG StreamingEventReporter: [0xab6f2558]: Creating Streaming Event Reporter Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.917 [22024.22024] DEBUG BackoffTimer: [0xab6f2638]: Creating Backoff Timer Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.917 [22024.22024] DEBUG TokenExpiryController: [0xab6f2748]: Creating Token Expiry Controller Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.917 [22024.22024] DEBUG UserInactivityManager: [0xab6f2798]: Creating new User Inactivity Manager Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.917 [22024.22024] INFO VolumeManager: [0xab6f1e68]: Setting new playback volume: 8 Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.918 [22024.22024] ERROR SampleApp: Unable to get ALSA volume element's mute state: Invalid argument (-22) Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.923 [22024.22024] INFO VolumeManager: [0xab6f1e68]: Setting new mute state: 0 Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.923 [22024.22024] DEBUG SampleApp: Qobuz Connect has been initialized Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.923 [22024.22024] TRACE SampleApp: Running event loop Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.923 [22024.22024] INFO QobuzConnect: [0xab6f2838]: Client initialized! Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.923 [22024.22024] INFO SampleApp: Starting Avahi advertising, name: lsq-test, service name: _qobuz-connect._tcp Jun 26 02:13:51 ifi-phmidsd-lsq-test node[513]: A new connection has been established. Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.941 [22024.22024] DEBUG SampleApp: Avahi client state changed: RUNNING Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.947 [22024.22024] DEBUG SampleApp: Avahi entry group state changed: UNCOMMITED Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.958 [22024.22024] INFO LocalConfigManager: [0xab6f1748]: Starting Local Configuration server Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.959 [22024.22024] INFO SampleApp: Starting Local configuration server Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.963 [22024.22024] DEBUG SampleApp: Avahi entry group state changed: REGISTERING Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.965 [22024.22024] INFO SampleApp: Playback volume changed: 8 Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:51.966 [22024.22024] TRACE SampleApp: Setting volume: 8 Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:51.966 I api SpRegisterDebugCallbacks [returned value: 0] Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:51.967 I api SpInit(0xffe96070, API v66) Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:51.967 I esdk eSDK version: HEAD-v3.166.69-g24821f73 Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:51.967 I esdk Successfully registered socket callbacks: cre, set_opt, cl, bind, list, con, acc, rd, wr, rd_from, wr_to, err, readable, writable, local_addresses, address, pump Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:51.968 I esdk Successfully registered dns callback: dns_lookup_callback Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:51.968 I download Using 128 kB for cache buffer Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:51.968 I download Using 621 kB for compressed audio buffer Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:51.968 I esdk Successfully registered error callback Jun 26 02:13:51 ifi-phmidsd-lsq-test volumio-remote-updater[491]: [2025-06-26 02:13:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750904031 101 Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:51.984 I esdk Creating IPv4 socket (domain 2) Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:51.985 I zc webserver: service port 5566 (5566-5576) buffer size 4444 Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:51.987 I zc Started ZeroConf service on port 5566 path /zc alias count 0 Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:51.988 I esdk Creating IPv4 socket (domain 2) Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:51.989 I api SpInit [returned value: 0] Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:51.990 I api SpRegisterConnectionCallbacks(0xffe96058, (nil)) Jun 26 02:13:51 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:51.992 I esdk Successfully registered connection callbacks: on_notify, on_message, on_new_credentials Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 17 Jun 26 02:13:52 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:52.007 I api SpRegisterConnectionCallbacks [returned value: 0] Jun 26 02:13:52 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:52.008 I api SpRegisterPlaybackCallbacks(0xffe96040, (nil)) Jun 26 02:13:52 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:52.009 I esdk Successfully registered playback callbacks: on_notify, on_audio_data, on_seek, on_apply_volume, - Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: info: Shairport-Sync Started Jun 26 02:13:52 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:52.012 I api SpRegisterPlaybackCallbacks [returned value: 0] Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: Error adding Membership: Error: addMembership EINVAL Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: info: Shairport-Sync Started Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: info: Spotify Connect Service Started Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: info: Exploding uri cdrom/tracks/dev/sr0/0 in service ifi-cdplayer Jun 26 02:13:52 ifi-phmidsd-lsq-test play[21995]: 2025-06-26 02:13:52.036 I app Initialized! Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: info: explode: cdrom/tracks/dev/sr0/0 Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: +++++++++++++++++++uri: cdrom/tracks/dev/sr0/0 Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: info: [ Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: "dev", Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: "sr0", Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: "0" Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: ] Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: aaaaaaaaaaaaa parts: {"drive":"/dev/sr0","track":"0","uri":"/dev/sr0/0"} Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: ++++++++++++cdDrives: {"loaded":true,"disc":{"dev":"/dev/sr0","mode":0,"total_tracks":10,"audio_length":200477,"tracks":[{"track_num":1,"start_lsn":30,"end_lsn":20917,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 1"},{"track_num":2,"start_lsn":20918,"end_lsn":42653,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 2"},{"track_num":3,"start_lsn":42654,"end_lsn":60715,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 3"},{"track_num":4,"start_lsn":60716,"end_lsn":82750,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 4"},{"track_num":5,"start_lsn":82751,"end_lsn":100523,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 5"},{"track_num":6,"start_lsn":100524,"end_lsn":120224,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 6"},{"track_num":7,"start_lsn":120225,"end_lsn":138352,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 7"},{"track_num":8,"start_lsn":138353,"end_lsn":157625,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 8"},{"track_num":9,"start_lsn":157626,"end_lsn":178389,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 9"},{"track_num":10,"start_lsn":178390,"end_lsn":200506,"is_audio":true,"copyp":false,"preemp":false,"channels":2,"name":"Track 10"}]}} Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: TypeError: self.cdClient.getDisc(...).then is not a function Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: at CdPlayer.explodeUri (/data/plugins/music_service/ifi-cdplayer/index.js:413:40) Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1487:16) Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: at listOnTimeout (internal/timers.js:557:17) Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: at processTimers (internal/timers.js:500:7) Jun 26 02:13:52 ifi-phmidsd-lsq-test volumio[21161]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 26 02:13:52 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-tidal-connect.service: Service RestartSec=1s expired, scheduling restart. Jun 26 02:13:52 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-tidal-connect.service: Scheduled restart job, restart counter is at 1. Jun 26 02:13:52 ifi-phmidsd-lsq-test node[513]: Closing connection with the client tidal-connect Jun 26 02:13:52 ifi-phmidsd-lsq-test systemd[1]: Stopping iFi Streamer Tidal Metadata Service... Jun 26 02:13:52 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-tidal-metadata.service: Main process exited, code=killed, status=15/TERM Jun 26 02:13:52 ifi-phmidsd-lsq-test systemd[1]: ifi-streamer-tidal-metadata.service: Succeeded. Jun 26 02:13:52 ifi-phmidsd-lsq-test systemd[1]: Stopped iFi Streamer Tidal Metadata Service. Jun 26 02:13:52 ifi-phmidsd-lsq-test systemd[1]: Stopped iFi Streamer Tidal Connect Service. Jun 26 02:13:52 ifi-phmidsd-lsq-test systemd[1]: Started iFi Streamer Tidal Connect Service. Jun 26 02:13:52 ifi-phmidsd-lsq-test systemd[1]: Started iFi Streamer Tidal Metadata Service. Jun 26 02:13:52 ifi-phmidsd-lsq-test node[513]: A new connection has been established. Jun 26 02:13:52 ifi-phmidsd-lsq-test play[21967]: 20250626 02:13:52.798 [22024.22024] DEBUG SampleApp: Avahi entry group state changed: ESTABLISHED Jun 26 02:13:53 ifi-phmidsd-lsq-test sudo[22074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-26 02:12 Jun 26 02:13:53 ifi-phmidsd-lsq-test sudo[22074]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="8a0defaac7e920a11527bed08dfa1cf7bce6cbbc" VOLUMIO_FE_VERSION="0548e367fe44b194ccf1306c8af35deb040eccd2" VOLUMIO_FE3_VERSION="c63cc070c36379a8d79dc0d7d4a24908bebc2472" VOLUMIO_BE_VERSION="679648e2cde0726b3cc817882082a0e2ad4c7511" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="ifi" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="2025年 04月 28日 星期一 18:15:48 CST" VOLUMIO_VERSION="2.01.0" VOLUMIO_HARDWARE="idsd-phantom" VOLUMIO_DEVICENAME="iDSD PHANTOM" VOLUMIO_HASH="eca77ea0917a55a7681cda1e64cb9701"