Mar 24 18:49:49 desk volumio[1211]: info: CoreCommandRouter::volumioGetState Mar 24 18:49:49 desk volumio[1211]: info: CorePlayQueue::getTrack 0 Mar 24 18:49:53 desk volumio[1211]: info: CoreCommandRouter::volumioGetQueue Mar 24 18:49:53 desk volumio[1211]: info: CoreStateMachine::getQueue Mar 24 18:49:53 desk volumio[1211]: info: CorePlayQueue::getQueue Mar 24 18:49:56 desk volumio[1211]: info: CoreCommandRouter::volumioGetState Mar 24 18:49:56 desk volumio[1211]: info: CorePlayQueue::getTrack 0 Mar 24 18:50:00 desk volumio[1211]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 18:50:00 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 24 18:50:00 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 18:50:00 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 24 18:50:00 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 18:50:00 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 18:50:00 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 18:50:00 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 18:50:00 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 18:50:00 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 18:50:00 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 18:50:00 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 24 18:50:00 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 24 18:50:05 desk systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 24 18:50:06 desk volumio[1211]: info: CoreCommandRouter::volumioGetState Mar 24 18:50:06 desk volumio[1211]: info: CorePlayQueue::getTrack 0 Mar 24 18:50:13 desk volumio[1211]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 18:50:13 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 24 18:50:13 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 18:50:13 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 24 18:50:13 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 18:50:13 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 18:50:13 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 18:50:13 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 18:50:13 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 18:50:13 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 18:50:13 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 18:50:13 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 24 18:50:13 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 24 18:50:14 desk bash[2913]: setdatetime-helper: all HTTPS Date fallbacks failed Mar 24 18:50:14 desk systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 24 18:50:14 desk systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 24 18:50:14 desk systemd[1]: setdatetime-helper.service: Consumed 1.089s CPU time. Mar 24 18:50:49 desk volumio[1211]: info: Airplay started streaming, receiving metadatas Mar 24 18:50:49 desk volumio[1211]: info: CorePlayQueue::getTrack 0 Mar 24 18:50:49 desk volumio[1211]: info: CoreCommandRouter::volumioStop Mar 24 18:50:49 desk volumio[1211]: info: CoreStateMachine::stop Mar 24 18:50:49 desk volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 18:50:49 desk volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 18:50:49 desk volumio[1211]: info: Airplay started streaming, receiving metadatas Mar 24 18:50:49 desk volumio[1211]: info: CorePlayQueue::getTrack 0 Mar 24 18:50:49 desk volumio[1211]: info: CoreCommandRouter::volumioStop Mar 24 18:50:49 desk volumio[1211]: info: CoreStateMachine::stop Mar 24 18:50:49 desk volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 18:50:49 desk volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 18:50:49 desk volumio[1211]: info: Airplay started streaming, receiving metadatas Mar 24 18:50:49 desk volumio[1211]: info: CorePlayQueue::getTrack 0 Mar 24 18:50:49 desk volumio[1211]: info: CoreCommandRouter::volumioStop Mar 24 18:50:49 desk volumio[1211]: info: CoreStateMachine::stop Mar 24 18:50:49 desk volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 18:50:49 desk volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 18:50:49 desk shairport-sync[3041]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 24 18:50:49 desk shairport-sync[3041]: Dload Upload Total Spent Left Speed Mar 24 18:50:49 desk volumio[1211]: info: Mar 24 18:50:49 desk volumio[1211]: ---------------------------- Client requests AirplayActive Mar 24 18:50:49 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Mar 24 18:50:49 desk shairport-sync[3041]: [158B blob data] Mar 24 18:50:49 desk shairport-sync[3041]: {"time":1774374649585,"response":"airplayActive Success"} Mar 24 18:50:49 desk shairport-sync[3042]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 24 18:50:49 desk shairport-sync[3042]: Dload Upload Total Spent Left Speed Mar 24 18:50:49 desk volumio[1211]: info: Mar 24 18:50:49 desk volumio[1211]: ---------------------------- Client requests Start Airplay PlaybackRoutine Mar 24 18:50:49 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Mar 24 18:50:49 desk volumio[1211]: info: Airplay playback start Mar 24 18:50:49 desk volumio[1211]: info: CorePlayQueue::getTrack 0 Mar 24 18:50:49 desk volumio[1211]: info: CoreCommandRouter::volumioStop Mar 24 18:50:49 desk volumio[1211]: info: CoreStateMachine::stop Mar 24 18:50:49 desk volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 18:50:49 desk volumio[1211]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 18:50:49 desk shairport-sync[3042]: [158B blob data] Mar 24 18:50:49 desk volumio[1211]: info: Updating volume from AirPlay: -20; 33% Mar 24 18:50:49 desk volumio[1211]: info: VolumeController::SetAlsaVolume33 Mar 24 18:50:49 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:49 desk volumio[1211]: info: CorePlayQueue::getTrack 0 Mar 24 18:50:49 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 18:50:49 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:49 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:49.696+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_STOPPED positionMs=0 volume=33 Mar 24 18:50:49 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:49.696+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title= Mar 24 18:50:50 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:50 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:50 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 18:50:50 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:50 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:50.586+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs=0 volume=33 Mar 24 18:50:50 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:50.587+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title= Mar 24 18:50:50 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:50 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:50 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:50 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:50.598+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs=0 volume=33 Mar 24 18:50:50 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:50.599+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title= Mar 24 18:50:50 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:50 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:50 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:50 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:50.607+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs=0 volume=33 Mar 24 18:50:50 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:50.607+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title= Mar 24 18:50:50 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:50 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:50 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:50 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:50.619+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs=0 volume=33 Mar 24 18:50:50 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:50.619+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title= Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:51 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:51 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:51 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.487+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs= volume=33 Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.488+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs= volume=33 Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.489+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title= Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.489+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title= Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.489+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs= volume=33 Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.490+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title= Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:51 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:51 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:51 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.513+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs= volume=33 Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.513+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title="Cheri Cheri Lady" Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.514+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs= volume=33 Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.514+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title="Cheri Cheri Lady" Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.515+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs= volume=33 Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.515+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title="Cheri Cheri Lady" Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:51 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:51 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:51 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.712+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs= volume=33 Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.712+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title="Cheri Cheri Lady" Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.713+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs= volume=33 Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.713+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title="Cheri Cheri Lady" Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.714+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs= volume=33 Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.715+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title="Cheri Cheri Lady" Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:51 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:51 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:51 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.739+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs=16000 volume=33 Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.740+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title="Cheri Cheri Lady" Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.740+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs=16000 volume=33 Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.741+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title="Cheri Cheri Lady" Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.742+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs=16000 volume=33 Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.742+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title="Cheri Cheri Lady" Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:51 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:51 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::servicePushState Mar 24 18:50:51 desk volumio[1211]: info: CoreStateMachine::pushState Mar 24 18:50:51 desk volumio[1211]: info: CoreCommandRouter::volumioPushState Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.767+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs=16000 volume=33 Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.767+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title="Cheri Cheri Lady" Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.770+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs=16000 volume=33 Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.770+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title="Cheri Cheri Lady" Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.775+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" state=STATUS_PLAYING positionMs=16000 volume=33 Mar 24 18:50:51 desk volumio5-onboarding[1506]: time=2026-03-24T18:50:51.775+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.45.63:55847,00:00:00:00:00:00%01 @ 0x3001260" id= title="Cheri Cheri Lady" Mar 24 18:50:51 desk volumio[1211]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 18:50:51 desk volumio[1211]: Error: certificate is not yet valid Mar 24 18:50:51 desk volumio[1211]: at TLSSocket.onConnectSecure (node:_tls_wrap:1627:34) Mar 24 18:50:51 desk volumio[1211]: at TLSSocket.emit (node:events:514:28) Mar 24 18:50:51 desk volumio[1211]: at TLSSocket._finishInit (node:_tls_wrap:1038:8) Mar 24 18:50:51 desk volumio[1211]: at ssl.onhandshakedone (node:_tls_wrap:824:12) { Mar 24 18:50:51 desk volumio[1211]: code: 'CERT_NOT_YET_VALID' Mar 24 18:50:51 desk volumio[1211]: } Mar 24 18:50:51 desk volumio[1211]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 18:50:52 desk sudo[3066]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 18:49' Mar 24 18:50:52 desk sudo[3066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"