Oct 14 22:46:00 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 22:46:01 volumio volumio[1326]: info: MPD Database updated - AlbumList cache refreshed Oct 14 22:46:01 volumio volumio[1326]: info: Oct 14 22:46:01 volumio volumio[1326]: ---------------------------- MPD announces state update: database Oct 14 22:46:01 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Oct 14 22:46:01 volumio volumio[1326]: info: Oct 14 22:46:01 volumio volumio[1326]: ---------------------------- MPD announces state update: update Oct 14 22:46:01 volumio volumio[1326]: info: ControllerMpd::getState Oct 14 22:46:01 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Oct 14 22:46:01 volumio volumio[1326]: info: ControllerMpd::getState Oct 14 22:46:01 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Oct 14 22:46:01 volumio volumio[1326]: info: MPD Database updated - AlbumList cache refreshed Oct 14 22:46:01 volumio volumio[1326]: info: Oct 14 22:46:01 volumio volumio[1326]: ---------------------------- MPD announces state update: database Oct 14 22:46:01 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Oct 14 22:46:01 volumio volumio[1326]: info: Oct 14 22:46:01 volumio volumio[1326]: ---------------------------- MPD announces state update: update Oct 14 22:46:01 volumio volumio[1326]: info: ControllerMpd::getState Oct 14 22:46:01 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Oct 14 22:46:01 volumio volumio[1326]: info: ControllerMpd::getState Oct 14 22:46:01 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Oct 14 22:46:01 volumio volumio[1326]: info: MPD Database updated - AlbumList cache refreshed Oct 14 22:46:01 volumio volumio[1326]: info: Oct 14 22:46:01 volumio volumio[1326]: ---------------------------- MPD announces state update: database Oct 14 22:46:01 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Oct 14 22:46:01 volumio volumio[1326]: info: Oct 14 22:46:01 volumio volumio[1326]: ---------------------------- MPD announces state update: update Oct 14 22:46:01 volumio volumio[1326]: info: ControllerMpd::getState Oct 14 22:46:01 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Oct 14 22:46:01 volumio volumio[1326]: info: ControllerMpd::getState Oct 14 22:46:01 volumio volumio[1326]: verbose: ControllerMpd::sendMpdCommand status Oct 14 22:46:02 volumio volumio[1326]: Cannot compose Albumart path Oct 14 22:46:02 volumio volumio[1326]: info: sendMpdCommand status took 1964 milliseconds Oct 14 22:46:02 volumio volumio[1326]: info: sendMpdCommand status took 1963 milliseconds Oct 14 22:46:02 volumio volumio[1326]: info: sendMpdCommand status took 1962 milliseconds Oct 14 22:46:02 volumio volumio[1326]: verbose: ControllerMpd::parseState Oct 14 22:46:02 volumio volumio[1326]: info: Command Router : Notfying DB Updatefalse Oct 14 22:46:02 volumio volumio[1326]: info: CoreCommandRouter::Close All Modals sent Oct 14 22:46:02 volumio volumio[1326]: verbose: ControllerMpd::parseState Oct 14 22:46:02 volumio volumio[1326]: verbose: ControllerMpd::parseState Oct 14 22:46:03 volumio volumio[1326]: info: ControllerMpd::pushState Oct 14 22:46:03 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Oct 14 22:46:03 volumio volumio[1326]: info: CoreStateMachine::pushState Oct 14 22:46:03 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:03 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Oct 14 22:46:03 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:03 volumio volumio[1326]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 14 22:46:03 volumio volumio[1326]: verbose: CURRENT POSITION 0 Oct 14 22:46:03 volumio volumio[1326]: info: CoreStateMachine::syncState stateService stop Oct 14 22:46:03 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus stop Oct 14 22:46:03 volumio volumio[1326]: info: CoreStateMachine::pushState Oct 14 22:46:03 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:03 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 22:46:03 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Oct 14 22:46:03 volumio volumio[1326]: info: No code Oct 14 22:46:03 volumio volumio[1326]: info: CoreStateMachine::pushState Oct 14 22:46:03 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:03 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Oct 14 22:46:03 volumio volumio[1326]: info: ControllerMpd::pushState Oct 14 22:46:03 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Oct 14 22:46:03 volumio volumio[1326]: info: CoreStateMachine::pushState Oct 14 22:46:03 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:03 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Oct 14 22:46:03 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:03 volumio volumio[1326]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 14 22:46:03 volumio volumio[1326]: verbose: CURRENT POSITION 0 Oct 14 22:46:03 volumio volumio[1326]: info: CoreStateMachine::syncState stateService stop Oct 14 22:46:03 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus stop Oct 14 22:46:03 volumio volumio[1326]: info: CoreStateMachine::pushState Oct 14 22:46:03 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:03 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Oct 14 22:46:03 volumio volumio[1326]: info: No code Oct 14 22:46:03 volumio volumio[1326]: info: CoreStateMachine::pushState Oct 14 22:46:03 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:03 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Oct 14 22:46:03 volumio volumio[1326]: info: ------------------------------ 2085ms Oct 14 22:46:03 volumio volumio[1326]: info: ------------------------------ 2088ms Oct 14 22:46:03 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:03.093+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 22:46:03 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:03.093+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 22:46:03 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:03.093+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 22:46:03 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:03.094+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" id= title= Oct 14 22:46:03 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:03.094+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" id= title= Oct 14 22:46:03 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:03.094+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 22:46:03 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:03.094+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" id= title= Oct 14 22:46:03 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:03.094+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" id= title= Oct 14 22:46:03 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:03.076+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 22:46:03 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:03.100+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" id= title= Oct 14 22:46:03 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:03.076+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 22:46:03 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:03.102+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" id= title= Oct 14 22:46:03 volumio volumio[1326]: info: Display-configuration --- Volumio status=stop timeout=301 noifplay=true screensavertype=dpms Oct 14 22:46:03 volumio volumio[1326]: info: Display-configuration --- Volumio status=stop timeout=301 noifplay=true screensavertype=dpms Oct 14 22:46:03 volumio volumio[1326]: info: Display-configuration --- Volumio status=stop timeout=301 noifplay=true screensavertype=dpms Oct 14 22:46:03 volumio volumio[1326]: info: Display-configuration --- Volumio status=stop timeout=301 noifplay=true screensavertype=dpms Oct 14 22:46:03 volumio volumio[1326]: info: Display-configuration --- Volumio status=stop timeout=301 noifplay=true screensavertype=dpms Oct 14 22:46:03 volumio volumio[1326]: info: Display-configuration --- Volumio status=stop timeout=301 noifplay=true screensavertype=dpms Oct 14 22:46:03 volumio volumio[1326]: Cannot compose Albumart path Oct 14 22:46:04 volumio volumio[1326]: info: sendMpdCommand status took 3118 milliseconds Oct 14 22:46:04 volumio volumio[1326]: info: sendMpdCommand status took 3117 milliseconds Oct 14 22:46:04 volumio volumio[1326]: info: sendMpdCommand status took 3116 milliseconds Oct 14 22:46:04 volumio volumio[1326]: verbose: ControllerMpd::parseState Oct 14 22:46:04 volumio volumio[1326]: info: Command Router : Notfying DB Updatefalse Oct 14 22:46:04 volumio volumio[1326]: info: CoreCommandRouter::Close All Modals sent Oct 14 22:46:04 volumio volumio[1326]: verbose: ControllerMpd::parseState Oct 14 22:46:04 volumio volumio[1326]: verbose: ControllerMpd::parseState Oct 14 22:46:04 volumio volumio[1326]: info: ControllerMpd::pushState Oct 14 22:46:04 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Oct 14 22:46:04 volumio volumio[1326]: info: CoreStateMachine::pushState Oct 14 22:46:04 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:04 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Oct 14 22:46:04 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:04 volumio volumio[1326]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 14 22:46:04 volumio volumio[1326]: verbose: CURRENT POSITION 0 Oct 14 22:46:04 volumio volumio[1326]: info: CoreStateMachine::syncState stateService stop Oct 14 22:46:04 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus stop Oct 14 22:46:04 volumio volumio[1326]: info: CoreStateMachine::pushState Oct 14 22:46:04 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:04 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Oct 14 22:46:04 volumio volumio[1326]: info: No code Oct 14 22:46:04 volumio volumio[1326]: info: CoreStateMachine::pushState Oct 14 22:46:04 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:04 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Oct 14 22:46:04 volumio volumio[1326]: info: ControllerMpd::pushState Oct 14 22:46:04 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Oct 14 22:46:04 volumio volumio[1326]: info: CoreStateMachine::pushState Oct 14 22:46:04 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:04 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Oct 14 22:46:04 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:04 volumio volumio[1326]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 14 22:46:04 volumio volumio[1326]: verbose: CURRENT POSITION 0 Oct 14 22:46:04 volumio volumio[1326]: info: CoreStateMachine::syncState stateService stop Oct 14 22:46:04 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus stop Oct 14 22:46:04 volumio volumio[1326]: info: CoreStateMachine::pushState Oct 14 22:46:04 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:04 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Oct 14 22:46:04 volumio volumio[1326]: info: No code Oct 14 22:46:04 volumio volumio[1326]: info: CoreStateMachine::pushState Oct 14 22:46:04 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:04 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Oct 14 22:46:04 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:04.197+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 22:46:04 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:04.198+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" id= title= Oct 14 22:46:04 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:04.198+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 22:46:04 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:04.199+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" id= title= Oct 14 22:46:04 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:04.200+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 22:46:04 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:04.200+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" id= title= Oct 14 22:46:04 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:04.201+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 22:46:04 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:04.202+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" id= title= Oct 14 22:46:04 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:04.202+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 22:46:04 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:04.202+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" id= title= Oct 14 22:46:04 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:04.204+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 22:46:04 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:04.204+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" id= title= Oct 14 22:46:04 volumio volumio[1326]: info: ------------------------------ 3198ms Oct 14 22:46:04 volumio volumio[1326]: info: ------------------------------ 3199ms Oct 14 22:46:04 volumio volumio[1326]: info: Display-configuration --- Volumio status=stop timeout=301 noifplay=true screensavertype=dpms Oct 14 22:46:04 volumio volumio[1326]: info: Display-configuration --- Volumio status=stop timeout=301 noifplay=true screensavertype=dpms Oct 14 22:46:04 volumio volumio[1326]: info: Display-configuration --- Volumio status=stop timeout=301 noifplay=true screensavertype=dpms Oct 14 22:46:04 volumio volumio[1326]: info: Display-configuration --- Volumio status=stop timeout=301 noifplay=true screensavertype=dpms Oct 14 22:46:04 volumio volumio[1326]: info: Display-configuration --- Volumio status=stop timeout=301 noifplay=true screensavertype=dpms Oct 14 22:46:04 volumio volumio[1326]: info: Display-configuration --- Volumio status=stop timeout=301 noifplay=true screensavertype=dpms Oct 14 22:46:04 volumio volumio[1326]: Cannot compose Albumart path Oct 14 22:46:05 volumio volumio[1326]: info: sendMpdCommand status took 4252 milliseconds Oct 14 22:46:05 volumio volumio[1326]: info: sendMpdCommand status took 4251 milliseconds Oct 14 22:46:05 volumio volumio[1326]: info: sendMpdCommand status took 4250 milliseconds Oct 14 22:46:05 volumio volumio[1326]: verbose: ControllerMpd::parseState Oct 14 22:46:05 volumio volumio[1326]: info: Command Router : Notfying DB Updatefalse Oct 14 22:46:05 volumio volumio[1326]: info: CoreCommandRouter::Close All Modals sent Oct 14 22:46:05 volumio volumio[1326]: verbose: ControllerMpd::parseState Oct 14 22:46:05 volumio volumio[1326]: verbose: ControllerMpd::parseState Oct 14 22:46:05 volumio volumio[1326]: info: ControllerMpd::pushState Oct 14 22:46:05 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Oct 14 22:46:05 volumio volumio[1326]: info: CoreStateMachine::pushState Oct 14 22:46:05 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:05 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Oct 14 22:46:05 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:05 volumio volumio[1326]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 14 22:46:05 volumio volumio[1326]: verbose: CURRENT POSITION 0 Oct 14 22:46:05 volumio volumio[1326]: info: CoreStateMachine::syncState stateService stop Oct 14 22:46:05 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus stop Oct 14 22:46:05 volumio volumio[1326]: info: CoreStateMachine::pushState Oct 14 22:46:05 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:05 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Oct 14 22:46:05 volumio volumio[1326]: info: No code Oct 14 22:46:05 volumio volumio[1326]: info: CoreStateMachine::pushState Oct 14 22:46:05 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:05 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Oct 14 22:46:05 volumio volumio[1326]: info: ControllerMpd::pushState Oct 14 22:46:05 volumio volumio[1326]: info: CoreCommandRouter::servicePushState Oct 14 22:46:05 volumio volumio[1326]: info: CoreStateMachine::pushState Oct 14 22:46:05 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:05 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Oct 14 22:46:05 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:05 volumio volumio[1326]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 14 22:46:05 volumio volumio[1326]: verbose: CURRENT POSITION 0 Oct 14 22:46:05 volumio volumio[1326]: info: CoreStateMachine::syncState stateService stop Oct 14 22:46:05 volumio volumio[1326]: info: CoreStateMachine::syncState currentStatus stop Oct 14 22:46:05 volumio volumio[1326]: info: CoreStateMachine::pushState Oct 14 22:46:05 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:05 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Oct 14 22:46:05 volumio volumio[1326]: info: No code Oct 14 22:46:05 volumio volumio[1326]: info: CoreStateMachine::pushState Oct 14 22:46:05 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:05 volumio volumio[1326]: info: CoreCommandRouter::volumioPushState Oct 14 22:46:05 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:05.337+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 22:46:05 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:05.338+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" id= title= Oct 14 22:46:05 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:05.339+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 22:46:05 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:05.340+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 22:46:05 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:05.341+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 22:46:05 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:05.341+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 22:46:05 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:05.342+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" id= title= Oct 14 22:46:05 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:05.342+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" id= title= Oct 14 22:46:05 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:05.342+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" id= title= Oct 14 22:46:05 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:05.343+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 22:46:05 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:05.343+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" id= title= Oct 14 22:46:05 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:05.343+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" id= title= Oct 14 22:46:05 volumio volumio[1326]: info: ------------------------------ 4334ms Oct 14 22:46:05 volumio volumio[1326]: info: ------------------------------ 4334ms Oct 14 22:46:05 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 14 22:46:05 volumio volumio[1326]: info: Display-configuration --- Volumio status=stop timeout=301 noifplay=true screensavertype=dpms Oct 14 22:46:05 volumio volumio[1326]: info: Display-configuration --- Volumio status=stop timeout=301 noifplay=true screensavertype=dpms Oct 14 22:46:05 volumio volumio[1326]: info: Display-configuration --- Volumio status=stop timeout=301 noifplay=true screensavertype=dpms Oct 14 22:46:05 volumio volumio[1326]: info: Display-configuration --- Volumio status=stop timeout=301 noifplay=true screensavertype=dpms Oct 14 22:46:05 volumio volumio[1326]: info: Display-configuration --- Volumio status=stop timeout=301 noifplay=true screensavertype=dpms Oct 14 22:46:05 volumio volumio[1326]: info: Display-configuration --- Volumio status=stop timeout=301 noifplay=true screensavertype=dpms Oct 14 22:46:08 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 22:46:08 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 14 22:46:12 volumio volumio[1326]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 22:46:12 volumio volumio[1326]: warn: [now-playing] MyBackgroundMonitor is not running. Returning empty image list. Oct 14 22:46:31 volumio volumio[1326]: info: CALLMETHOD: user_interface now_playing configureVolumioKiosk [object Object] Oct 14 22:46:31 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: now_playing , configureVolumioKiosk Oct 14 22:46:31 volumio volumio[1326]: info: [now-playing] Backing up /opt/volumiokiosk.sh to /home/volumio/.now_playing/volumiokiosk.sh.bak Oct 14 22:46:31 volumio sudo[2759]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:3000/localhost:4004/g /opt/volumiokiosk.sh Oct 14 22:46:31 volumio sudo[2759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 22:46:31 volumio sudo[2759]: pam_unix(sudo:session): session closed for user root Oct 14 22:46:31 volumio volumio[1326]: [sudo] password for volumio: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Oct 14 22:46:32 volumio sudo[2763]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Oct 14 22:46:32 volumio sudo[2763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 22:46:32 volumio sudo[2763]: pam_unix(sudo:session): session closed for user root Oct 14 22:46:32 volumio volumio[1326]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl restart volumio-kiosk Oct 14 22:46:32 volumio sudo[2766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk Oct 14 22:46:32 volumio sudo[2766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 22:46:32 volumio systemd[1]: Stopping volumio-kiosk.service - Start Volumio Kiosk... Oct 14 22:46:32 volumio startx[1397]: xinit: connection to X server lost Oct 14 22:46:32 volumio startx[1397]: Oct 14 22:46:32 volumio startx[1397]: waiting for X server to shut down Oct 14 22:46:32 volumio startx[1398]: (II) Server terminated successfully (0). Closing log file. Oct 14 22:46:33 volumio startx[1397]: xinit: unexpected signal 15 Oct 14 22:46:33 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Oct 14 22:46:33 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Oct 14 22:46:33 volumio systemd[1]: Stopped volumio-kiosk.service - Start Volumio Kiosk. Oct 14 22:46:33 volumio systemd[1]: volumio-kiosk.service: Consumed 1min 5.672s CPU time. Oct 14 22:46:33 volumio systemd[1]: Started volumio-kiosk.service - Start Volumio Kiosk. Oct 14 22:46:33 volumio sudo[2766]: pam_unix(sudo:session): session closed for user root Oct 14 22:46:33 volumio volumio[1326]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 22:46:33 volumio volumio[1326]: warn: [now-playing] MyBackgroundMonitor is not running. Returning empty image list. Oct 14 22:46:33 volumio startx[2795]: X.Org X Server 1.21.1.7 Oct 14 22:46:33 volumio startx[2795]: X Protocol Version 11, Revision 0 Oct 14 22:46:33 volumio startx[2795]: Current Operating System: Linux volumio 6.12.57-volumio #2 SMP PREEMPT_DYNAMIC Thu Nov 13 17:08:37 CET 2025 x86_64 Oct 14 22:46:33 volumio startx[2795]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet video=HDMI-A-1:panel_orientation=upside_down loglevel=0 bootdelay=5 biosdevname=0 imgpart=UUID=989265ff-d248-4f89-8e52-b7555c637af2 bootpart=UUID=DDF4-322F datapart=UUID=5a1dd212-cb91-4291-a486-23f2c88c837a hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=native net.ifnames=0 use_kmsg=no plymouth=0 video=efifb fbcon=rotate:0 Oct 14 22:46:33 volumio startx[2795]: xorg-server 2:21.1.7-3+deb12u11 (https://www.debian.org/support) Oct 14 22:46:33 volumio startx[2795]: Current version of pixman: 0.42.2 Oct 14 22:46:33 volumio startx[2795]: Before reporting problems, check http://wiki.x.org Oct 14 22:46:33 volumio startx[2795]: to make sure that you have the latest version. Oct 14 22:46:33 volumio startx[2795]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 14 22:46:33 volumio startx[2795]: (++) from command line, (!!) notice, (II) informational, Oct 14 22:46:33 volumio startx[2795]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 14 22:46:33 volumio startx[2795]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Oct 14 22:46:33 2026 Oct 14 22:46:33 volumio startx[2795]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 14 22:46:48 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:48.155+04:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-902.446021ms timeout=10s Oct 14 22:46:48 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 22:46:48 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 22:46:48 volumio volumio[1326]: info: Discovery: Getting this device information Oct 14 22:46:48 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Oct 14 22:46:48 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:48.238+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-902.446021ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=82.77607ms Oct 14 22:46:48 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:48 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 22:46:48 volumio volumio[1326]: verbose: New Socket.io Connection to 192.168.3.56:3000 from 192.168.3.3 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 14 22:46:48 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 22:46:48 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 22:46:48 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:48.360+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-902.446021ms timeout=10s endpoint=http://pushupdates.volumio.org duration=204.618082ms Oct 14 22:46:48 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:48.416+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-902.446021ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=260.372946ms Oct 14 22:46:48 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:48.434+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-902.446021ms timeout=10s endpoint=https://www.googleapis.com duration=275.417456ms Oct 14 22:46:48 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:48.466+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-902.446021ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=307.146128ms Oct 14 22:46:48 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:48.473+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-902.446021ms timeout=10s endpoint=https://securetoken.googleapis.com duration=317.554852ms Oct 14 22:46:48 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:48.542+04:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-902.446021ms timeout=10s endpoint=http://cddb.volumio.org duration=382.211644ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 14 22:46:48 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:48.600+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-902.446021ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=439.858724ms Oct 14 22:46:48 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:48.601+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-902.446021ms timeout=10s endpoint=https://functions.volumio.cloud duration=441.351843ms Oct 14 22:46:48 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:48.605+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-902.446021ms timeout=10s endpoint=https://functions.volumio.cloud duration=445.383768ms Oct 14 22:46:48 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:48.608+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-902.446021ms timeout=10s endpoint=https://google.com duration=449.7737ms Oct 14 22:46:48 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:48.808+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-902.446021ms timeout=10s endpoint=https://database.volumio.cloud duration=649.189432ms Oct 14 22:46:48 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:48.821+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-902.446021ms timeout=10s endpoint=http://plugins.volumio.org duration=664.180143ms Oct 14 22:46:58 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:58.623+04:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-934.767214ms timeout=10s Oct 14 22:46:58 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:58.701+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-934.767214ms timeout=10s endpoint=http://pushupdates.volumio.org duration=77.77705ms Oct 14 22:46:58 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:58.703+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-934.767214ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=79.397697ms Oct 14 22:46:58 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:58.708+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-934.767214ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=84.498858ms Oct 14 22:46:58 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:58.710+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-934.767214ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=86.887722ms Oct 14 22:46:58 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:58.742+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-934.767214ms timeout=10s endpoint=https://google.com duration=117.827775ms Oct 14 22:46:58 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:58.771+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-934.767214ms timeout=10s endpoint=https://www.googleapis.com duration=147.227345ms Oct 14 22:46:58 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:58.797+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-934.767214ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=173.113554ms Oct 14 22:46:58 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:58.797+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-934.767214ms timeout=10s endpoint=https://securetoken.googleapis.com duration=173.15153ms Oct 14 22:46:58 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 22:46:58 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 22:46:58 volumio volumio[1326]: info: Discovery: Getting this device information Oct 14 22:46:58 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Oct 14 22:46:58 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:46:58 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 22:46:58 volumio volumio[1326]: verbose: New Socket.io Connection to 192.168.3.56:3000 from 192.168.3.3 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 14 22:46:58 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 22:46:58 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 22:46:59 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:59.008+04:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-934.767214ms timeout=10s endpoint=http://cddb.volumio.org duration=385.136883ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 14 22:46:59 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:59.060+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-934.767214ms timeout=10s endpoint=https://functions.volumio.cloud duration=435.560943ms Oct 14 22:46:59 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:59.060+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-934.767214ms timeout=10s endpoint=https://functions.volumio.cloud duration=436.501576ms Oct 14 22:46:59 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:59.074+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-934.767214ms timeout=10s endpoint=https://database.volumio.cloud duration=450.304336ms Oct 14 22:46:59 volumio volumio5-onboarding[2348]: time=2026-10-14T22:46:59.290+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:53012,00:00:00:00:00:00%01 @ 0xc000317c20" latency=-934.767214ms timeout=10s endpoint=http://plugins.volumio.org duration=666.365439ms Oct 14 22:47:11 volumio volumio[1326]: info: CALLMETHOD: user_interface now_playing configRestoreConfigFromBackup [object Object] Oct 14 22:47:11 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: now_playing , configRestoreConfigFromBackup Oct 14 22:47:11 volumio volumio[1326]: info: [now-playing] ConfigUpdater: config is up to date. Oct 14 22:47:11 volumio sudo[3164]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:4004/localhost:3000/g /opt/volumiokiosk.sh Oct 14 22:47:11 volumio sudo[3164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 22:47:11 volumio sudo[3164]: pam_unix(sudo:session): session closed for user root Oct 14 22:47:11 volumio volumio[1326]: [sudo] password for volumio: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Oct 14 22:47:11 volumio sudo[3167]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Oct 14 22:47:11 volumio sudo[3167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 22:47:11 volumio sudo[3167]: pam_unix(sudo:session): session closed for user root Oct 14 22:47:11 volumio volumio[1326]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl restart volumio-kiosk Oct 14 22:47:11 volumio sudo[3170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk Oct 14 22:47:11 volumio sudo[3170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 22:47:11 volumio startx[2794]: xinit: connection to X server lost Oct 14 22:47:11 volumio systemd[1]: Stopping volumio-kiosk.service - Start Volumio Kiosk... Oct 14 22:47:11 volumio startx[2794]: Oct 14 22:47:11 volumio startx[2794]: waiting for X server to shut down Oct 14 22:47:11 volumio startx[2795]: (II) Server terminated successfully (0). Closing log file. Oct 14 22:47:12 volumio startx[2794]: xinit: unexpected signal 15 Oct 14 22:47:12 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Oct 14 22:47:12 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Oct 14 22:47:12 volumio systemd[1]: Stopped volumio-kiosk.service - Start Volumio Kiosk. Oct 14 22:47:12 volumio systemd[1]: volumio-kiosk.service: Consumed 7.165s CPU time. Oct 14 22:47:12 volumio systemd[1]: Started volumio-kiosk.service - Start Volumio Kiosk. Oct 14 22:47:12 volumio sudo[3170]: pam_unix(sudo:session): session closed for user root Oct 14 22:47:12 volumio volumio[1326]: warn: [now-playing] MyBackgroundMonitor stopped Oct 14 22:47:12 volumio volumio[1326]: info: [now-playing] ConfigUpdater: config is up to date. Oct 14 22:47:12 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 22:47:12 volumio volumio[1326]: info: Discovery: Getting this device information Oct 14 22:47:12 volumio volumio[1326]: info: CoreCommandRouter::volumioGetState Oct 14 22:47:12 volumio volumio[1326]: info: CorePlayQueue::getTrack 0 Oct 14 22:47:12 volumio volumio[1326]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 22:47:12 volumio volumio[1326]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 22:47:12 volumio volumio[1326]: Error: Invalid coordinates Oct 14 22:47:12 volumio volumio[1326]: at OpenMeteoAPI.setCoordinates (/data/plugins/user_interface/now_playing/dist/lib/api/open-meteo/index.js:47:15) Oct 14 22:47:12 volumio volumio[1326]: at WeatherAPI.setConfig (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:79:64) Oct 14 22:47:12 volumio volumio[1326]: at ControllerNowPlaying._ControllerNowPlaying_configureWeatherApi (/data/plugins/user_interface/now_playing/dist/index.js:609:39) Oct 14 22:47:12 volumio volumio[1326]: at ControllerNowPlaying._ControllerNowPlaying_doOnStart (/data/plugins/user_interface/now_playing/dist/index.js:629:115) Oct 14 22:47:12 volumio volumio[1326]: at async ControllerNowPlaying.configRestoreConfigFromBackup (/data/plugins/user_interface/now_playing/dist/index.js:518:9) Oct 14 22:47:12 volumio volumio[1326]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 22:47:12 volumio startx[3212]: X.Org X Server 1.21.1.7 Oct 14 22:47:12 volumio startx[3212]: X Protocol Version 11, Revision 0 Oct 14 22:47:12 volumio startx[3212]: Current Operating System: Linux volumio 6.12.57-volumio #2 SMP PREEMPT_DYNAMIC Thu Nov 13 17:08:37 CET 2025 x86_64 Oct 14 22:47:12 volumio startx[3212]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet video=HDMI-A-1:panel_orientation=upside_down loglevel=0 bootdelay=5 biosdevname=0 imgpart=UUID=989265ff-d248-4f89-8e52-b7555c637af2 bootpart=UUID=DDF4-322F datapart=UUID=5a1dd212-cb91-4291-a486-23f2c88c837a hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=native net.ifnames=0 use_kmsg=no plymouth=0 video=efifb fbcon=rotate:0 Oct 14 22:47:12 volumio startx[3212]: xorg-server 2:21.1.7-3+deb12u11 (https://www.debian.org/support) Oct 14 22:47:12 volumio startx[3212]: Current version of pixman: 0.42.2 Oct 14 22:47:12 volumio startx[3212]: Before reporting problems, check http://wiki.x.org Oct 14 22:47:12 volumio startx[3212]: to make sure that you have the latest version. Oct 14 22:47:12 volumio startx[3212]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 14 22:47:12 volumio startx[3212]: (++) from command line, (!!) notice, (II) informational, Oct 14 22:47:12 volumio startx[3212]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 14 22:47:12 volumio startx[3212]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Oct 14 22:47:12 2026 Oct 14 22:47:12 volumio startx[3212]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 14 22:47:12 volumio sudo[3219]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 22:46' Oct 14 22:47:12 volumio sudo[3219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"