Oct 14 01:19:00 volumio upmpdcli[1382]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Oct 14 01:19:00 volumio volumio[1168]: error: updateQueue error: null Oct 14 01:19:00 volumio volumio[1168]: error: updateQueue error: null Oct 14 01:19:00 volumio volumio[1168]: info: ------------------------------ 3342ms Oct 14 01:19:00 volumio volumio[1168]: info: sendMpdCommand status took 3345 milliseconds Oct 14 01:19:00 volumio volumio[1168]: info: ------------------------------ 3335ms Oct 14 01:19:00 volumio volumio[1168]: info: sendMpdCommand status took 3333 milliseconds Oct 14 01:19:00 volumio volumio[1168]: info: sendMpdCommand stop took 3182 milliseconds Oct 14 01:19:00 volumio volumio[1168]: verbose: ControllerMpd::parseState Oct 14 01:19:00 volumio volumio[1168]: verbose: ControllerMpd::parseState Oct 14 01:19:00 volumio volumio[1168]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 01:19:00 volumio volumio[1168]: info: ControllerMpd::pushState Oct 14 01:19:00 volumio volumio[1168]: info: CoreCommandRouter::servicePushState Oct 14 01:19:00 volumio volumio[1168]: info: CoreStateMachine::pushState Oct 14 01:19:00 volumio volumio[1168]: info: CorePlayQueue::getTrack 5 Oct 14 01:19:00 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 01:19:00 volumio volumio[1168]: info: CoreCommandRouter::volumioPushState Oct 14 01:19:00 volumio volumio[1168]: info: CorePlayQueue::getTrack 5 Oct 14 01:19:00 volumio volumio[1168]: 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 01:19:00 volumio volumio[1168]: verbose: CURRENT POSITION 5 Oct 14 01:19:00 volumio volumio[1168]: info: CoreStateMachine::syncState stateService stop Oct 14 01:19:00 volumio volumio[1168]: info: CoreStateMachine::syncState currentStatus stop Oct 14 01:19:00 volumio volumio[1168]: info: CoreStateMachine::pushState Oct 14 01:19:00 volumio volumio[1168]: info: CorePlayQueue::getTrack 5 Oct 14 01:19:00 volumio volumio[1168]: info: CoreCommandRouter::volumioPushState Oct 14 01:19:00 volumio volumio[1168]: info: No code Oct 14 01:19:00 volumio volumio[1168]: info: CoreStateMachine::pushState Oct 14 01:19:00 volumio volumio[1168]: info: CorePlayQueue::getTrack 5 Oct 14 01:19:00 volumio volumio[1168]: info: CoreCommandRouter::volumioPushState Oct 14 01:19:00 volumio volumio[1168]: info: ControllerMpd::pushState Oct 14 01:19:00 volumio volumio[1168]: info: CoreCommandRouter::servicePushState Oct 14 01:19:00 volumio volumio[1168]: info: CoreStateMachine::pushState Oct 14 01:19:00 volumio volumio[1168]: info: CorePlayQueue::getTrack 5 Oct 14 01:19:00 volumio volumio[1168]: info: CoreCommandRouter::volumioPushState Oct 14 01:19:00 volumio volumio[1168]: info: CorePlayQueue::getTrack 5 Oct 14 01:19:00 volumio volumio[1168]: 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 01:19:00 volumio volumio[1168]: verbose: CURRENT POSITION 5 Oct 14 01:19:00 volumio volumio[1168]: info: CoreStateMachine::syncState stateService stop Oct 14 01:19:00 volumio volumio[1168]: info: CoreStateMachine::syncState currentStatus stop Oct 14 01:19:00 volumio volumio[1168]: info: CoreStateMachine::pushState Oct 14 01:19:00 volumio volumio[1168]: info: CorePlayQueue::getTrack 5 Oct 14 01:19:00 volumio volumio[1168]: info: CoreCommandRouter::volumioPushState Oct 14 01:19:00 volumio volumio[1168]: info: No code Oct 14 01:19:00 volumio volumio[1168]: info: CoreStateMachine::pushState Oct 14 01:19:00 volumio volumio[1168]: info: CorePlayQueue::getTrack 5 Oct 14 01:19:00 volumio volumio[1168]: info: CoreCommandRouter::volumioPushState Oct 14 01:19:00 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:00.656+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" state=STATUS_STOPPED positionMs=3106 volume=65 Oct 14 01:19:00 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:00.656+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" id=http://192.168.1.1:8200/MediaItems/402.mp3 title="06 I do belive in you" Oct 14 01:19:00 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:00.657+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" state=STATUS_STOPPED positionMs=3106 volume=65 Oct 14 01:19:00 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:00.658+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" id=http://192.168.1.1:8200/MediaItems/402.mp3 title="06 I do belive in you" Oct 14 01:19:00 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:00.660+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" state=STATUS_STOPPED positionMs=3106 volume=65 Oct 14 01:19:00 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:00.661+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" state=STATUS_STOPPED positionMs=3106 volume=65 Oct 14 01:19:00 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:00.661+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" state=STATUS_STOPPED positionMs=3106 volume=65 Oct 14 01:19:00 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:00.661+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" state=STATUS_STOPPED positionMs=3106 volume=65 Oct 14 01:19:00 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:00.661+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" id=http://192.168.1.1:8200/MediaItems/402.mp3 title="06 I do belive in you" Oct 14 01:19:00 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:00.669+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" id=http://192.168.1.1:8200/MediaItems/402.mp3 title="06 I do belive in you" Oct 14 01:19:00 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:00.669+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" id=http://192.168.1.1:8200/MediaItems/402.mp3 title="06 I do belive in you" Oct 14 01:19:00 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:00.670+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" id=http://192.168.1.1:8200/MediaItems/402.mp3 title="06 I do belive in you" Oct 14 01:19:00 volumio volumio[1168]: info: ------------------------------ 3443ms Oct 14 01:19:00 volumio volumio[1168]: info: ------------------------------ 3429ms Oct 14 01:19:00 volumio volumio[1168]: info: Oct 14 01:19:00 volumio volumio[1168]: ---------------------------- MPD announces system playlist update Oct 14 01:19:00 volumio volumio[1168]: info: Ignoring MPD Status Update Oct 14 01:19:00 volumio volumio[1168]: info: sendMpdCommand clear took 105 milliseconds Oct 14 01:19:00 volumio volumio[1168]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.1:8200/MediaItems/402.mp3" Oct 14 01:19:00 volumio volumio[1168]: info: Oct 14 01:19:00 volumio volumio[1168]: ---------------------------- MPD announces system playlist update Oct 14 01:19:00 volumio volumio[1168]: info: Ignoring MPD Status Update Oct 14 01:19:00 volumio volumio[1168]: error: updateQueue error: null Oct 14 01:19:00 volumio volumio[1168]: info: ------------------------------ 152ms Oct 14 01:19:00 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:00 volumio volumio[1168]: info: CorePlayQueue::getTrack 5 Oct 14 01:19:00 volumio volumio[1168]: info: Selecting previously unselected package rpi-chromium-mods. Oct 14 01:19:00 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:00.883+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:00 volumio volumio[1168]: info: Preparing to unpack .../11-rpi-chromium-mods_20250506_armhf.deb ... Oct 14 01:19:00 volumio volumio[1168]: info: Unpacking rpi-chromium-mods (20250506) ... Oct 14 01:19:00 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:00.929+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:00 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:00.934+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:00 volumio volumio[1168]: info: Selecting previously unselected package chromium-browser. Oct 14 01:19:01 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:01.025+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:01 volumio volumio[1168]: info: Preparing to unpack .../12-chromium-browser_1%3a155.0.8059.39-1~deb12u1+rpt1_all.deb ... Oct 14 01:19:01 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:01.072+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:01 volumio volumio[1168]: error: updateQueue error: null Oct 14 01:19:01 volumio volumio[1168]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.1:8200/MediaItems/402.mp3" Oct 14 01:19:01 volumio volumio[1168]: info: ------------------------------ 275ms Oct 14 01:19:01 volumio volumio[1168]: info: Unpacking chromium-browser (1:155.0.8059.39-1~deb12u1+rpt1) ... Oct 14 01:19:01 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:01.117+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:01 volumio volumio[1168]: info: Oct 14 01:19:01 volumio volumio[1168]: ---------------------------- MPD announces system playlist update Oct 14 01:19:01 volumio volumio[1168]: info: Ignoring MPD Status Update Oct 14 01:19:01 volumio volumio[1168]: info: sendMpdCommand add "http://192.168.1.1:8200/MediaItems/402.mp3" took 26 milliseconds Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 01:19:01 volumio volumio[1168]: verbose: ControllerMpd::sendMpdCommand play Oct 14 01:19:01 volumio volumio[1168]: info: Oct 14 01:19:01 volumio volumio[1168]: ---------------------------- MPD announces system playlist update Oct 14 01:19:01 volumio volumio[1168]: info: Ignoring MPD Status Update Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:01 volumio volumio[1168]: info: ------------------------------ 12ms Oct 14 01:19:01 volumio volumio[1168]: info: sendMpdCommand play took 10 milliseconds Oct 14 01:19:01 volumio volumio[1168]: info: ------------------------------ 9ms Oct 14 01:19:01 volumio volumio[1168]: info: Oct 14 01:19:01 volumio volumio[1168]: ---------------------------- MPD announces state update: player Oct 14 01:19:01 volumio volumio[1168]: info: ControllerMpd::getState Oct 14 01:19:01 volumio volumio[1168]: verbose: ControllerMpd::sendMpdCommand status Oct 14 01:19:01 volumio volumio[1168]: info: Oct 14 01:19:01 volumio volumio[1168]: ---------------------------- MPD announces state update: player Oct 14 01:19:01 volumio volumio[1168]: info: ControllerMpd::getState Oct 14 01:19:01 volumio volumio[1168]: verbose: ControllerMpd::sendMpdCommand status Oct 14 01:19:01 volumio volumio[1168]: info: Selecting previously unselected package chromium-l10n. Oct 14 01:19:01 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:01.281+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:01 volumio volumio[1168]: info: Oct 14 01:19:01 volumio volumio[1168]: ---------------------------- MPD announces state update: player Oct 14 01:19:01 volumio volumio[1168]: info: Oct 14 01:19:01 volumio volumio[1168]: ---------------------------- MPD announces system playlist update Oct 14 01:19:01 volumio volumio[1168]: info: Ignoring MPD Status Update Oct 14 01:19:01 volumio volumio[1168]: info: sendMpdCommand status took 70 milliseconds Oct 14 01:19:01 volumio volumio[1168]: info: ControllerMpd::getState Oct 14 01:19:01 volumio volumio[1168]: verbose: ControllerMpd::sendMpdCommand status Oct 14 01:19:01 volumio volumio[1168]: info: sendMpdCommand status took 68 milliseconds Oct 14 01:19:01 volumio volumio[1168]: verbose: ControllerMpd::parseState Oct 14 01:19:01 volumio volumio[1168]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 01:19:01 volumio volumio[1168]: verbose: ControllerMpd::parseState Oct 14 01:19:01 volumio volumio[1168]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 01:19:01 volumio volumio[1168]: info: Oct 14 01:19:01 volumio volumio[1168]: ---------------------------- MPD announces system playlist update Oct 14 01:19:01 volumio volumio[1168]: info: Ignoring MPD Status Update Oct 14 01:19:01 volumio volumio[1168]: info: Oct 14 01:19:01 volumio volumio[1168]: ---------------------------- MPD announces state update: player Oct 14 01:19:01 volumio volumio[1168]: info: ControllerMpd::getState Oct 14 01:19:01 volumio volumio[1168]: verbose: ControllerMpd::sendMpdCommand status Oct 14 01:19:01 volumio volumio[1168]: info: sendMpdCommand status took 44 milliseconds Oct 14 01:19:01 volumio volumio[1168]: info: ------------------------------ 45ms Oct 14 01:19:01 volumio volumio[1168]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 14 01:19:01 volumio volumio[1168]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 14 01:19:01 volumio volumio[1168]: info: ------------------------------ 42ms Oct 14 01:19:01 volumio volumio[1168]: info: sendMpdCommand status took 40 milliseconds Oct 14 01:19:01 volumio volumio[1168]: verbose: ControllerMpd::parseState Oct 14 01:19:01 volumio volumio[1168]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 01:19:01 volumio volumio[1168]: verbose: ControllerMpd::parseTrackInfo Oct 14 01:19:01 volumio volumio[1168]: verbose: ControllerMpd::parseTrackInfo Oct 14 01:19:01 volumio volumio[1168]: verbose: ControllerMpd::parseState Oct 14 01:19:01 volumio volumio[1168]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 01:19:01 volumio volumio[1168]: info: ControllerMpd::pushState Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::servicePushState Oct 14 01:19:01 volumio volumio[1168]: info: CorePlayQueue::getTrack 5 Oct 14 01:19:01 volumio volumio[1168]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":220,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"06 I do belive in you","artist":"America","album":"Alibi","uri":"http://192.168.1.1:8200/MediaItems/402.mp3","trackType":"mp3"} Oct 14 01:19:01 volumio volumio[1168]: verbose: CURRENT POSITION 5 Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::syncState stateService play Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::syncState currentStatus stop Oct 14 01:19:01 volumio volumio[1168]: info: ControllerMpd::pushState Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::servicePushState Oct 14 01:19:01 volumio volumio[1168]: info: CorePlayQueue::getTrack 5 Oct 14 01:19:01 volumio volumio[1168]: verbose: STATE SERVICE {"status":"play","position":0,"seek":652,"duration":220,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"06 I do belive in you","artist":"America","album":"Alibi","uri":"http://192.168.1.1:8200/MediaItems/402.mp3","trackType":"mp3"} Oct 14 01:19:01 volumio volumio[1168]: verbose: CURRENT POSITION 5 Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::syncState stateService play Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::syncState currentStatus play Oct 14 01:19:01 volumio volumio[1168]: info: Received an update from plugin. extracting info from payload Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::pushState Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::volumioPushState Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::pushState Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::volumioPushState Oct 14 01:19:01 volumio volumio[1168]: info: ------------------------------ 176ms Oct 14 01:19:01 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:01.393+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" state=STATUS_PLAYING positionMs=0 volume=65 Oct 14 01:19:01 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:01.394+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" id=http://192.168.1.1:8200/MediaItems/402.mp3 title="06 I do belive in you" Oct 14 01:19:01 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:01.394+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" state=STATUS_PLAYING positionMs=0 volume=65 Oct 14 01:19:01 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:01.395+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" id=http://192.168.1.1:8200/MediaItems/402.mp3 title="06 I do belive in you" Oct 14 01:19:01 volumio volumio[1168]: info: ------------------------------ 180ms Oct 14 01:19:01 volumio volumio[1168]: info: Preparing to unpack .../13-chromium-l10n_1%3a155.0.8059.39-1~deb12u1+rpt1_all.deb ... Oct 14 01:19:01 volumio volumio[1168]: info: Unpacking chromium-l10n (1:155.0.8059.39-1~deb12u1+rpt1) ... Oct 14 01:19:01 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:01.428+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:01 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:01.458+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:01 volumio volumio[1168]: info: sendMpdCommand playlistinfo took 126 milliseconds Oct 14 01:19:01 volumio volumio[1168]: info: sendMpdCommand playlistinfo took 121 milliseconds Oct 14 01:19:01 volumio volumio[1168]: verbose: ControllerMpd::parseTrackInfo Oct 14 01:19:01 volumio volumio[1168]: verbose: ControllerMpd::parseTrackInfo Oct 14 01:19:01 volumio volumio[1168]: info: ControllerMpd::pushState Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::servicePushState Oct 14 01:19:01 volumio volumio[1168]: info: CorePlayQueue::getTrack 5 Oct 14 01:19:01 volumio volumio[1168]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":220,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"06 I do belive in you","artist":"America","album":"Alibi","uri":"http://192.168.1.1:8200/MediaItems/402.mp3","trackType":"mp3"} Oct 14 01:19:01 volumio volumio[1168]: verbose: CURRENT POSITION 5 Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::syncState stateService play Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::syncState currentStatus play Oct 14 01:19:01 volumio volumio[1168]: info: Received an update from plugin. extracting info from payload Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::pushState Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::volumioPushState Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::pushState Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::volumioPushState Oct 14 01:19:01 volumio volumio[1168]: info: ControllerMpd::pushState Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::servicePushState Oct 14 01:19:01 volumio volumio[1168]: info: CorePlayQueue::getTrack 5 Oct 14 01:19:01 volumio volumio[1168]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":220,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"06 I do belive in you","artist":"America","album":"Alibi","uri":"http://192.168.1.1:8200/MediaItems/402.mp3","trackType":"mp3"} Oct 14 01:19:01 volumio volumio[1168]: verbose: CURRENT POSITION 5 Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::syncState stateService play Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::syncState currentStatus play Oct 14 01:19:01 volumio volumio[1168]: info: Received an update from plugin. extracting info from payload Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::pushState Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::volumioPushState Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::pushState Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::volumioPushState Oct 14 01:19:01 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:01.485+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" state=STATUS_PLAYING positionMs=308 volume=65 Oct 14 01:19:01 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:01.486+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" state=STATUS_PLAYING positionMs=308 volume=65 Oct 14 01:19:01 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:01.486+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" state=STATUS_PLAYING positionMs=308 volume=65 Oct 14 01:19:01 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:01.489+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" id=http://192.168.1.1:8200/MediaItems/402.mp3 title="06 I do belive in you" Oct 14 01:19:01 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:01.491+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" state=STATUS_PLAYING positionMs=308 volume=65 Oct 14 01:19:01 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:01.491+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" id=http://192.168.1.1:8200/MediaItems/402.mp3 title="06 I do belive in you" Oct 14 01:19:01 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:01.492+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" id=http://192.168.1.1:8200/MediaItems/402.mp3 title="06 I do belive in you" Oct 14 01:19:01 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:01.499+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.15:39792 @ 0x1ca3da0" id=http://192.168.1.1:8200/MediaItems/402.mp3 title="06 I do belive in you" Oct 14 01:19:01 volumio volumio[1168]: info: ------------------------------ 213ms Oct 14 01:19:01 volumio volumio[1168]: info: ------------------------------ 208ms Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::volumioGetQueue Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::getQueue Oct 14 01:19:01 volumio volumio[1168]: info: CorePlayQueue::getQueue Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::volumioGetQueue Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::getQueue Oct 14 01:19:01 volumio volumio[1168]: info: CorePlayQueue::getQueue Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::volumioGetQueue Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::getQueue Oct 14 01:19:01 volumio volumio[1168]: info: CorePlayQueue::getQueue Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::volumioGetQueue Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::getQueue Oct 14 01:19:01 volumio volumio[1168]: info: CorePlayQueue::getQueue Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::volumioGetQueue Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::getQueue Oct 14 01:19:01 volumio volumio[1168]: info: CorePlayQueue::getQueue Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::volumioGetQueue Oct 14 01:19:01 volumio volumio[1168]: info: CoreStateMachine::getQueue Oct 14 01:19:01 volumio volumio[1168]: info: CorePlayQueue::getQueue Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:01 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:02 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:02 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:02 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:02 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:02 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:02 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:03 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:03 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:03 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:03 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:03 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:03 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:04 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:04 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:04 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:04 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:04 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:04 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:05 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:05 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:05 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:05 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:05 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:05 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:06 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:06 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:06 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:06 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:06 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:06 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:06 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:07 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:07 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:07 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:07 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:07 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:07 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:07 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:08 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:08 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:08 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:08 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:08 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:08 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:09 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:09 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:09 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:09 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:09 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:09 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:10 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:10 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:10 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:10 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:10 volumio volumio[1168]: info: Selecting previously unselected package fonts-liberation. Oct 14 01:19:10 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:10.708+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:10 volumio volumio[1168]: info: Preparing to unpack .../14-fonts-liberation_1%3a1.07.4-11_all.deb ... Oct 14 01:19:10 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:10.782+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:10 volumio volumio[1168]: info: Unpacking fonts-liberation (1:1.07.4-11) ... Oct 14 01:19:10 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:10.825+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:10 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:10 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:11 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:11 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:11 volumio volumio[1168]: info: Selecting previously unselected package libipc-system-simple-perl. Oct 14 01:19:11 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:11.501+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:11 volumio volumio[1168]: info: Preparing to unpack .../15-libipc-system-simple-perl_1.30-2_all.deb ... Oct 14 01:19:11 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:11.581+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:11 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:11 volumio volumio[1168]: info: Unpacking libipc-system-simple-perl (1.30-2) ... Oct 14 01:19:11 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:11.715+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:11 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:12 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:12 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:12 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:12 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:12 volumio volumio[1168]: info: Selecting previously unselected package libfile-basedir-perl. Oct 14 01:19:12 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:12.580+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:12 volumio volumio[1168]: info: Preparing to unpack .../16-libfile-basedir-perl_0.09-2_all.deb ... Oct 14 01:19:12 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:12.626+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:12 volumio volumio[1168]: info: Unpacking libfile-basedir-perl (0.09-2) ... Oct 14 01:19:12 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:12.673+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:12 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:12 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:12 volumio volumio[1168]: info: Selecting previously unselected package libfile-desktopentry-perl. Oct 14 01:19:12 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:12.856+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:12 volumio volumio[1168]: info: Preparing to unpack .../17-libfile-desktopentry-perl_0.22-3_all.deb ... Oct 14 01:19:12 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:12.931+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:12 volumio volumio[1168]: info: Unpacking libfile-desktopentry-perl (0.22-3) ... Oct 14 01:19:12 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:12.974+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:13 volumio volumio[1168]: info: Selecting previously unselected package libfile-mimeinfo-perl. Oct 14 01:19:13 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:13.074+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:13 volumio volumio[1168]: info: Preparing to unpack .../18-libfile-mimeinfo-perl_0.33-1_all.deb ... Oct 14 01:19:13 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:13.120+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:13 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:13 volumio volumio[1168]: info: Unpacking libfile-mimeinfo-perl (0.33-1) ... Oct 14 01:19:13 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:13.175+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:13 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:13 volumio volumio[1168]: info: Selecting previously unselected package libio-stringy-perl. Oct 14 01:19:13 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:13.392+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:13 volumio volumio[1168]: info: Preparing to unpack .../19-libio-stringy-perl_2.111-3_all.deb ... Oct 14 01:19:13 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:13.454+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:13 volumio volumio[1168]: info: Unpacking libio-stringy-perl (2.111-3) ... Oct 14 01:19:13 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:13.492+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:13 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:13 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:13 volumio volumio[1168]: info: Selecting previously unselected package libxml-parser-perl. Oct 14 01:19:13 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:13.737+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:13 volumio volumio[1168]: info: Preparing to unpack .../20-libxml-parser-perl_2.46-4+deb12u1_armhf.deb ... Oct 14 01:19:13 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:13.800+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:13 volumio volumio[1168]: info: Unpacking libxml-parser-perl (2.46-4+deb12u1) ... Oct 14 01:19:13 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:13.841+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:13 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:13 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:14 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:14 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:14 volumio volumio[1168]: info: Selecting previously unselected package libxml-twig-perl. Oct 14 01:19:14 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:14.340+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:14 volumio volumio[1168]: info: Preparing to unpack .../21-libxml-twig-perl_1%3a3.52-2_all.deb ... Oct 14 01:19:14 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:14.393+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:14 volumio volumio[1168]: info: Unpacking libxml-twig-perl (1:3.52-2) ... Oct 14 01:19:14 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:14.467+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:14 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:14 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:14 volumio volumio[1168]: info: Selecting previously unselected package libnet-dbus-perl. Oct 14 01:19:14 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:14.644+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:14 volumio volumio[1168]: info: Preparing to unpack .../22-libnet-dbus-perl_1.2.0-2_armhf.deb ... Oct 14 01:19:14 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:14.696+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:14 volumio volumio[1168]: info: Unpacking libnet-dbus-perl (1.2.0-2) ... Oct 14 01:19:14 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:14.750+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:14 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:14 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:15 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:15 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:15 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:15 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:15 volumio volumio[1168]: info: Selecting previously unselected package libtie-ixhash-perl. Oct 14 01:19:15 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:15.757+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:15 volumio volumio[1168]: info: Preparing to unpack .../23-libtie-ixhash-perl_1.23-4_all.deb ... Oct 14 01:19:15 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:15.807+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:15 volumio volumio[1168]: info: Unpacking libtie-ixhash-perl (1.23-4) ... Oct 14 01:19:15 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:15.833+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:15 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:15 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:16 volumio volumio[1168]: info: Selecting previously unselected package libupower-glib3:armhf. Oct 14 01:19:16 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:16.099+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:16 volumio volumio[1168]: info: Preparing to unpack .../24-libupower-glib3_0.99.20-2_armhf.deb ... Oct 14 01:19:16 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:16.134+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:16 volumio volumio[1168]: info: Unpacking libupower-glib3:armhf (0.99.20-2) ... Oct 14 01:19:16 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:16.181+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:16 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:16 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:16 volumio volumio[1168]: info: Selecting previously unselected package libwidevinecdm0. Oct 14 01:19:16 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:16.412+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:16 volumio volumio[1168]: info: Preparing to unpack .../25-libwidevinecdm0_4.10.2662.3+1_armhf.deb ... Oct 14 01:19:16 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:16.460+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:16 volumio volumio[1168]: info: Unpacking libwidevinecdm0 (4.10.2662.3+1) ... Oct 14 01:19:16 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:16.505+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:16 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:16 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:16 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:16 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:17 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:17 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:17 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:17 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:17 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:17 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:18 volumio volumio[1168]: info: Selecting previously unselected package libx11-protocol-perl. Oct 14 01:19:18 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:18.128+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:18 volumio volumio[1168]: info: Preparing to unpack .../26-libx11-protocol-perl_0.56-9_all.deb ... Oct 14 01:19:18 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:18.179+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:18 volumio volumio[1168]: info: Unpacking libx11-protocol-perl (0.56-9) ... Oct 14 01:19:18 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:18 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:18.220+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:18 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:18 volumio volumio[1168]: info: Selecting previously unselected package libxml-xpathengine-perl. Oct 14 01:19:18 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:18.587+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:18 volumio volumio[1168]: info: Preparing to unpack .../27-libxml-xpathengine-perl_0.14-2_all.deb ... Oct 14 01:19:18 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:18.635+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:18 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:18 volumio volumio[1168]: info: Unpacking libxml-xpathengine-perl (0.14-2) ... Oct 14 01:19:18 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:18.700+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:18 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:18 volumio volumio[1168]: info: Selecting previously unselected package notification-daemon. Oct 14 01:19:18 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:18.920+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:18 volumio volumio[1168]: info: Preparing to unpack .../28-notification-daemon_3.20.0-4_armhf.deb ... Oct 14 01:19:18 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:18.975+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:18 volumio volumio[1168]: info: Unpacking notification-daemon (3.20.0-4) ... Oct 14 01:19:19 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:19.023+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:19 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:19 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:19 volumio volumio[1168]: info: Selecting previously unselected package upower. Oct 14 01:19:19 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:19.281+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:19 volumio volumio[1168]: info: Preparing to unpack .../29-upower_0.99.20-2_armhf.deb ... Oct 14 01:19:19 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:19.330+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:19 volumio volumio[1168]: info: Unpacking upower (0.99.20-2) ... Oct 14 01:19:19 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:19.384+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:19 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:19 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:19 volumio dbus-daemon[632]: [system] Reloaded configuration Oct 14 01:19:19 volumio dbus-daemon[632]: [system] Reloaded configuration Oct 14 01:19:19 volumio volumio5-onboarding[1558]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio5-onboarding[1558]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio dbus-daemon[632]: [system] Reloaded configuration Oct 14 01:19:19 volumio volumio5-onboarding[1558]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio dbus-daemon[632]: [system] Reloaded configuration Oct 14 01:19:19 volumio volumio5-onboarding[1558]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio shairport-sync[2696]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio shairport-sync[2696]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio shairport-sync[2696]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio shairport-sync[2696]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:19 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:20 volumio volumio[1168]: info: Setting up libxml-parser-perl (2.46-4+deb12u1) ... Oct 14 01:19:20 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:20.235+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:20 volumio volumio[1168]: info: Setting up libdouble-conversion3:armhf (3.2.1-1) ... Oct 14 01:19:20 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:20.287+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:20 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:20 volumio volumio[1168]: info: Setting up libtie-ixhash-perl (1.23-4) ... Oct 14 01:19:20 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:20.338+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:20 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:20 volumio volumio[1168]: info: Setting up chromium-codecs-ffmpeg-extra (126.0.6478.164-rpt1) ... Oct 14 01:19:20 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:20.394+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:20 volumio volumio[1168]: info: Setting up libio-stringy-perl (2.111-3) ... Oct 14 01:19:20 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:20.430+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:20 volumio volumio[1168]: info: Setting up libxml-twig-perl (1:3.52-2) ... Oct 14 01:19:20 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:20.473+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:20 volumio volumio[1168]: info: Setting up libnet-dbus-perl (1.2.0-2) ... Oct 14 01:19:20 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:20.516+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:20 volumio volumio[1168]: info: Setting up notification-daemon (3.20.0-4) ... Oct 14 01:19:20 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:20.560+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:20 volumio volumio[1168]: info: Setting up libxnvctrl0:armhf (525.85.05-3~deb12u1) ... Oct 14 01:19:20 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:20.625+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:20 volumio volumio[1168]: info: Setting up libnspr4:armhf (2:4.35-1) ... Oct 14 01:19:20 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:20.665+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:20 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:20 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:20 volumio volumio[1168]: info: Setting up libipc-system-simple-perl (1.30-2) ... Oct 14 01:19:20 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:20.732+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:20 volumio volumio[1168]: info: Setting up libxml-xpathengine-perl (0.14-2) ... Oct 14 01:19:20 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:20.795+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:20 volumio volumio[1168]: info: Setting up libopenh264-7:armhf (2.3.1+dfsg-3+deb12u2) ... Oct 14 01:19:20 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:20.858+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:20 volumio volumio[1168]: info: Setting up fonts-liberation (1:1.07.4-11) ... Oct 14 01:19:20 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:20.941+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:20 volumio volumio[1168]: info: Setting up libupower-glib3:armhf (0.99.20-2) ... Oct 14 01:19:20 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:20.996+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:21 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:21 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:21 volumio volumio[1168]: info: Setting up libharfbuzz-subset0:armhf (6.0.0+dfsg-3) ... Oct 14 01:19:21 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:21.069+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:21 volumio volumio[1168]: info: Setting up zenoty (0.2) ... Oct 14 01:19:21 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:21.134+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:21 volumio volumio[1168]: info: Setting up xdg-utils (1.1.3-4.1) ... Oct 14 01:19:21 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:21.212+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:21 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:21 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:21 volumio volumio[1168]: info: update-alternatives: using /usr/bin/xdg-open to provide /usr/bin/open (open) in auto mode Oct 14 01:19:21 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:21.429+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:21 volumio volumio[1168]: info: Setting up libx11-protocol-perl (0.56-9) ... Oct 14 01:19:21 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:21.477+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:21 volumio volumio[1168]: info: Setting up libfile-basedir-perl (0.09-2) ... Oct 14 01:19:21 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:21.527+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:21 volumio volumio[1168]: info: Setting up upower (0.99.20-2) ... Oct 14 01:19:21 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:21.566+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:21 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:21 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:22 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:22 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:22 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:22 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:22 volumio systemd[1]: Reloading. Oct 14 01:19:22 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:22 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:23 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:23 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:23 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:23 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:23 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:23 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:23 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:23 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:24 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:24 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:24 volumio volumio[1168]: info: upower.service is a disabled or a static unit, not starting it. Oct 14 01:19:24 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:24.521+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:24 volumio volumio[1168]: info: Setting up libnss3:armhf (2:3.87.1-1+deb12u4) ... Oct 14 01:19:24 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:24.589+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:24 volumio volumio[1168]: info: Setting up libwidevinecdm0 (4.10.2662.3+1) ... Oct 14 01:19:24 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:24 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:24 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:24.724+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:24 volumio volumio[1168]: info: Setting up libfile-desktopentry-perl (0.22-3) ... Oct 14 01:19:24 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:24.751+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:24 volumio volumio[1168]: info: Setting up chromium-common (1:155.0.8059.39-1~deb12u1+rpt1) ... Oct 14 01:19:24 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:24.865+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:24 volumio volumio[1168]: info: Setting up chromium (1:155.0.8059.39-1~deb12u1+rpt1) ... Oct 14 01:19:24 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:24.960+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:24 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:24 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:25 volumio volumio[1168]: info: update-alternatives: using /usr/bin/chromium to provide /usr/bin/x-www-browser (x-www-browser) in auto mode Oct 14 01:19:25 volumio volumio[1168]: info: update-alternatives: using /usr/bin/chromium to provide /usr/bin/gnome-www-browser (gnome-www-browser) in auto mode Oct 14 01:19:25 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:25.226+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.15:39792 @ 0x1ca3da0" latency=106.180638ms timeout=10s Oct 14 01:19:25 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:25.239+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:25 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:25.261+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:25 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:25 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:25 volumio volumio[1168]: info: Setting up libfile-mimeinfo-perl (0.33-1) ... Oct 14 01:19:25 volumio volumio[1168]: info: Setting up rpi-chromium-mods (20250506) ... Oct 14 01:19:25 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:25.484+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.15:39792 @ 0x1ca3da0" latency=106.180638ms timeout=10s endpoint=http://pushupdates.volumio.org duration=250.405755ms Oct 14 01:19:25 volumio volumio[1168]: info: Setting up chromium-l10n (1:155.0.8059.39-1~deb12u1+rpt1) ... Oct 14 01:19:25 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:25.556+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.15:39792 @ 0x1ca3da0" latency=106.180638ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=327.448519ms Oct 14 01:19:25 volumio volumio[1168]: info: Setting up chromium-browser (1:155.0.8059.39-1~deb12u1+rpt1) ... Oct 14 01:19:25 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:25 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:25.687+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:25 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:25 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:25.701+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:25 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 01:19:25 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 01:19:25 volumio volumio[1168]: info: Discovery: Getting this device information Oct 14 01:19:25 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:25 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 01:19:25 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:25.728+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:25 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:25.743+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:25 volumio volumio[1168]: info: Processing triggers for hicolor-icon-theme (0.17-2) ... Oct 14 01:19:25 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:25.817+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.15:39792 @ 0x1ca3da0" latency=106.180638ms timeout=10s endpoint=https://www.googleapis.com duration=586.880874ms Oct 14 01:19:25 volumio volumio[1168]: verbose: New Socket.io Connection to 192.168.1.25:3000 from 192.168.1.15 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 14 01:19:25 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 01:19:25 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 01:19:25 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:25.856+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.15:39792 @ 0x1ca3da0" latency=106.180638ms timeout=10s endpoint=https://securetoken.googleapis.com duration=597.11585ms Oct 14 01:19:25 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:25.872+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:25 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:25.962+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.15:39792 @ 0x1ca3da0" latency=106.180638ms timeout=10s endpoint=https://database.volumio.cloud duration=709.447574ms Oct 14 01:19:25 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:25.996+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.15:39792 @ 0x1ca3da0" latency=106.180638ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=765.74346ms Oct 14 01:19:26 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:26 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:26 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:26.067+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.15:39792 @ 0x1ca3da0" latency=106.180638ms timeout=10s endpoint=http://plugins.volumio.org duration=815.29377ms Oct 14 01:19:26 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:26.095+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.15:39792 @ 0x1ca3da0" latency=106.180638ms timeout=10s endpoint=https://google.com duration=866.982763ms Oct 14 01:19:26 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:26.097+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.15:39792 @ 0x1ca3da0" latency=106.180638ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=858.59569ms Oct 14 01:19:26 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:26.121+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.15:39792 @ 0x1ca3da0" latency=106.180638ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=870.193365ms Oct 14 01:19:26 volumio volumio[1168]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... Oct 14 01:19:26 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:26.214+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.15:39792 @ 0x1ca3da0" latency=106.180638ms timeout=10s endpoint=https://functions.volumio.cloud duration=965.310577ms Oct 14 01:19:26 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:26.226+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.15:39792 @ 0x1ca3da0" latency=106.180638ms timeout=10s endpoint=https://functions.volumio.cloud duration=973.092552ms Oct 14 01:19:26 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:26.228+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:26 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:26 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:26 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 01:19:26 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 01:19:26 volumio volumio[1168]: info: Discovery: Getting this device information Oct 14 01:19:26 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:26 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 01:19:26 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:26 volumio volumio[1168]: info: Processing triggers for man-db (2.11.2-2) ... Oct 14 01:19:26 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:26.684+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:26 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:26 volumio volumio[1168]: verbose: New Socket.io Connection to 192.168.1.25:3000 from 192.168.1.15 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 14 01:19:26 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 01:19:26 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 01:19:27 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:27 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:27 volumio volumio[1168]: info: Processing triggers for dbus (1.14.10-1~deb12u1) ... Oct 14 01:19:27 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:27.245+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:27 volumio dbus-daemon[632]: [system] Reloaded configuration Oct 14 01:19:27 volumio shairport-sync[2696]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio5-onboarding[1558]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: WARNING: Unhandled message: interface=org.freedesktop.DBus, path=/org/freedesktop/DBus, member=ActivatableServicesChanged Oct 14 01:19:27 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:27 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:27 volumio volumio[1168]: info: Processing triggers for udev (252.39-1~deb12u1+rpi1) ... Oct 14 01:19:27 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:27.403+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:27 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:27 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:28 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:28 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:28 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:28 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:28 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:28 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:28 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:28 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:29 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:29 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:29 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:29 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:29 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:29 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:30 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:30 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:30 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:30 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:30 volumio volumio[1168]: info: Processing triggers for fontconfig (2.14.1-4) ... Oct 14 01:19:30 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:30.808+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:30 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:30 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:30 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:30.888+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.15:39792 @ 0x1ca3da0" latency=106.180638ms timeout=10s endpoint=http://cddb.volumio.org duration=5.63856543s error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 14 01:19:31 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:31 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:31 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:31 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:31 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:31 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:32 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:32 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:32 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:32 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:32 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:32 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:33 volumio volumio[1168]: verbose: New Socket.io Connection to 192.168.1.25 from 192.168.1.15 UA: Mozilla/5.0 (Linux; Android 13; SM-A715F Build/TP1A.220624.014; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.36 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 14 01:19:33 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:33 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:33 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 14 01:19:33 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:33 volumio volumio[1168]: info: CoreCommandRouter::volumioGetVisibleSources Oct 14 01:19:33 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 01:19:33 volumio volumio[1168]: info: CoreCommandRouter::volumioGetQueue Oct 14 01:19:33 volumio volumio[1168]: info: CoreStateMachine::getQueue Oct 14 01:19:33 volumio volumio[1168]: info: CorePlayQueue::getQueue Oct 14 01:19:33 volumio volumio[1168]: info: Listing playlists Oct 14 01:19:33 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:34 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:34 volumio volumio[1168]: info: Installing fonts Oct 14 01:19:34 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:34.168+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:34 volumio volumio[1168]: info: Reading package lists... Oct 14 01:19:34 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:34.421+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:34 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:34 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:34 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:34 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:35 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:35 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:35 volumio volumio[1168]: info: Building dependency tree... Oct 14 01:19:35 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:35.407+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:35 volumio volumio[1168]: info: Reading state information... Oct 14 01:19:35 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:35 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:35.489+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:35 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:35 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:35 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:36 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:36 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:36 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:36 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:36 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:36 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:37 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:37 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:37 volumio volumio[1168]: info: The following additional packages will be installed: Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.268+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio[1168]: info: fonts-ipafont-gothic fonts-ipafont-mincho fonts-tlwg-garuda Oct 14 01:19:37 volumio volumio[1168]: info: fonts-tlwg-garuda-ttf fonts-tlwg-kinnari fonts-tlwg-kinnari-ttf Oct 14 01:19:37 volumio volumio[1168]: info: fonts-tlwg-laksaman fonts-tlwg-laksaman-ttf fonts-tlwg-loma Oct 14 01:19:37 volumio volumio[1168]: info: fonts-tlwg-loma-ttf fonts-tlwg-mono fonts-tlwg-mono-ttf fonts-tlwg-norasi Oct 14 01:19:37 volumio volumio[1168]: info: fonts-tlwg-norasi-ttf fonts-tlwg-purisa fonts-tlwg-purisa-ttf Oct 14 01:19:37 volumio volumio[1168]: info: fonts-tlwg-sawasdee fonts-tlwg-sawasdee-ttf fonts-tlwg-typewriter Oct 14 01:19:37 volumio volumio[1168]: info: fonts-tlwg-typewriter-ttf fonts-tlwg-typist fonts-tlwg-typist-ttf Oct 14 01:19:37 volumio volumio[1168]: info: fonts-tlwg-typo fonts-tlwg-typo-ttf fonts-tlwg-umpush fonts-tlwg-umpush-ttf Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.402+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.410+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.435+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.439+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.445+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.454+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.473+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.477+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio[1168]: info: fonts-tlwg-waree fonts-tlwg-waree-ttf Oct 14 01:19:37 volumio volumio[1168]: info: Suggested packages: Oct 14 01:19:37 volumio volumio[1168]: info: fonts-unfonts-extra Oct 14 01:19:37 volumio volumio[1168]: info: The following NEW packages will be installed: Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.763+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio[1168]: info: fonts-arphic-gbsn00lp fonts-arphic-ukai fonts-ipafont fonts-ipafont-gothic Oct 14 01:19:37 volumio volumio[1168]: info: fonts-ipafont-mincho fonts-thai-tlwg-ttf fonts-tlwg-garuda Oct 14 01:19:37 volumio volumio[1168]: info: fonts-tlwg-garuda-ttf fonts-tlwg-kinnari fonts-tlwg-kinnari-ttf Oct 14 01:19:37 volumio volumio[1168]: info: fonts-tlwg-laksaman fonts-tlwg-laksaman-ttf fonts-tlwg-loma Oct 14 01:19:37 volumio volumio[1168]: info: fonts-tlwg-loma-ttf fonts-tlwg-mono fonts-tlwg-mono-ttf fonts-tlwg-norasi Oct 14 01:19:37 volumio volumio[1168]: info: fonts-tlwg-norasi-ttf fonts-tlwg-purisa fonts-tlwg-purisa-ttf Oct 14 01:19:37 volumio volumio[1168]: info: fonts-tlwg-sawasdee fonts-tlwg-sawasdee-ttf fonts-tlwg-typewriter Oct 14 01:19:37 volumio volumio[1168]: info: fonts-tlwg-typewriter-ttf fonts-tlwg-typist fonts-tlwg-typist-ttf Oct 14 01:19:37 volumio volumio[1168]: info: fonts-tlwg-typo fonts-tlwg-typo-ttf fonts-tlwg-umpush fonts-tlwg-umpush-ttf Oct 14 01:19:37 volumio volumio[1168]: info: fonts-tlwg-waree fonts-tlwg-waree-ttf fonts-unfonts-core fonts-vlgothic Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.903+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.914+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.925+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.931+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.937+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.975+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.983+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.987+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.993+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:37 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:37.999+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:38 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:38.003+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:38 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:38.008+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:38 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:38.014+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:38 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:38 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:38 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:38 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:38 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:38 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:39 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:39 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:39 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:39 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:39 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:39 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:40 volumio volumio[1168]: info: 0 upgraded, 34 newly installed, 0 to remove and 86 not upgraded. Oct 14 01:19:40 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:40.167+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:40 volumio volumio[1168]: info: Need to get 39.3 MB of archives. Oct 14 01:19:40 volumio volumio[1168]: info: After this operation, 102 MB of additional disk space will be used. Oct 14 01:19:40 volumio volumio[1168]: info: Get:1 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-ipafont-gothic all 00303-23 [3515 kB] Oct 14 01:19:40 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:40.215+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:40 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:40.222+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:40 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:40.229+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:40 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:40 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:40 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:40 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:40 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:40 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:41 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:41 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:41 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:41 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:41 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:41 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:42 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:42 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:42 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:42 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:42 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:42 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:43 volumio volumio[1168]: info: Get:2 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-ipafont-mincho all 00303-23 [4726 kB] Oct 14 01:19:43 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:43.168+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:43 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:43 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:43 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:43 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:43 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:43 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:44 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:44 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:44 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:44 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:44 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:44 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:45 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:45 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:45 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:45 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:45 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:45 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:46 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:46 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:46 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:46 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:46 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:46 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:46 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:46 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:47 volumio volumio[1168]: info: Get:3 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-ipafont all 00303-23 [12.6 kB] Oct 14 01:19:47 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:47.308+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:47 volumio volumio[1168]: info: Get:4 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-unfonts-core all 1:1.0.2-080608-18 [14.8 MB] Oct 14 01:19:47 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:47.364+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:47 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:47 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:47 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:47 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:48 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:48 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:48 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:48 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:48 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:48 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:48 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 01:19:48 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 14 01:19:49 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:49 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:49 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:49 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:49 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:49 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:50 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:50 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:50 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:50 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:50 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:50 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:51 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:51 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:51 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:51 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:51 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:51 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:52 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:52 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:52 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:52 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:52 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:52 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:52 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:52 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:53 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:53 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:53 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:53 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:53 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:53 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:54 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:54 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:54 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:54 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:54 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:54 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:55 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:55 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:55 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:55 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:55 volumio volumio[1168]: info: Enabling plugin touch_display Oct 14 01:19:55 volumio volumio[1168]: info: Loading plugin "touch_display"... Oct 14 01:19:56 volumio volumio[1168]: info: PLUGIN START: touch_display Oct 14 01:19:56 volumio volumio[1168]: info: Loading i18n strings for locale it Oct 14 01:19:56 volumio volumio[1168]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Oct 14 01:19:56 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 01:19:56 volumio volumio[1168]: info: touch_display: No backlight interface detected. Oct 14 01:19:56 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:56 volumio volumio[1168]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 14 01:19:56 volumio sudo[7847]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Oct 14 01:19:56 volumio sudo[7849]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Oct 14 01:19:56 volumio sudo[7847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 01:19:56 volumio sudo[7851]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 14 01:19:56 volumio sudo[7849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 01:19:56 volumio sudo[7845]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Oct 14 01:19:56 volumio sudo[7851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 01:19:56 volumio sudo[7845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 01:19:56 volumio volumio[1168]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 01:19:56 volumio sudo[7845]: pam_unix(sudo:session): session closed for user root Oct 14 01:19:56 volumio volumio[1168]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Oct 14 01:19:56 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:56 volumio systemd[1]: Reloading. Oct 14 01:19:56 volumio sudo[7857]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Oct 14 01:19:56 volumio sudo[7857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 01:19:56 volumio sudo[7857]: pam_unix(sudo:session): session closed for user root Oct 14 01:19:56 volumio volumio[1168]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Oct 14 01:19:56 volumio sudo[7860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Oct 14 01:19:56 volumio sudo[7860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 01:19:56 volumio volumio[1168]: info: touch_display: About to detect HDMI ports... Oct 14 01:19:56 volumio volumio[1168]: info: touch_display: X server not ready, cannot detect HDMI ports yet. Oct 14 01:19:56 volumio sudo[7860]: pam_unix(sudo:session): session closed for user root Oct 14 01:19:57 volumio volumio[1168]: info: touch_display: IframeKeyboardBridge extension installed successfully Oct 14 01:19:57 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:57 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:57 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:57 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:57 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:57 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:58 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:58 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:58 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:58 volumio volumio[1168]: info: Get:5 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-arphic-gbsn00lp all 2.11-16 [1725 kB] Oct 14 01:19:58 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:58.452+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:58 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:58 volumio sudo[7851]: pam_unix(sudo:session): session closed for user root Oct 14 01:19:58 volumio systemd[1]: Stopping getty@tty1.service - Getty on tty1... Oct 14 01:19:58 volumio volumio[1168]: info: touch_display: systemctl daemon-reload succeeded. Oct 14 01:19:58 volumio systemd[1]: getty@tty1.service: Deactivated successfully. Oct 14 01:19:58 volumio systemd[1]: Stopped getty@tty1.service - Getty on tty1. Oct 14 01:19:58 volumio sudo[7896]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Oct 14 01:19:58 volumio sudo[7896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 01:19:58 volumio systemd[1]: Reloading. Oct 14 01:19:58 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:58 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:59 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:59 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:59 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:59 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:59 volumio volumio[1168]: info: Get:6 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-arphic-ukai all 0.2.20080216.2-5 [8376 kB] Oct 14 01:19:59 volumio volumio5-onboarding[1558]: time=2026-10-14T01:19:59.800+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:19:59 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:19:59 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:00 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:00 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:00 volumio sudo[7849]: pam_unix(sudo:session): session closed for user root Oct 14 01:20:00 volumio volumio[1168]: info: touch_display: systemctl disable getty@tty1.service succeeded. Oct 14 01:20:00 volumio sudo[7847]: pam_unix(sudo:session): session closed for user root Oct 14 01:20:00 volumio volumio[1168]: info: touch_display: systemctl stop getty@tty1.service succeeded. Oct 14 01:20:00 volumio sudo[7896]: pam_unix(sudo:session): session closed for user root Oct 14 01:20:00 volumio volumio[1168]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Oct 14 01:20:00 volumio volumio[1168]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Oct 14 01:20:00 volumio volumio[1168]: info: Error: Error Oct 14 01:20:00 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:00 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:00 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:00 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:01 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:01 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:01 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:01 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:01 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:01 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:02 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:02 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:02 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:02 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:02 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:02 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:02 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:02 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:03 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:03 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:03 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:03 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:03 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:03 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:04 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:04 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:04 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:04 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:04 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:04 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:05 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:05 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:05 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:05 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:05 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:05 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:06 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:06 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:06 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:06 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:06 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:06 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:07 volumio volumio[1168]: info: Get:7 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-garuda-ttf all 1:0.7.3-1 [187 kB] Oct 14 01:20:07 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:07.050+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:07 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:07 volumio volumio[1168]: info: Get:8 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-kinnari-ttf all 1:0.7.3-1 [317 kB] Oct 14 01:20:07 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:07.117+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:07 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:07 volumio volumio[1168]: info: Get:9 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-laksaman-ttf all 1:0.7.3-1 [223 kB] Oct 14 01:20:07 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:07.368+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:07 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:07 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:07 volumio volumio[1168]: info: Get:10 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-loma-ttf all 1:0.7.3-1 [204 kB] Oct 14 01:20:07 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:07.604+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:07 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:07 volumio volumio[1168]: info: Get:11 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-mono-ttf all 1:0.7.3-1 [210 kB] Oct 14 01:20:07 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:07.809+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:07 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:07 volumio volumio[1168]: info: Get:12 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-norasi-ttf all 1:0.7.3-1 [352 kB] Oct 14 01:20:08 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:08.009+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:08 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:08 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:08 volumio volumio[1168]: info: Get:13 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-purisa-ttf all 1:0.7.3-1 [339 kB] Oct 14 01:20:08 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:08.349+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:08 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:08 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:08 volumio volumio[1168]: info: Get:14 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-sawasdee-ttf all 1:0.7.3-1 [205 kB] Oct 14 01:20:08 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:08.638+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:08 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:08 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:08 volumio volumio[1168]: info: Get:15 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-typewriter-ttf all 1:0.7.3-1 [213 kB] Oct 14 01:20:08 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:08.885+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:09 volumio volumio[1168]: info: Get:16 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-typist-ttf all 1:0.7.3-1 [211 kB] Oct 14 01:20:09 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:09.028+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:09 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:09 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:09 volumio volumio[1168]: info: Get:17 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-typo-ttf all 1:0.7.3-1 [211 kB] Oct 14 01:20:09 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:09.257+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:09 volumio volumio[1168]: info: Get:18 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-umpush-ttf all 1:0.7.3-1 [264 kB] Oct 14 01:20:09 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:09.468+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:09 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:09 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:09 volumio volumio[1168]: info: Get:19 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-waree-ttf all 1:0.7.3-1 [212 kB] Oct 14 01:20:09 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:09.740+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:09 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:09 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:09 volumio volumio[1168]: info: Get:20 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-thai-tlwg-ttf all 1:0.7.3-1 [53.5 kB] Oct 14 01:20:09 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:09.914+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:09 volumio volumio[1168]: info: Get:21 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-garuda all 1:0.7.3-1 [54.1 kB] Oct 14 01:20:09 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:09.980+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:10 volumio volumio[1168]: info: Get:22 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-kinnari all 1:0.7.3-1 [54.1 kB] Oct 14 01:20:10 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:10.036+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:10 volumio volumio[1168]: info: Get:23 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-laksaman all 1:0.7.3-1 [54.1 kB] Oct 14 01:20:10 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:10.096+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:10 volumio volumio[1168]: info: Get:24 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-loma all 1:0.7.3-1 [53.9 kB] Oct 14 01:20:10 volumio volumio[1168]: info: Get:25 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-mono all 1:0.7.3-1 [53.8 kB] Oct 14 01:20:10 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:10.174+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:10 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:10.188+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:10 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:10 volumio volumio[1168]: info: Get:26 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-norasi all 1:0.7.3-1 [53.8 kB] Oct 14 01:20:10 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:10.270+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:10 volumio volumio[1168]: info: Get:27 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-purisa all 1:0.7.3-1 [53.3 kB] Oct 14 01:20:10 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:10.303+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:10 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:10 volumio volumio[1168]: info: Get:28 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-sawasdee all 1:0.7.3-1 [53.4 kB] Oct 14 01:20:10 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:10.390+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:10 volumio volumio[1168]: info: Get:29 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-typewriter all 1:0.7.3-1 [53.4 kB] Oct 14 01:20:10 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:10.423+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:10 volumio volumio[1168]: info: Get:30 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-typist all 1:0.7.3-1 [53.8 kB] Oct 14 01:20:10 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:10.505+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:10 volumio volumio[1168]: info: Get:31 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-typo all 1:0.7.3-1 [53.9 kB] Oct 14 01:20:10 volumio volumio[1168]: info: Get:32 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-umpush all 1:0.7.3-1 [54.1 kB] Oct 14 01:20:10 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:10.545+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:10 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:10.556+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:10 volumio volumio[1168]: info: Get:33 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-tlwg-waree all 1:0.7.3-1 [53.8 kB] Oct 14 01:20:10 volumio volumio[1168]: info: Get:34 http://ftp.arnes.si/mirrors/raspbian/raspbian bookworm/main armhf fonts-vlgothic all 20220612-1 [2239 kB] Oct 14 01:20:10 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:10.682+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:10 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:10.711+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:10 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:10 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:11 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:11 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:11 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:11 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:11 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:11 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:12 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:12 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:12 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:12 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:12 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:12 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:13 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:13 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:13 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:13 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:13 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:13 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:13 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:13 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:14 volumio volumio[1168]: info: Fetched 39.3 MB in 35s (1136 kB/s) Oct 14 01:20:14 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:14.058+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:14 volumio volumio[1168]: info: Selecting previously unselected package fonts-ipafont-gothic. Oct 14 01:20:14 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:14.201+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:14 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:14 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:14 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:14 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:14 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:14 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:15 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:15 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:15 volumio volumio[1168]: [620B blob data] Oct 14 01:20:15 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:15.447+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:15 volumio volumio[1168]: info: Preparing to unpack .../00-fonts-ipafont-gothic_00303-23_all.deb ... Oct 14 01:20:15 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:15.517+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:15 volumio volumio[1168]: info: Unpacking fonts-ipafont-gothic (00303-23) ... Oct 14 01:20:15 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:15.577+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:15 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:15 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:15 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:15 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:16 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:16 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:16 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:16 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:16 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:16 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:17 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:17 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:17 volumio volumio[1168]: info: Selecting previously unselected package fonts-ipafont-mincho. Oct 14 01:20:17 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:17.479+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:17 volumio volumio[1168]: info: Preparing to unpack .../01-fonts-ipafont-mincho_00303-23_all.deb ... Oct 14 01:20:17 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:17.553+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:17 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:17 volumio volumio[1168]: info: Unpacking fonts-ipafont-mincho (00303-23) ... Oct 14 01:20:17 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:17.623+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:17 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:17 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:18 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:18 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:18 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:18 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:18 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:18 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:18 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:19 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:19 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:19 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:19 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:19 volumio volumio[1168]: info: Selecting previously unselected package fonts-ipafont. Oct 14 01:20:19 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:19.873+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:19 volumio volumio[1168]: info: Preparing to unpack .../02-fonts-ipafont_00303-23_all.deb ... Oct 14 01:20:19 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:19.932+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:19 volumio volumio[1168]: info: Unpacking fonts-ipafont (00303-23) ... Oct 14 01:20:19 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:19.989+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:19 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:20 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:20 volumio volumio[1168]: info: Selecting previously unselected package fonts-unfonts-core. Oct 14 01:20:20 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:20.127+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:20 volumio volumio[1168]: info: Preparing to unpack .../03-fonts-unfonts-core_1%3a1.0.2-080608-18_all.deb ... Oct 14 01:20:20 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:20.207+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:20 volumio volumio[1168]: info: Unpacking fonts-unfonts-core (1:1.0.2-080608-18) ... Oct 14 01:20:20 volumio volumio5-onboarding[1558]: time=2026-10-14T01:20:20.288+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 14 01:20:20 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:20 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:20 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:20 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:20 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:20 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:21 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:21 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:21 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 01:20:21 volumio volumio[1168]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 14 01:20:21 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:21 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:21 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:21 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:22 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:22 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:22 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:22 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:22 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:22 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:23 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:23 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:23 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:23 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:23 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:23 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:24 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:24 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:24 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:24 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:24 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:24 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:25 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:25 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:25 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:25 volumio volumio[1168]: info: CoreCommandRouter::volumioGetState Oct 14 01:20:25 volumio sudo[8018]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Oct 14 01:20:25 volumio sudo[8018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 01:20:25 volumio sudo[8020]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Oct 14 01:20:25 volumio sudo[8020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 01:20:25 volumio sudo[8018]: pam_unix(sudo:session): session closed for user root Oct 14 01:20:25 volumio volumio[1168]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Oct 14 01:20:25 volumio sudo[8023]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Oct 14 01:20:25 volumio sudo[8023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 01:20:25 volumio sudo[8026]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Oct 14 01:20:25 volumio sudo[8026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 01:20:25 volumio volumio[1168]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 01:20:25 volumio sudo[8026]: pam_unix(sudo:session): session closed for user root Oct 14 01:20:25 volumio systemd[1]: Reloading. Oct 14 01:20:25 volumio volumio[1168]: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 01:20:25 volumio volumio[1168]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Oct 14 01:20:25 volumio volumio[1168]: at ChildProcess.exithandler (node:child_process:413:7) Oct 14 01:20:25 volumio volumio[1168]: at ChildProcess.emit (node:events:514:28) Oct 14 01:20:25 volumio volumio[1168]: at maybeClose (node:internal/child_process:1105:16) Oct 14 01:20:25 volumio volumio[1168]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Oct 14 01:20:25 volumio volumio[1168]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 01:20:27 volumio sudo[8023]: pam_unix(sudo:session): session closed for user root Oct 14 01:20:27 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 14 01:20:27 volumio sudo[8020]: pam_unix(sudo:session): session closed for user root Oct 14 01:20:28 volumio sudo[8100]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 01:19' Oct 14 01:20:28 volumio sudo[8100]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"