Feb 11 23:25:05 volumiopi4 wpa_supplicant[1255]: wlan0: WPA: Group rekeying completed with e2:08:55:05:3c:80 [GTK=CCMP] Feb 11 23:25:34 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioGetState Feb 11 23:25:34 volumiopi4 volumio[1292]: info: CorePlayQueue::getTrack 0 Feb 11 23:25:35 volumiopi4 volumio[1292]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 23:25:35 volumiopi4 volumio[1292]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 11 23:25:37 volumiopi4 volumio[1292]: info: Disabling plugin Systeminfo Feb 11 23:25:37 volumiopi4 volumio[1292]: info: Done. Feb 11 23:25:38 volumiopi4 volumio[1292]: info: Disabling plugin backup_restore Feb 11 23:25:38 volumiopi4 volumio[1292]: info: Done. Feb 11 23:25:43 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioGetState Feb 11 23:25:43 volumiopi4 volumio[1292]: info: CorePlayQueue::getTrack 0 Feb 11 23:25:44 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPlay Feb 11 23:25:44 volumiopi4 volumio[1292]: info: CoreStateMachine::play index undefined Feb 11 23:25:44 volumiopi4 volumio[1292]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 23:25:44 volumiopi4 volumio[1292]: info: CorePlayQueue::getTrack 0 Feb 11 23:25:44 volumiopi4 volumio[1292]: info: CoreStateMachine::startPlaybackTimer Feb 11 23:25:44 volumiopi4 volumio[1292]: info: CorePlayQueue::getTrack 0 Feb 11 23:25:44 volumiopi4 volumio[1292]: info: [1770848744613] ControllerWebradio::clearAddPlayTrack Feb 11 23:25:44 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 23:25:44 volumiopi4 volumio[1292]: info: sendMpdCommand stop took 12 milliseconds Feb 11 23:25:44 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand clear Feb 11 23:25:44 volumiopi4 volumio[1292]: info: Feb 11 23:25:44 volumiopi4 volumio[1292]: ---------------------------- MPD announces system playlist update Feb 11 23:25:44 volumiopi4 volumio[1292]: info: Ignoring MPD Status Update Feb 11 23:25:44 volumiopi4 volumio[1292]: info: sendMpdCommand clear took 5 milliseconds Feb 11 23:25:44 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand load "http://streams.80s80s.de/web/mp3-192/tunein/play.m3u" Feb 11 23:25:44 volumiopi4 volumio[1292]: info: Feb 11 23:25:44 volumiopi4 volumio[1292]: ---------------------------- MPD announces system playlist update Feb 11 23:25:44 volumiopi4 volumio[1292]: info: Ignoring MPD Status Update Feb 11 23:25:44 volumiopi4 volumio[1292]: info: Feb 11 23:25:44 volumiopi4 volumio[1292]: ---------------------------- MPD announces system playlist update Feb 11 23:25:44 volumiopi4 volumio[1292]: info: Ignoring MPD Status Update Feb 11 23:25:44 volumiopi4 volumio[1292]: error: updateQueue error: null Feb 11 23:25:44 volumiopi4 volumio[1292]: info: Feb 11 23:25:44 volumiopi4 volumio[1292]: ---------------------------- MPD announces system playlist update Feb 11 23:25:44 volumiopi4 volumio[1292]: info: Ignoring MPD Status Update Feb 11 23:25:44 volumiopi4 volumio[1292]: info: ------------------------------ 84ms Feb 11 23:25:44 volumiopi4 volumio[1292]: info: sendMpdCommand load "http://streams.80s80s.de/web/mp3-192/tunein/play.m3u" took 80 milliseconds Feb 11 23:25:44 volumiopi4 volumio[1292]: info: ------------------------------ 76ms Feb 11 23:25:44 volumiopi4 volumio[1292]: info: ------------------------------ 75ms Feb 11 23:25:44 volumiopi4 volumio[1292]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 11 23:25:44 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand play Feb 11 23:25:44 volumiopi4 volumio[1292]: info: Feb 11 23:25:44 volumiopi4 volumio[1292]: ---------------------------- MPD announces system playlist update Feb 11 23:25:44 volumiopi4 volumio[1292]: info: Ignoring MPD Status Update Feb 11 23:25:44 volumiopi4 volumio[1292]: info: Feb 11 23:25:44 volumiopi4 volumio[1292]: ---------------------------- MPD announces system playlist update Feb 11 23:25:44 volumiopi4 volumio[1292]: info: Ignoring MPD Status Update Feb 11 23:25:44 volumiopi4 volumio[1292]: info: ------------------------------ 25ms Feb 11 23:25:44 volumiopi4 volumio[1292]: info: sendMpdCommand play took 20 milliseconds Feb 11 23:25:44 volumiopi4 volumio[1292]: info: ------------------------------ 13ms Feb 11 23:25:44 volumiopi4 volumio[1292]: info: ------------------------------ 12ms Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Feb 11 23:25:45 volumiopi4 volumio[1292]: ---------------------------- MPD announces state update: player Feb 11 23:25:45 volumiopi4 volumio[1292]: info: ControllerMpd::getState Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Feb 11 23:25:45 volumiopi4 volumio[1292]: ---------------------------- MPD announces state update: player Feb 11 23:25:45 volumiopi4 volumio[1292]: info: ControllerMpd::getState Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Feb 11 23:25:45 volumiopi4 volumio[1292]: ---------------------------- MPD announces state update: player Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Feb 11 23:25:45 volumiopi4 volumio[1292]: ---------------------------- MPD announces state update: player Feb 11 23:25:45 volumiopi4 volumio[1292]: info: ControllerMpd::getState Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:25:45 volumiopi4 volumio[1292]: info: sendMpdCommand status took 12 milliseconds Feb 11 23:25:45 volumiopi4 volumio[1292]: info: ControllerMpd::getState Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:25:45 volumiopi4 volumio[1292]: info: sendMpdCommand status took 6 milliseconds Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseState Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseState Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Feb 11 23:25:45 volumiopi4 volumio[1292]: ---------------------------- MPD announces state update: player Feb 11 23:25:45 volumiopi4 volumio[1292]: info: ControllerMpd::getState Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Feb 11 23:25:45 volumiopi4 volumio[1292]: ---------------------------- MPD announces state update: player Feb 11 23:25:45 volumiopi4 volumio[1292]: info: ControllerMpd::getState Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:25:45 volumiopi4 volumio[1292]: info: sendMpdCommand status took 29 milliseconds Feb 11 23:25:45 volumiopi4 volumio[1292]: info: sendMpdCommand status took 30 milliseconds Feb 11 23:25:45 volumiopi4 volumio[1292]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 11 23:25:45 volumiopi4 volumio[1292]: info: sendMpdCommand playlistinfo took 18 milliseconds Feb 11 23:25:45 volumiopi4 volumio[1292]: info: sendMpdCommand status took 12 milliseconds Feb 11 23:25:45 volumiopi4 volumio[1292]: info: sendMpdCommand status took 4 milliseconds Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseState Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseState Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseState Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseState Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:25:45 volumiopi4 volumio[1292]: info: ControllerMpd::pushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreCommandRouter::servicePushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CorePlayQueue::getTrack 0 Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"regc-80s80smweb2517500-mp3-192-1672667?sABC=698q01r8%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770848744","artist":null,"album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698q01r8%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770848744","trackType":""} Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: CURRENT POSITION 0 Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState stateService play Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState currentStatus stop Feb 11 23:25:45 volumiopi4 volumio[1292]: info: ControllerMpd::pushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreCommandRouter::servicePushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CorePlayQueue::getTrack 0 Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"regc-80s80smweb2517500-mp3-192-1672667?sABC=698q01r8%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770848744","artist":null,"album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698q01r8%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770848744","trackType":""} Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: CURRENT POSITION 0 Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState stateService play Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Received an update from plugin. extracting info from payload Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CorePlayQueue::getTrack 0 Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: ------------------------------ 156ms Feb 11 23:25:45 volumiopi4 volumio[1292]: info: ------------------------------ 240ms Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Feb 11 23:25:45 volumiopi4 volumio[1292]: ---------------------------- MPD announces system playlist update Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Ignoring MPD Status Update Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Feb 11 23:25:45 volumiopi4 volumio[1292]: ---------------------------- MPD announces state update: player Feb 11 23:25:45 volumiopi4 volumio[1292]: info: ControllerMpd::getState Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Feb 11 23:25:45 volumiopi4 volumio[1292]: ---------------------------- MPD announces system playlist update Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Ignoring MPD Status Update Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Feb 11 23:25:45 volumiopi4 volumio[1292]: ---------------------------- MPD announces state update: player Feb 11 23:25:45 volumiopi4 volumio[1292]: info: ControllerMpd::getState Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Feb 11 23:25:45 volumiopi4 volumio[1292]: ---------------------------- MPD announces system playlist update Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Ignoring MPD Status Update Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Feb 11 23:25:45 volumiopi4 volumio[1292]: ---------------------------- MPD announces state update: player Feb 11 23:25:45 volumiopi4 volumio[1292]: info: ControllerMpd::getState Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:25:45 volumiopi4 volumio[1292]: info: sendMpdCommand playlistinfo took 218 milliseconds Feb 11 23:25:45 volumiopi4 volumio[1292]: info: sendMpdCommand playlistinfo took 219 milliseconds Feb 11 23:25:45 volumiopi4 volumio[1292]: info: sendMpdCommand playlistinfo took 201 milliseconds Feb 11 23:25:45 volumiopi4 volumio[1292]: info: sendMpdCommand playlistinfo took 200 milliseconds Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:25:45 volumiopi4 volumio[1292]: info: ControllerMpd::pushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreCommandRouter::servicePushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CorePlayQueue::getTrack 0 Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"80s80s Digital Web","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698q01r8%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770848744","trackType":""} Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: CURRENT POSITION 0 Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState stateService play Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Received an update from plugin. extracting info from payload Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: ControllerMpd::pushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreCommandRouter::servicePushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CorePlayQueue::getTrack 0 Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"80s80s Digital Web","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698q01r8%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770848744","trackType":""} Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: CURRENT POSITION 0 Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState stateService play Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Received an update from plugin. extracting info from payload Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: ControllerMpd::pushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreCommandRouter::servicePushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CorePlayQueue::getTrack 0 Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"80s80s Digital Web","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698q01r8%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770848744","trackType":""} Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: CURRENT POSITION 0 Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState stateService play Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Received an update from plugin. extracting info from payload Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: ControllerMpd::pushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreCommandRouter::servicePushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CorePlayQueue::getTrack 0 Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"80s80s Digital Web","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698q01r8%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770848744","trackType":""} Feb 11 23:25:45 volumiopi4 volumio[1292]: verbose: CURRENT POSITION 0 Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState stateService play Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:25:45 volumiopi4 volumio[1292]: info: Received an update from plugin. extracting info from payload Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:25:45 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: ------------------------------ 523ms Feb 11 23:25:46 volumiopi4 volumio[1292]: info: ------------------------------ 523ms Feb 11 23:25:46 volumiopi4 volumio[1292]: info: ------------------------------ 509ms Feb 11 23:25:46 volumiopi4 volumio[1292]: info: ------------------------------ 521ms Feb 11 23:25:46 volumiopi4 volumio[1292]: info: ------------------------------ 416ms Feb 11 23:25:46 volumiopi4 volumio[1292]: info: sendMpdCommand status took 409 milliseconds Feb 11 23:25:46 volumiopi4 volumio[1292]: info: ------------------------------ 404ms Feb 11 23:25:46 volumiopi4 volumio[1292]: info: sendMpdCommand status took 403 milliseconds Feb 11 23:25:46 volumiopi4 volumio[1292]: info: ------------------------------ 403ms Feb 11 23:25:46 volumiopi4 volumio[1292]: info: sendMpdCommand status took 402 milliseconds Feb 11 23:25:46 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseState Feb 11 23:25:46 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:25:46 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseState Feb 11 23:25:46 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:25:46 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseState Feb 11 23:25:46 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:25:46 volumiopi4 volumio[1292]: info: sendMpdCommand playlistinfo took 34 milliseconds Feb 11 23:25:46 volumiopi4 volumio[1292]: info: sendMpdCommand playlistinfo took 33 milliseconds Feb 11 23:25:46 volumiopi4 volumio[1292]: info: sendMpdCommand playlistinfo took 33 milliseconds Feb 11 23:25:46 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:25:46 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:25:46 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:25:46 volumiopi4 volumio[1292]: info: ControllerMpd::pushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreCommandRouter::servicePushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CorePlayQueue::getTrack 0 Feb 11 23:25:46 volumiopi4 volumio[1292]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1103,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"80s80s Digital Web","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698q01r8%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770848744","trackType":""} Feb 11 23:25:46 volumiopi4 volumio[1292]: verbose: CURRENT POSITION 0 Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState stateService play Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:25:46 volumiopi4 volumio[1292]: info: Received an update from plugin. extracting info from payload Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: ControllerMpd::pushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreCommandRouter::servicePushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CorePlayQueue::getTrack 0 Feb 11 23:25:46 volumiopi4 volumio[1292]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1103,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"80s80s Digital Web","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698q01r8%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770848744","trackType":""} Feb 11 23:25:46 volumiopi4 volumio[1292]: verbose: CURRENT POSITION 0 Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState stateService play Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:25:46 volumiopi4 volumio[1292]: info: Received an update from plugin. extracting info from payload Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: ControllerMpd::pushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreCommandRouter::servicePushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CorePlayQueue::getTrack 0 Feb 11 23:25:46 volumiopi4 volumio[1292]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1103,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"80s80s Digital Web","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698q01r8%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770848744","trackType":""} Feb 11 23:25:46 volumiopi4 volumio[1292]: verbose: CURRENT POSITION 0 Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState stateService play Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:25:46 volumiopi4 volumio[1292]: info: Received an update from plugin. extracting info from payload Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:25:46 volumiopi4 volumio[1292]: info: ------------------------------ 688ms Feb 11 23:25:46 volumiopi4 volumio[1292]: info: ------------------------------ 674ms Feb 11 23:25:46 volumiopi4 volumio[1292]: info: ------------------------------ 674ms Feb 11 23:25:48 volumiopi4 volumio[1292]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 23:25:48 volumiopi4 volumio[1292]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 11 23:25:56 volumiopi4 volumio[1292]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 11 23:25:59 volumiopi4 volumio[1292]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/touch_display/3.6.0/volumio/bookworm/armhf Feb 11 23:25:59 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:25:59.410+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:00 volumiopi4 volumio[1292]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/touch_display/3.6.0/volumio/bookworm/armhf Feb 11 23:26:00 volumiopi4 volumio[1292]: info: Folder /tmp/plugins removed Feb 11 23:26:00 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:00.583+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:00 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:00.724+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:00 volumiopi4 volumio[1292]: info: Check plugin dependencies Feb 11 23:26:00 volumiopi4 volumio[1292]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 23:26:00 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:00.763+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:00 volumiopi4 volumio[1292]: info: Checking if plugin already exists Feb 11 23:26:00 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:00.769+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:00 volumiopi4 volumio[1292]: info: Rename folder Feb 11 23:26:00 volumiopi4 volumio[1292]: info: Folder /tmp/downloaded_plugin.zip removed Feb 11 23:26:00 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:00.790+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:00 volumiopi4 volumio[1292]: info: Move to category Feb 11 23:26:01 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:01.149+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:01 volumiopi4 volumio[1292]: info: Checking if install.sh is present Feb 11 23:26:01 volumiopi4 volumio[1292]: info: Executing install.sh Feb 11 23:26:01 volumiopi4 sudo[3176]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/touch_display/install.sh Feb 11 23:26:01 volumiopi4 sudo[3176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:26:01 volumiopi4 volumio[1292]: info: Re-synchronizing package index files from their sources Feb 11 23:26:01 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:01.219+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:01 volumiopi4 volumio[1292]: info: Hit:1 http://archive.raspberrypi.com/debian bookworm InRelease Feb 11 23:26:01 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:01.490+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:01 volumiopi4 volumio[1292]: info: Hit:2 http://raspbian.raspberrypi.com/raspbian bookworm InRelease Feb 11 23:26:01 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:01.497+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:04 volumiopi4 volumio[1292]: info: Feb 11 23:26:04 volumiopi4 volumio[1292]: ---------------------------- MPD announces system playlist update Feb 11 23:26:04 volumiopi4 volumio[1292]: info: Ignoring MPD Status Update Feb 11 23:26:04 volumiopi4 volumio[1292]: info: Feb 11 23:26:04 volumiopi4 volumio[1292]: ---------------------------- MPD announces state update: player Feb 11 23:26:04 volumiopi4 volumio[1292]: info: ControllerMpd::getState Feb 11 23:26:04 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:26:04 volumiopi4 volumio[1292]: info: Feb 11 23:26:04 volumiopi4 volumio[1292]: ---------------------------- MPD announces system playlist update Feb 11 23:26:04 volumiopi4 volumio[1292]: info: Ignoring MPD Status Update Feb 11 23:26:04 volumiopi4 volumio[1292]: info: Feb 11 23:26:04 volumiopi4 volumio[1292]: ---------------------------- MPD announces state update: player Feb 11 23:26:04 volumiopi4 volumio[1292]: info: ControllerMpd::getState Feb 11 23:26:04 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:26:04 volumiopi4 volumio[1292]: info: Feb 11 23:26:04 volumiopi4 volumio[1292]: ---------------------------- MPD announces system playlist update Feb 11 23:26:04 volumiopi4 volumio[1292]: info: Ignoring MPD Status Update Feb 11 23:26:04 volumiopi4 volumio[1292]: info: Feb 11 23:26:04 volumiopi4 volumio[1292]: ---------------------------- MPD announces state update: player Feb 11 23:26:04 volumiopi4 volumio[1292]: info: ControllerMpd::getState Feb 11 23:26:04 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:26:04 volumiopi4 volumio[1292]: info: ------------------------------ 13ms Feb 11 23:26:04 volumiopi4 volumio[1292]: info: sendMpdCommand status took 11 milliseconds Feb 11 23:26:04 volumiopi4 volumio[1292]: info: ------------------------------ 10ms Feb 11 23:26:04 volumiopi4 volumio[1292]: info: sendMpdCommand status took 8 milliseconds Feb 11 23:26:04 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseState Feb 11 23:26:04 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:26:04 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseState Feb 11 23:26:04 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:26:04 volumiopi4 volumio[1292]: info: ------------------------------ 10ms Feb 11 23:26:04 volumiopi4 volumio[1292]: info: sendMpdCommand status took 4 milliseconds Feb 11 23:26:04 volumiopi4 volumio[1292]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 11 23:26:04 volumiopi4 volumio[1292]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 11 23:26:04 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseState Feb 11 23:26:04 volumiopi4 volumio[1292]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:26:04 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:26:04 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:26:04 volumiopi4 volumio[1292]: info: ControllerMpd::pushState Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreCommandRouter::servicePushState Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CorePlayQueue::getTrack 0 Feb 11 23:26:04 volumiopi4 volumio[1292]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20101,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Pet Shop Boys - Heart","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698q01r8%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770848744","trackType":""} Feb 11 23:26:04 volumiopi4 volumio[1292]: verbose: CURRENT POSITION 0 Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState stateService play Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:26:04 volumiopi4 volumio[1292]: info: Received an update from plugin. extracting info from payload Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:26:04 volumiopi4 volumio[1292]: info: ControllerMpd::pushState Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreCommandRouter::servicePushState Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CorePlayQueue::getTrack 0 Feb 11 23:26:04 volumiopi4 volumio[1292]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20101,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Pet Shop Boys - Heart","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698q01r8%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770848744","trackType":""} Feb 11 23:26:04 volumiopi4 volumio[1292]: verbose: CURRENT POSITION 0 Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState stateService play Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:26:04 volumiopi4 volumio[1292]: info: Received an update from plugin. extracting info from payload Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:26:04 volumiopi4 volumio[1292]: info: ------------------------------ 79ms Feb 11 23:26:04 volumiopi4 volumio[1292]: info: ------------------------------ 79ms Feb 11 23:26:04 volumiopi4 volumio[1292]: info: sendMpdCommand playlistinfo took 66 milliseconds Feb 11 23:26:04 volumiopi4 volumio[1292]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:26:04 volumiopi4 volumio[1292]: info: ControllerMpd::pushState Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreCommandRouter::servicePushState Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CorePlayQueue::getTrack 0 Feb 11 23:26:04 volumiopi4 volumio[1292]: verbose: STATE SERVICE {"status":"play","position":0,"seek":20101,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Pet Shop Boys - Heart","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698q01r8%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770848744","trackType":""} Feb 11 23:26:04 volumiopi4 volumio[1292]: verbose: CURRENT POSITION 0 Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState stateService play Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:26:04 volumiopi4 volumio[1292]: info: Received an update from plugin. extracting info from payload Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreStateMachine::pushState Feb 11 23:26:04 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioPushState Feb 11 23:26:04 volumiopi4 volumio[1292]: info: ------------------------------ 100ms Feb 11 23:26:05 volumiopi4 volumio[1292]: info: Reading package lists... Feb 11 23:26:05 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:05.549+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:05 volumiopi4 volumio[1292]: info: Reading package lists... Feb 11 23:26:05 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:05.724+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:06 volumiopi4 volumio[1292]: info: Building dependency tree... Feb 11 23:26:06 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:06.240+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:07 volumiopi4 volumio[1292]: info: Reading state information... Feb 11 23:26:07 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:07.117+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:08 volumiopi4 volumio[1292]: info: 0 upgraded, 0 newly installed, 0 to remove and 26 not upgraded. Feb 11 23:26:08 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:08.180+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:08 volumiopi4 volumio[1292]: info: Installing graphical environment Feb 11 23:26:08 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:08.190+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:08 volumiopi4 volumio[1292]: info: Reading package lists... Feb 11 23:26:08 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:08.291+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:08 volumiopi4 volumio[1292]: info: Building dependency tree... Feb 11 23:26:08 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:08.792+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:08 volumiopi4 volumio[1292]: info: Reading state information... Feb 11 23:26:08 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:08.796+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:09 volumiopi4 volumio[1292]: info: x11-utils is already the newest version (7.7+5). Feb 11 23:26:09 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:09.873+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:09 volumiopi4 volumio[1292]: info: 0 upgraded, 0 newly installed, 0 to remove and 26 not upgraded. Feb 11 23:26:09 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:09.879+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:09 volumiopi4 volumio[1292]: info: Reading package lists... Feb 11 23:26:09 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:09.973+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:10 volumiopi4 volumio[1292]: info: Building dependency tree... Feb 11 23:26:10 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:10.488+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:10 volumiopi4 volumio[1292]: info: Reading state information... Feb 11 23:26:10 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:10.497+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:11 volumiopi4 volumio[1292]: info: xinit is already the newest version (1.4.0-1). Feb 11 23:26:11 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:11.561+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:11 volumiopi4 volumio[1292]: info: 0 upgraded, 0 newly installed, 0 to remove and 26 not upgraded. Feb 11 23:26:11 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:11.567+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:11 volumiopi4 volumio[1292]: info: Reading package lists... Feb 11 23:26:11 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:11.662+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:12 volumiopi4 volumio[1292]: info: Building dependency tree... Feb 11 23:26:12 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:12.169+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:12 volumiopi4 volumio[1292]: info: Reading state information... Feb 11 23:26:12 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:12.174+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:13 volumiopi4 volumio[1292]: info: xorg is already the newest version (1:7.7+23+b1). Feb 11 23:26:13 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:13.253+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:13 volumiopi4 volumio[1292]: info: 0 upgraded, 0 newly installed, 0 to remove and 26 not upgraded. Feb 11 23:26:13 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:13.259+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:13 volumiopi4 volumio[1292]: info: Reading package lists... Feb 11 23:26:13 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:13.354+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:13 volumiopi4 volumio[1292]: info: Building dependency tree... Feb 11 23:26:13 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:13.862+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:13 volumiopi4 volumio[1292]: info: Reading state information... Feb 11 23:26:13 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:13.868+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:14 volumiopi4 volumio[1292]: info: xinput is already the newest version (1.6.3-1). Feb 11 23:26:14 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:14.935+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:14 volumiopi4 volumio[1292]: info: 0 upgraded, 0 newly installed, 0 to remove and 26 not upgraded. Feb 11 23:26:14 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:14.942+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:15 volumiopi4 volumio[1292]: info: Reading package lists... Feb 11 23:26:15 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:15.045+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:15 volumiopi4 volumio[1292]: info: Building dependency tree... Feb 11 23:26:15 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:15.552+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:15 volumiopi4 volumio[1292]: info: Reading state information... Feb 11 23:26:15 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:15.559+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:16 volumiopi4 volumio[1292]: info: openbox is already the newest version (3.6.1-10+rpt1). Feb 11 23:26:16 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:16.644+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:16 volumiopi4 volumio[1292]: info: 0 upgraded, 0 newly installed, 0 to remove and 26 not upgraded. Feb 11 23:26:16 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:16.649+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:16 volumiopi4 volumio[1292]: info: Creating /etc/X11/xorg.conf.d dir Feb 11 23:26:16 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:16.660+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:16 volumiopi4 volumio[1292]: info: Creating Xorg configuration Feb 11 23:26:16 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:16.665+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:16 volumiopi4 volumio[1292]: info: Installing Chromium Feb 11 23:26:16 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:16.676+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:16 volumiopi4 volumio[1292]: info: Reading package lists... Feb 11 23:26:16 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:16.766+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:17 volumiopi4 volumio[1292]: info: Building dependency tree... Feb 11 23:26:17 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:17.273+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:17 volumiopi4 volumio[1292]: info: Reading state information... Feb 11 23:26:17 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:17.282+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:18 volumiopi4 volumio[1292]: info: chromium-browser is already the newest version (1:144.0.7559.109-2~deb12u1+rpt1). Feb 11 23:26:18 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:18.353+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:18 volumiopi4 volumio[1292]: info: 0 upgraded, 0 newly installed, 0 to remove and 26 not upgraded. Feb 11 23:26:18 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:18.358+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:18 volumiopi4 volumio[1292]: info: Installing fonts Feb 11 23:26:18 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:18.368+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:18 volumiopi4 volumio[1292]: info: Reading package lists... Feb 11 23:26:18 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:18.465+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:18 volumiopi4 volumio[1292]: info: Building dependency tree... Feb 11 23:26:18 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:18.979+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:18 volumiopi4 volumio[1292]: info: Reading state information... Feb 11 23:26:18 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:18.988+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:20 volumiopi4 volumio[1292]: info: fonts-arphic-ukai is already the newest version (0.2.20080216.2-5). Feb 11 23:26:20 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:20.066+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:20 volumiopi4 volumio[1292]: info: fonts-arphic-gbsn00lp is already the newest version (2.11-16). Feb 11 23:26:20 volumiopi4 volumio[1292]: info: fonts-unfonts-core is already the newest version (1:1.0.2-080608-18). Feb 11 23:26:20 volumiopi4 volumio[1292]: info: fonts-ipafont is already the newest version (00303-23). Feb 11 23:26:20 volumiopi4 volumio[1292]: info: fonts-vlgothic is already the newest version (20220612-1). Feb 11 23:26:20 volumiopi4 volumio[1292]: info: fonts-thai-tlwg-ttf is already the newest version (1:0.7.3-1). Feb 11 23:26:20 volumiopi4 volumio[1292]: info: 0 upgraded, 0 newly installed, 0 to remove and 26 not upgraded. Feb 11 23:26:20 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:20.083+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:20 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:20.084+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:20 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:20.085+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:20 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:20.085+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:20 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:20.086+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:20 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:20.087+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:20 volumiopi4 systemd[1]: Reloading. Feb 11 23:26:20 volumiopi4 volumio[1292]: info: Creating Kiosk data dir Feb 11 23:26:20 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:20.109+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:20 volumiopi4 volumio[1292]: info: Creating Volumio kiosk start script Feb 11 23:26:20 volumiopi4 volumio[1292]: info: Creating Systemd Unit for Kiosk Feb 11 23:26:20 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:20.116+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:20 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:20.116+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:21 volumiopi4 volumio[1292]: info: Installing Virtual Keyboard Feb 11 23:26:21 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:21.010+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:22 volumiopi4 volumio[1292]: info: Allowing volumio to start an xsession Feb 11 23:26:22 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:22.431+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:22 volumiopi4 volumio[1292]: info: Configuring GPU/DRI permissions for volumio user Feb 11 23:26:22 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:22.440+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:22 volumiopi4 usermod[3463]: add 'volumio' to group 'video' Feb 11 23:26:22 volumiopi4 usermod[3463]: add 'volumio' to group 'render' Feb 11 23:26:22 volumiopi4 usermod[3463]: add 'volumio' to shadow group 'video' Feb 11 23:26:22 volumiopi4 usermod[3463]: add 'volumio' to shadow group 'render' Feb 11 23:26:22 volumiopi4 volumio[1292]: info: Creating udev rules for persistent DRI device permissions Feb 11 23:26:22 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:22.524+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:22 volumiopi4 volumio[1292]: info: Reloading udev rules Feb 11 23:26:22 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:22.534+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:22 volumiopi4 volumio[1292]: info: Creating Mesa shader cache directory Feb 11 23:26:22 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:22.728+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:22 volumiopi4 sudo[3176]: pam_unix(sudo:session): session closed for user root Feb 11 23:26:22 volumiopi4 volumio[1292]: Plugin install end detected on script Feb 11 23:26:22 volumiopi4 volumio[1292]: info: Install script completed Feb 11 23:26:22 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:22.779+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:22 volumiopi4 volumio[1292]: info: Adding reference to registry Feb 11 23:26:22 volumiopi4 volumio5-onboarding[1597]: time=2026-02-11T23:26:22.821+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:26:22 volumiopi4 systemd[1416]: Reached target sound.target - Sound Card. Feb 11 23:26:22 volumiopi4 volumio[1292]: info: Done installing plugin. Feb 11 23:26:22 volumiopi4 volumio[1292]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 23:26:22 volumiopi4 volumio[1292]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 11 23:26:22 volumiopi4 volumio[1292]: info: Folder /tmp/plugins removed Feb 11 23:26:22 volumiopi4 volumio[1292]: info: Folder /tmp/downloaded_plugin.zip removed Feb 11 23:26:22 volumiopi4 volumio[1292]: info: Folder /data/temp removed Feb 11 23:26:23 volumiopi4 systemd-logind[768]: Watching system buttons on /dev/input/event2 (vc4-hdmi-1) Feb 11 23:26:23 volumiopi4 systemd-logind[768]: Watching system buttons on /dev/input/event0 (vc4-hdmi-0) Feb 11 23:26:23 volumiopi4 systemd[1416]: Reached target bluetooth.target - Bluetooth. Feb 11 23:26:32 volumiopi4 volumio[1292]: info: Enabling plugin touch_display Feb 11 23:26:32 volumiopi4 volumio[1292]: info: Loading plugin "touch_display"... Feb 11 23:26:33 volumiopi4 volumio[1292]: info: Applying required configuration parameters for plugin touch_display Feb 11 23:26:33 volumiopi4 volumio[1292]: info: PLUGIN START: touch_display Feb 11 23:26:33 volumiopi4 volumio[1292]: info: Loading i18n strings for locale de Feb 11 23:26:33 volumiopi4 volumio[1292]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 23:26:33 volumiopi4 sudo[3578]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Feb 11 23:26:33 volumiopi4 sudo[3578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:26:33 volumiopi4 sudo[3578]: pam_unix(sudo:session): session closed for user root Feb 11 23:26:33 volumiopi4 volumio[1292]: info: touch_display: No backlight interface detected. Feb 11 23:26:33 volumiopi4 sudo[3580]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 11 23:26:33 volumiopi4 sudo[3580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:26:33 volumiopi4 sudo[3583]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 11 23:26:33 volumiopi4 sudo[3583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:26:33 volumiopi4 volumio[1292]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 11 23:26:33 volumiopi4 sudo[3585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 11 23:26:33 volumiopi4 sudo[3585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:26:33 volumiopi4 sudo[3590]: 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/ Feb 11 23:26:33 volumiopi4 sudo[3590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:26:33 volumiopi4 volumio[1292]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 11 23:26:33 volumiopi4 sudo[3590]: pam_unix(sudo:session): session closed for user root Feb 11 23:26:33 volumiopi4 systemd[1]: Reloading. Feb 11 23:26:33 volumiopi4 volumio[1292]: info: touch_display: IframeKeyboardBridge extension installed successfully Feb 11 23:26:33 volumiopi4 sudo[3594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 11 23:26:33 volumiopi4 sudo[3594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:26:33 volumiopi4 sudo[3594]: pam_unix(sudo:session): session closed for user root Feb 11 23:26:33 volumiopi4 volumio[1292]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 11 23:26:34 volumiopi4 sudo[3585]: pam_unix(sudo:session): session closed for user root Feb 11 23:26:34 volumiopi4 volumio[1292]: info: touch_display: systemctl daemon-reload succeeded. Feb 11 23:26:34 volumiopi4 systemd[1]: Reloading. Feb 11 23:26:34 volumiopi4 sudo[3620]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 11 23:26:34 volumiopi4 sudo[3620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:26:35 volumiopi4 volumio[1292]: info: Enabling plugin touch_display Feb 11 23:26:35 volumiopi4 volumio[1292]: info: Loading plugin "touch_display"... Feb 11 23:26:35 volumiopi4 volumio[1292]: info: Applying required configuration parameters for plugin touch_display Feb 11 23:26:35 volumiopi4 volumio[1292]: info: PLUGIN START: touch_display Feb 11 23:26:35 volumiopi4 volumio[1292]: info: Loading i18n strings for locale de Feb 11 23:26:35 volumiopi4 volumio[1292]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 23:26:35 volumiopi4 volumio[1292]: info: touch_display: No backlight interface detected. Feb 11 23:26:35 volumiopi4 sudo[3646]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 11 23:26:35 volumiopi4 sudo[3646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:26:35 volumiopi4 sudo[3648]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 11 23:26:35 volumiopi4 sudo[3648]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:26:35 volumiopi4 sudo[3641]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Feb 11 23:26:35 volumiopi4 volumio[1292]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 11 23:26:35 volumiopi4 sudo[3641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:26:35 volumiopi4 sudo[3644]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 11 23:26:35 volumiopi4 sudo[3644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:26:35 volumiopi4 sudo[3641]: pam_unix(sudo:session): session closed for user root Feb 11 23:26:35 volumiopi4 sudo[3653]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 11 23:26:35 volumiopi4 sudo[3653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:26:35 volumiopi4 sudo[3653]: pam_unix(sudo:session): session closed for user root Feb 11 23:26:35 volumiopi4 volumio[1292]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 11 23:26:35 volumiopi4 sudo[3657]: 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/ Feb 11 23:26:35 volumiopi4 sudo[3657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:26:35 volumiopi4 sudo[3657]: pam_unix(sudo:session): session closed for user root Feb 11 23:26:35 volumiopi4 volumio[1292]: info: touch_display: IframeKeyboardBridge extension installed successfully Feb 11 23:26:36 volumiopi4 volumio[1292]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 23:26:36 volumiopi4 volumio[1292]: info: touch_display: About to detect HDMI ports... Feb 11 23:26:36 volumiopi4 volumio[1292]: info: touch_display: X server not ready, cannot detect HDMI ports yet. Feb 11 23:26:36 volumiopi4 sudo[3583]: pam_unix(sudo:session): session closed for user root Feb 11 23:26:36 volumiopi4 volumio[1292]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 11 23:26:36 volumiopi4 systemd[1]: Reloading. Feb 11 23:26:37 volumiopi4 sudo[3646]: pam_unix(sudo:session): session closed for user root Feb 11 23:26:37 volumiopi4 systemd[1]: Reloading. Feb 11 23:26:37 volumiopi4 volumio[1292]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 11 23:26:38 volumiopi4 sudo[3648]: pam_unix(sudo:session): session closed for user root Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: systemctl daemon-reload succeeded. Feb 11 23:26:38 volumiopi4 sudo[3703]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 11 23:26:38 volumiopi4 sudo[3703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:26:38 volumiopi4 systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Feb 11 23:26:38 volumiopi4 sudo[3620]: pam_unix(sudo:session): session closed for user root Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: Volumio Kiosk started. Feb 11 23:26:38 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioGetState Feb 11 23:26:38 volumiopi4 volumio[1292]: info: Done. Feb 11 23:26:38 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioGetState Feb 11 23:26:38 volumiopi4 sudo[3644]: pam_unix(sudo:session): session closed for user root Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 11 23:26:38 volumiopi4 sudo[3580]: pam_unix(sudo:session): session closed for user root Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 11 23:26:38 volumiopi4 sudo[3703]: pam_unix(sudo:session): session closed for user root Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: X display number found: Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: Volumio Kiosk started. Feb 11 23:26:38 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioGetState Feb 11 23:26:38 volumiopi4 volumio[1292]: info: Done. Feb 11 23:26:38 volumiopi4 volumio[1292]: info: CoreCommandRouter::volumioGetState Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:38 volumiopi4 startx[3729]: X.Org X Server 1.21.1.7 Feb 11 23:26:38 volumiopi4 startx[3729]: X Protocol Version 11, Revision 0 Feb 11 23:26:38 volumiopi4 startx[3729]: Current Operating System: Linux volumiopi4 6.12.47-v7l+ #1904 SMP Mon Sep 15 13:08:10 BST 2025 armv7l Feb 11 23:26:38 volumiopi4 startx[3729]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 snd_bcm2835.enable_hdmi=0 video=HDMI-A-1:1024x600M@60D numa=fake=2 system_heap.max_order=0 smsc95xx.macaddr=DC:A6:32:56:31:09 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=b0b466fc-4556-43af-9f97-ebe56fe4abe4 imgfile=/volumio_current.sqsh bootpart=UUID=0428-C7E4 datapart=UUID=82051e0e-a859-4f54-aa9f-0cc23a932af8 uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Feb 11 23:26:38 volumiopi4 startx[3729]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Feb 11 23:26:38 volumiopi4 startx[3729]: Current version of pixman: 0.44.0 Feb 11 23:26:38 volumiopi4 startx[3729]: Before reporting problems, check http://wiki.x.org Feb 11 23:26:38 volumiopi4 startx[3729]: to make sure that you have the latest version. Feb 11 23:26:38 volumiopi4 startx[3729]: Markers: (--) probed, (**) from config file, (==) default setting, Feb 11 23:26:38 volumiopi4 startx[3729]: (++) from command line, (!!) notice, (II) informational, Feb 11 23:26:38 volumiopi4 startx[3729]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Feb 11 23:26:38 volumiopi4 startx[3729]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Feb 11 23:26:38 2026 Feb 11 23:26:38 volumiopi4 startx[3729]: (==) Using config directory: "/etc/X11/xorg.conf.d" Feb 11 23:26:38 volumiopi4 startx[3729]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:38 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:39 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:39 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:39 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:39 volumiopi4 kernel: vc4-drm gpu: [drm] User-defined mode not supported: "1024x600": 60 49000 1024 1029 1042 1312 600 602 605 622 0x68 0x6 Feb 11 23:26:39 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:39 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:39 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:39 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:39 volumiopi4 kernel: vc4-drm gpu: [drm] User-defined mode not supported: "1024x600": 60 49000 1024 1029 1042 1312 600 602 605 622 0x68 0x6 Feb 11 23:26:39 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:39 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:39 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:39 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:39 volumiopi4 volumio[1292]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 11 23:26:39 volumiopi4 volumio[1292]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 11 23:26:39 volumiopi4 volumio[1292]: info: touch_display: X display number found: 0 Feb 11 23:26:39 volumiopi4 volumio[1292]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 23:26:39 volumiopi4 volumio[1292]: Error: connect EISCONN /tmp/.X11-unix/X0 Feb 11 23:26:39 volumiopi4 volumio[1292]: at PipeConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 11 23:26:39 volumiopi4 volumio[1292]: errno: -106, Feb 11 23:26:39 volumiopi4 volumio[1292]: code: 'EISCONN', Feb 11 23:26:39 volumiopi4 volumio[1292]: syscall: 'connect', Feb 11 23:26:39 volumiopi4 volumio[1292]: address: '/tmp/.X11-unix/X0' Feb 11 23:26:39 volumiopi4 volumio[1292]: } Feb 11 23:26:39 volumiopi4 volumio[1292]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 23:26:39 volumiopi4 sudo[3812]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-11 23:25' Feb 11 23:26:39 volumiopi4 sudo[3812]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"