Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio shairport-sync[12430]: {"time":1792149172047,"response":"startAirplayPlayback Success"}
Oct 16 18:13:58 volumio shairport-sync[12610]: % Total % Received % Xferd Average Speed Time Time Time Current
Oct 16 18:13:58 volumio shairport-sync[12610]: Dload Upload Total Spent Left Speed
Oct 16 18:13:58 volumio volumio[1189]: info:
Oct 16 18:13:58 volumio volumio[1189]: ---------------------------- Client requests Stop Airplay Playback
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback
Oct 16 18:13:58 volumio volumio[1189]: info: Airplay playback stop
Oct 16 18:13:58 volumio volumio[1189]: info: Airplay Playback Stopped, clearing state
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::resetVolumioState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::getcurrentVolume
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioRetrievevolume
Oct 16 18:13:58 volumio shairport-sync[12610]: [158B blob data]
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::updateTrackBlock
Oct 16 18:13:58 volumio volumio[1189]: info: CorePlayQueue::getTrackBlock
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioRetrievevolume
Oct 16 18:13:58 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:13:58 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:03 volumio shairport-sync[12610]: {"time":1792149238692,"response":"stopAirplayPlayback Success"}
Oct 16 18:14:03 volumio shairport-sync[12645]: % Total % Received % Xferd Average Speed Time Time Time Current
Oct 16 18:14:03 volumio shairport-sync[12645]: Dload Upload Total Spent Left Speed
Oct 16 18:14:03 volumio volumio[1189]: info:
Oct 16 18:14:03 volumio volumio[1189]: ---------------------------- Client requests Start Airplay PlaybackRoutine
Oct 16 18:14:03 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback
Oct 16 18:14:03 volumio volumio[1189]: info: Airplay playback start
Oct 16 18:14:03 volumio shairport-sync[12645]: [158B blob data]
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:04 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:05 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:05.817+07:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.7:52852
Oct 16 18:14:05 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:05.882+07:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.7:52852 @ 0xc0005af110" latency=149.935804ms platform=PLATFORM_IOS version=6.260930.0
Oct 16 18:14:05 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:05.884+07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.7:52852 @ 0xc0005af110" latency=149.051711ms timeout=20s
Oct 16 18:14:05 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:05.884+07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110"
Oct 16 18:14:05 volumio volumio[1189]: info: Received Get System Info
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 16 18:14:05 volumio volumio[1189]: info: Discovery: Getting this device information
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 16 18:14:05 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:05.891+07:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" name=Volumio
Oct 16 18:14:05 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:05.892+07:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" language=vi
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone
Oct 16 18:14:05 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:05.894+07:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" timezone=Asia/Ho_Chi_Minh
Oct 16 18:14:05 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:05.896+07:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" available=true connected=true macAddress=a4:bb:6d:12:d2:cc ip4Address=192.168.1.6/24 ip6Address=
Oct 16 18:14:05 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:05.897+07:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" available=false connected=false macAddress= ip4Address= ip6Address= ssid=
Oct 16 18:14:05 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:05.897+07:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" setupComplete=true
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 16 18:14:05 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Oct 16 18:14:05 volumio volumio[1189]: amixer -c 1 info | grep "bytcr-rt5640"
Oct 16 18:14:06 volumio volumio[1189]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 2
Oct 16 18:14:06 volumio volumio[1189]: /bin/sh: 1: /usr/local/bin/alsacap: not found
Oct 16 18:14:06 volumio volumio[1189]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 2\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"}
Oct 16 18:14:06 volumio volumio[1189]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 1
Oct 16 18:14:06 volumio volumio[1189]: /bin/sh: 1: /usr/local/bin/alsacap: not found
Oct 16 18:14:06 volumio volumio[1189]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 1","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 1\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"}
Oct 16 18:14:06 volumio volumio[1189]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0
Oct 16 18:14:06 volumio volumio[1189]: /bin/sh: 1: /usr/local/bin/alsacap: not found
Oct 16 18:14:06 volumio volumio[1189]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"}
Oct 16 18:14:06 volumio volumio[1189]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5
Oct 16 18:14:06 volumio volumio[1189]: /bin/sh: 1: /usr/local/bin/alsacap: not found
Oct 16 18:14:06 volumio volumio[1189]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"}
Oct 16 18:14:06 volumio volumio[1189]: amixer -c 5 info | grep "i.MXRT1011 Audio USB"
Oct 16 18:14:06 volumio volumio[1189]: Card sysdefault:5 'USB'/'Custom Build i.MXRT1011 Audio USB at usb-0000:00:14.0-4, high speed'
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio shairport-sync[12645]: {"time":1792149243248,"response":"startAirplayPlayback Success"}
Oct 16 18:14:06 volumio shairport-sync[12675]: % Total % Received % Xferd Average Speed Time Time Time Current
Oct 16 18:14:06 volumio shairport-sync[12675]: Dload Upload Total Spent Left Speed
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.147+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.147+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.148+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.149+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.149+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.149+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.149+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.149+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.149+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.149+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.149+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.150+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.150+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.150+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.150+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.150+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.151+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.151+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.151+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.152+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.152+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.152+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.153+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.153+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.153+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.153+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.153+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.154+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.154+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.154+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.154+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.154+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.155+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.155+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.155+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.156+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.156+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.156+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.157+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.157+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.157+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.158+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.158+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.158+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.159+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.159+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.160+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.160+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.160+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.161+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.161+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.161+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.162+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.162+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.162+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.162+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.163+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.163+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.163+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.164+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.164+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.164+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.165+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.165+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.165+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.166+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.166+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.166+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.166+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.167+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.167+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.167+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.167+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.167+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.168+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.168+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.168+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.168+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.168+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.168+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.169+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.169+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.170+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.170+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.170+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.171+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.171+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.171+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.172+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.172+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.172+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.171+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.172+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.172+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.173+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.173+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.174+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.174+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.175+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.176+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.176+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.176+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.177+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.177+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.177+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.176+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.177+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.177+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.178+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.177+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.178+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.178+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.176+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.178+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio[1189]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5
Oct 16 18:14:06 volumio volumio[1189]: /bin/sh: 1: /usr/local/bin/alsacap: not found
Oct 16 18:14:06 volumio volumio[1189]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"}
Oct 16 18:14:06 volumio volumio[1189]: amixer -c 5 info | grep "i.MXRT1011 Audio USB"
Oct 16 18:14:06 volumio volumio[1189]: Card sysdefault:5 'USB'/'Custom Build i.MXRT1011 Audio USB at usb-0000:00:14.0-4, high speed'
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.242+07:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" selectedOutputId=5
Oct 16 18:14:06 volumio volumio[1189]: info: Received Get System Info
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 16 18:14:06 volumio volumio[1189]: info: Discovery: Getting this device information
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.286+07:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" currentVersion=4.119 latestVersion=4.119
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.286+07:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" status=UPDATE_STATUS_NONE progress=0
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.286+07:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" userId=
Oct 16 18:14:06 volumio volumio[1189]: info:
Oct 16 18:14:06 volumio volumio[1189]: ---------------------------- Client requests Stop Airplay Playback
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.291+07:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" providers=3
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.291+07:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" plugins=58
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay playback stop
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay Playback Stopped, clearing state
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::resetVolumioState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::getcurrentVolume
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioRetrievevolume
Oct 16 18:14:06 volumio shairport-sync[12675]: [158B blob data]
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.336+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.336+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::updateTrackBlock
Oct 16 18:14:06 volumio volumio[1189]: info: CorePlayQueue::getTrackBlock
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioRetrievevolume
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.446+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.447+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.599+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:06 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:06.599+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio shairport-sync[12675]: {"time":1792149246290,"response":"stopAirplayPlayback Success"}
Oct 16 18:14:06 volumio shairport-sync[12696]: % Total % Received % Xferd Average Speed Time Time Time Current
Oct 16 18:14:06 volumio shairport-sync[12696]: Dload Upload Total Spent Left Speed
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay started streaming, receiving metadatas
Oct 16 18:14:06 volumio volumio[1189]: info:
Oct 16 18:14:06 volumio volumio[1189]: ---------------------------- Client requests Start Airplay PlaybackRoutine
Oct 16 18:14:06 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback
Oct 16 18:14:06 volumio volumio[1189]: info: Airplay playback start
Oct 16 18:14:06 volumio shairport-sync[12696]: [158B blob data]
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 16 18:14:07 volumio volumio[1189]: info: Discovery: Getting this device information
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 16 18:14:07 volumio volumio[1189]: verbose: New Socket.io Connection to 192.168.1.6:3000 from 192.168.1.7 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.340+07:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.7:52852 @ 0xc0005af110" latency=148.790534ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.365+07:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:52852 @ 0xc0005af110" latency=148.521776ms timeout=10s
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.597+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:52852 @ 0xc0005af110" latency=148.521776ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=231.79551ms
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.605+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.605+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.611+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.613+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.615+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.616+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.619+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.619+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.629+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:52852 @ 0xc0005af110" latency=148.521776ms timeout=10s endpoint=https://www.googleapis.com duration=259.591445ms
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.630+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.630+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.630+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.631+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:52852 @ 0xc0005af110" latency=148.521776ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=263.697646ms
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.633+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.636+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.636+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.641+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.643+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.645+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.645+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.649+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.650+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.654+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.658+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.658+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.659+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.665+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.671+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.674+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.678+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.688+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.688+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.689+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.690+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.692+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.695+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.695+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.697+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.700+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.700+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.701+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.701+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.702+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.702+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.704+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.704+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.707+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.707+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.711+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.711+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.714+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.714+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.717+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.717+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.720+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.720+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.724+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.724+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.724+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:52852 @ 0xc0005af110" latency=148.521776ms timeout=10s endpoint=https://google.com duration=356.740226ms
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.725+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:52852 @ 0xc0005af110" latency=148.521776ms timeout=10s endpoint=https://securetoken.googleapis.com duration=357.919686ms
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.731+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.733+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.735+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.737+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.738+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.739+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.739+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:52852 @ 0xc0005af110" latency=148.521776ms timeout=10s endpoint=https://functions.volumio.cloud duration=372.661436ms
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.739+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:52852 @ 0xc0005af110" latency=148.521776ms timeout=10s endpoint=https://functions.volumio.cloud duration=370.536729ms
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.742+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.742+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.745+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.745+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.748+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.748+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.752+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.754+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.754+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.755+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.757+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.758+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.760+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:52852 @ 0xc0005af110" latency=148.521776ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=393.956485ms
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.761+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.763+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.764+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.764+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.767+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.768+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.771+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.771+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.774+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.776+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.778+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.778+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.781+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.781+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.784+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.786+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.790+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:52852 @ 0xc0005af110" latency=148.521776ms timeout=10s endpoint=http://pushupdates.volumio.org duration=422.567139ms
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.812+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.812+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.818+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.819+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.821+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.823+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.824+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.825+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.826+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.827+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.829+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.830+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.832+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.833+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.836+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.836+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.839+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.840+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.843+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=1000 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.843+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.846+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.848+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.849+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.849+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:07 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.853+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs=0 volume=100
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.854+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id= title=
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.907+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:52852 @ 0xc0005af110" latency=148.521776ms timeout=10s endpoint=http://plugins.volumio.org duration=538.383907ms
Oct 16 18:14:07 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:07.921+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:52852 @ 0xc0005af110" latency=148.521776ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=553.599036ms
Oct 16 18:14:08 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:08.067+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:52852 @ 0xc0005af110" latency=148.521776ms timeout=10s endpoint=https://database.volumio.cloud duration=698.126608ms
Oct 16 18:14:08 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:08.243+07:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.7:52852 @ 0xc0005af110" latency=148.521776ms timeout=10s endpoint=http://cddb.volumio.org duration=874.932106ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF"
Oct 16 18:14:08 volumio sudo[12706]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 16 18:14:08 volumio sudo[12706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Oct 16 18:14:08 volumio sudo[12706]: pam_unix(sudo:session): session closed for user root
Oct 16 18:14:08 volumio sudo[12708]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 16 18:14:08 volumio sudo[12708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Oct 16 18:14:08 volumio sudo[12708]: pam_unix(sudo:session): session closed for user root
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::servicePushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreStateMachine::pushState
Oct 16 18:14:08 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState
Oct 16 18:14:08 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:08.561+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" state=STATUS_PLAYING positionMs= volume=100
Oct 16 18:14:08 volumio volumio5-onboarding[1907]: time=2026-10-16T18:14:08.562+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.7:52852 @ 0xc0005af110" id=