-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Sun 2026-10-11 11:57:31 CST. -- Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:01.469+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PAUSED positionMs=11000 volume=33 Oct 11 11:56:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:01.469+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="VEM MEU AMOR (归我怀中呀)" Oct 11 11:56:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:01.470+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PAUSED positionMs=11000 volume=33 Oct 11 11:56:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:01.471+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="VEM MEU AMOR (归我怀中呀)" Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:01.554+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=11000 volume=33 Oct 11 11:56:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:01.554+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=11000 volume=33 Oct 11 11:56:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:01.555+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="VEM MEU AMOR (归我怀中呀)" Oct 11 11:56:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:01.555+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="VEM MEU AMOR (归我怀中呀)" Oct 11 11:56:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:01.555+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="VEM MEU AMOR (归我怀中呀)" Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:01 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:01.980+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=11000 volume=33 Oct 11 11:56:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:01.980+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=11000 volume=33 Oct 11 11:56:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:01.981+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="Vem Meu Amor 2" Oct 11 11:56:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:01.981+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=11000 volume=33 Oct 11 11:56:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:01.981+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="Vem Meu Amor 2" Oct 11 11:56:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:01.982+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="Vem Meu Amor 2" Oct 11 11:56:02 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:02 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:02 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:02 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:02 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:02 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:02 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:02 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:02 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:02 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:02.015+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=11000 volume=33 Oct 11 11:56:02 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:02.015+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="Vem Meu Amor 2" Oct 11 11:56:02 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:02.016+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=11000 volume=33 Oct 11 11:56:02 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:02.016+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="Vem Meu Amor 2" Oct 11 11:56:02 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:02.017+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="Vem Meu Amor 2" Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.029+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=12000 volume=33 Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.029+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=12000 volume=33 Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.030+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="Vem Meu Amor 2" Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.030+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=12000 volume=33 Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.030+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="Vem Meu Amor 2" Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.031+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="Vem Meu Amor 2" Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.036+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.037+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="Vem Meu Amor 2" Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.037+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.037+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="Vem Meu Amor 2" Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.037+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.037+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="Vem Meu Amor 2" Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.048+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.049+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="Vem Meu Amor 2" Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.049+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.050+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="Vem Meu Amor 2" Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.562+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=1000 volume=33 Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.562+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=1000 volume=33 Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.562+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="作词: Друзья/GIACOBINID$＿K-V" Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.563+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=1000 volume=33 Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.563+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="作词: Друзья/GIACOBINID$＿K-V" Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.564+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="作词: Друзья/GIACOBINID$＿K-V" Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.573+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.573+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="作词: Друзья/GIACOBINID$＿K-V" Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.574+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.575+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.575+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="作词: Друзья/GIACOBINID$＿K-V" Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:03 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.615+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.615+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="作词: Друзья/GIACOBINID$＿K-V" Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.615+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.615+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="作词: Друзья/GIACOBINID$＿K-V" Oct 11 11:56:03 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:03.616+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="作词: Друзья/GIACOBINID$＿K-V" Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.386+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.386+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="作曲: Друзья/GIACOBINID$＿K-V" Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.386+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.386+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="作曲: Друзья/GIACOBINID$＿K-V" Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.393+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=1000 volume=33 Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.393+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="作曲: Друзья/GIACOBINID$＿K-V" Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.393+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=1000 volume=33 Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.394+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="作曲: Друзья/GIACOBINID$＿K-V" Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.426+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=1000 volume=33 Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.427+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="作曲: Друзья/GIACOBINID$＿K-V" Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.427+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=1000 volume=33 Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.427+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="作曲: Друзья/GIACOBINID$＿K-V" Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.428+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=1000 volume=33 Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.429+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="作曲: Друзья/GIACOBINID$＿K-V" Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.884+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=2000 volume=33 Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.884+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=2000 volume=33 Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.884+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="编曲: Друзья" Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.885+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="编曲: Друзья" Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.885+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="编曲: Друзья" Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.894+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=2000 volume=33 Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.894+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=2000 volume=33 Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.894+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="编曲: Друзья" Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.894+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=2000 volume=33 Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.894+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="编曲: Друзья" Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.895+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="编曲: Друзья" Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:04 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.910+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=2000 volume=33 Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.910+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="编曲: Друзья" Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.910+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=2000 volume=33 Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.910+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="编曲: Друзья" Oct 11 11:56:04 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:04.911+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title="编曲: Друзья" Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:07 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:07.906+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=5000 volume=33 Oct 11 11:56:07 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:07.906+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title=纯音乐，请欣赏 Oct 11 11:56:07 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:07.907+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title=纯音乐，请欣赏 Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:07 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:07.914+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=5000 volume=33 Oct 11 11:56:07 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:07.915+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=5000 volume=33 Oct 11 11:56:07 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:07.915+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title=纯音乐，请欣赏 Oct 11 11:56:07 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:07.915+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title=纯音乐，请欣赏 Oct 11 11:56:07 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:07.916+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=5000 volume=33 Oct 11 11:56:07 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:07.916+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title=纯音乐，请欣赏 Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:07 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:07 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:07.952+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=5000 volume=33 Oct 11 11:56:07 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:07.952+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title=纯音乐，请欣赏 Oct 11 11:56:07 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:07.953+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=5000 volume=33 Oct 11 11:56:07 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:07.953+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title=纯音乐，请欣赏 Oct 11 11:56:12 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:12 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:12 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:56:12 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:12 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:12 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:12 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:12 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:12 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:12 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:12 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:12.689+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PAUSED positionMs=10000 volume=33 Oct 11 11:56:12 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:12.689+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title=纯音乐，请欣赏 Oct 11 11:56:12 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:12.690+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title=纯音乐，请欣赏 Oct 11 11:56:12 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:12 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:12 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:56:12 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:12 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:12 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:12 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:12 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:12 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:12 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:12 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:12.731+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=10000 volume=33 Oct 11 11:56:12 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:12.731+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=10000 volume=33 Oct 11 11:56:12 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:12.731+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title=纯音乐，请欣赏 Oct 11 11:56:12 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:12.732+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=10000 volume=33 Oct 11 11:56:12 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:12.732+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title=纯音乐，请欣赏 Oct 11 11:56:12 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:12.732+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title=纯音乐，请欣赏 Oct 11 11:56:19 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:19 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:19 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:56:19 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:19 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:19 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:19 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:19 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:56:19 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:19 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:19 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:19.884+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:56:19 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:19.884+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title= Oct 11 11:56:19 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:19.884+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:56:19 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:19.885+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title= Oct 11 11:56:19 volumio shairport-sync[8417]: {"time":1791690901769,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Oct 11 11:56:19 volumio shairport-sync[8417]: Dload Upload Total Spent Left Speed Oct 11 11:56:19 volumio volumio[8229]: info: Oct 11 11:56:19 volumio volumio[8229]: ---------------------------- Client requests Stop Airplay Playback Oct 11 11:56:19 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Oct 11 11:56:19 volumio volumio[8229]: info: Airplay playback stop Oct 11 11:56:19 volumio volumio[8229]: info: Airplay Playback Stopped, clearing state Oct 11 11:56:19 volumio volumio[8229]: info: CoreStateMachine::resetVolumioState Oct 11 11:56:19 volumio volumio[8229]: info: CoreStateMachine::getcurrentVolume Oct 11 11:56:19 volumio volumio[8229]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 11:56:19 volumio shairport-sync[8417]: [158B blob data] Oct 11 11:56:19 volumio volumio[8229]: info: VolumeController:: Volume=33 Mute =false Oct 11 11:56:19 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:19 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:19 volumio volumio[8229]: info: CoreStateMachine::updateTrackBlock Oct 11 11:56:19 volumio volumio[8229]: info: CorePlayQueue::getTrackBlock Oct 11 11:56:19 volumio volumio[8229]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 11:56:19 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:19.998+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:56:19 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:19.998+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title= Oct 11 11:56:20 volumio volumio[8229]: info: VolumeController:: Volume=33 Mute =false Oct 11 11:56:20 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:56:20 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:56:20 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:20.047+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:56:20 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:20.047+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879,00:00:00:00:00:00%01 @ 0x24d94a0" id= title= Oct 11 11:56:37 volumio dbus-daemon[657]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.18" (uid=0 pid=1426 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.4" (uid=0 pid=715 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Oct 11 11:56:40 volumio volumio5-onboarding[1426]: time=2026-10-11T11:56:40.226+08:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Oct 11 11:57:00 volumio volumio[8229]: info: Airplay started streaming, receiving metadatas Oct 11 11:57:00 volumio shairport-sync[8417]: {"time":1791690979911,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Oct 11 11:57:00 volumio shairport-sync[8417]: Dload Upload Total Spent Left Speed Oct 11 11:57:00 volumio volumio[8229]: info: Oct 11 11:57:00 volumio volumio[8229]: ---------------------------- Client requests Start Airplay PlaybackRoutine Oct 11 11:57:00 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Oct 11 11:57:00 volumio volumio[8229]: info: Airplay playback start Oct 11 11:57:00 volumio shairport-sync[8417]: [158B blob data] Oct 11 11:57:01 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:01 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:01 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:57:01 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:01.446+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PLAYING positionMs=1000 volume=33 Oct 11 11:57:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:01.446+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:01 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:01 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:01 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:01.449+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PLAYING positionMs=1000 volume=33 Oct 11 11:57:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:01.450+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:01 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:01 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:01 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:01.453+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PLAYING positionMs=1000 volume=33 Oct 11 11:57:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:01.453+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:01 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:01 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:01 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:01.482+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PLAYING positionMs=1000 volume=33 Oct 11 11:57:01 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:01.482+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:02 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:02 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:02 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:57:02 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:02 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:02 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:02 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:02 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:02 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:02 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:02 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:02.386+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PLAYING positionMs= volume=33 Oct 11 11:57:02 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:02.386+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:02 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:02.387+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:02 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:02.387+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PLAYING positionMs= volume=33 Oct 11 11:57:02 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:02.388+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:02 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:02 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:02 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:57:02 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:02 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:02 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:02 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:02 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:02 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:02 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:02 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:02.396+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PAUSED positionMs= volume=33 Oct 11 11:57:02 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:02.397+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PAUSED positionMs= volume=33 Oct 11 11:57:02 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:02.397+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PAUSED positionMs= volume=33 Oct 11 11:57:02 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:02.397+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title=纯音乐，请欣赏 Oct 11 11:57:02 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:02.397+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title=纯音乐，请欣赏 Oct 11 11:57:05 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:05 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:05 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:57:05 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:05 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:05 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:05 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:05 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:05 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:05 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:05 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:05.518+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PAUSED positionMs=0 volume=33 Oct 11 11:57:05 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:05.518+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:05 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:05.519+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:05 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:05.519+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PAUSED positionMs=0 volume=33 Oct 11 11:57:05 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:05.519+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:05 volumio shairport-sync[8417]: {"time":1791691020479,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Oct 11 11:57:05 volumio shairport-sync[8417]: Dload Upload Total Spent Left Speed Oct 11 11:57:05 volumio volumio[8229]: info: Oct 11 11:57:05 volumio volumio[8229]: ---------------------------- Client requests Stop Airplay Playback Oct 11 11:57:05 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Oct 11 11:57:05 volumio volumio[8229]: info: Airplay playback stop Oct 11 11:57:05 volumio volumio[8229]: info: Airplay Playback Stopped, clearing state Oct 11 11:57:05 volumio volumio[8229]: info: CoreStateMachine::resetVolumioState Oct 11 11:57:05 volumio volumio[8229]: info: CoreStateMachine::getcurrentVolume Oct 11 11:57:05 volumio volumio[8229]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 11:57:05 volumio shairport-sync[8417]: [158B blob data] Oct 11 11:57:05 volumio volumio[8229]: info: VolumeController:: Volume=33 Mute =false Oct 11 11:57:05 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:05 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:05 volumio volumio[8229]: info: CoreStateMachine::updateTrackBlock Oct 11 11:57:05 volumio volumio[8229]: info: CorePlayQueue::getTrackBlock Oct 11 11:57:05 volumio volumio[8229]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 11:57:05 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:05.625+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PAUSED positionMs=0 volume=33 Oct 11 11:57:05 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:05.626+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:05 volumio volumio[8229]: info: VolumeController:: Volume=33 Mute =false Oct 11 11:57:05 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:05 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:05 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:05.674+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PAUSED positionMs=0 volume=33 Oct 11 11:57:05 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:05.674+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:13 volumio volumio[8229]: info: Airplay started streaming, receiving metadatas Oct 11 11:57:13 volumio volumio[8229]: info: Airplay started streaming, receiving metadatas Oct 11 11:57:13 volumio shairport-sync[8417]: {"time":1791691025547,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Oct 11 11:57:13 volumio shairport-sync[8417]: Dload Upload Total Spent Left Speed Oct 11 11:57:13 volumio volumio[8229]: info: Oct 11 11:57:13 volumio volumio[8229]: ---------------------------- Client requests Start Airplay PlaybackRoutine Oct 11 11:57:13 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Oct 11 11:57:13 volumio volumio[8229]: info: Airplay playback start Oct 11 11:57:13 volumio shairport-sync[8417]: [158B blob data] Oct 11 11:57:14 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:14 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:14 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:57:14 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:14 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:14.704+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:57:14 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:14.705+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:14 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:14 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:14 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:14 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:14.707+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:57:14 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:14.707+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:14 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:14 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:14 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:14 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:14.710+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:57:14 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:14.710+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:14 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:14 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:14 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:14 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:14.736+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PLAYING positionMs=0 volume=33 Oct 11 11:57:14 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:14.736+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:15 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:15 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:15 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:57:15 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:15 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:15 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:15 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:15 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:15 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:15 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:15 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:15.636+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PLAYING positionMs= volume=33 Oct 11 11:57:15 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:15.636+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:15 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:15.637+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PLAYING positionMs= volume=33 Oct 11 11:57:15 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:15.637+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:15 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:15 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:15 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:57:15 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:15 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:15 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:15 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:15 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:15 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:15 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:15 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:15.645+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PAUSED positionMs= volume=33 Oct 11 11:57:15 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:15.646+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PAUSED positionMs= volume=33 Oct 11 11:57:15 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:15.646+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title=纯音乐，请欣赏 Oct 11 11:57:15 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:15.646+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title=纯音乐，请欣赏 Oct 11 11:57:21 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:21 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:21 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 11:57:21 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:21 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:21 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:21 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:21 volumio volumio[8229]: info: CoreCommandRouter::servicePushState Oct 11 11:57:21 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:21 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:21 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:21.858+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PAUSED positionMs=0 volume=33 Oct 11 11:57:21 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:21.858+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:21 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:21.859+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:21 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:21.859+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PAUSED positionMs=0 volume=33 Oct 11 11:57:21 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:21.859+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:21 volumio shairport-sync[8417]: {"time":1791691033733,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Oct 11 11:57:21 volumio shairport-sync[8417]: Dload Upload Total Spent Left Speed Oct 11 11:57:21 volumio volumio[8229]: info: Oct 11 11:57:21 volumio volumio[8229]: ---------------------------- Client requests Stop Airplay Playback Oct 11 11:57:21 volumio volumio[8229]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Oct 11 11:57:21 volumio volumio[8229]: info: Airplay playback stop Oct 11 11:57:21 volumio volumio[8229]: info: Airplay Playback Stopped, clearing state Oct 11 11:57:21 volumio volumio[8229]: info: CoreStateMachine::resetVolumioState Oct 11 11:57:21 volumio volumio[8229]: info: CoreStateMachine::getcurrentVolume Oct 11 11:57:21 volumio volumio[8229]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 11:57:21 volumio shairport-sync[8417]: [158B blob data] Oct 11 11:57:21 volumio volumio[8229]: info: VolumeController:: Volume=33 Mute =false Oct 11 11:57:21 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:21 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:21 volumio volumio[8229]: info: CoreStateMachine::updateTrackBlock Oct 11 11:57:21 volumio volumio[8229]: info: CorePlayQueue::getTrackBlock Oct 11 11:57:21 volumio volumio[8229]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 11:57:21 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:21.965+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PAUSED positionMs=0 volume=33 Oct 11 11:57:21 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:21.965+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:22 volumio volumio[8229]: info: VolumeController:: Volume=33 Mute =false Oct 11 11:57:22 volumio volumio[8229]: info: CoreStateMachine::pushState Oct 11 11:57:22 volumio volumio[8229]: info: CoreCommandRouter::volumioPushState Oct 11 11:57:22 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:22.012+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" state=STATUS_PAUSED positionMs=0 volume=33 Oct 11 11:57:22 volumio volumio5-onboarding[1426]: time=2026-10-11T11:57:22.013+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.111:49879 @ 0x24d94a0" id= title= Oct 11 11:57:31 volumio volumio[8229]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 11:57:31 volumio volumio[8229]: Error: connect ETIMEDOUT 128.242.240.149:443 Oct 11 11:57:31 volumio volumio[8229]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 11 11:57:31 volumio volumio[8229]: errno: -110, Oct 11 11:57:31 volumio volumio[8229]: code: 'ETIMEDOUT', Oct 11 11:57:31 volumio volumio[8229]: syscall: 'connect', Oct 11 11:57:31 volumio volumio[8229]: address: '128.242.240.149', Oct 11 11:57:31 volumio volumio[8229]: port: 443 Oct 11 11:57:31 volumio volumio[8229]: } Oct 11 11:57:31 volumio volumio[8229]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 11:57:31 volumio sudo[9714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-11 11:56 Oct 11 11:57:31 volumio sudo[9714]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"