Aug 31 15:15:07 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:07.989+07:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-30.427173ms timeout=10s Aug 31 15:15:08 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:08.000+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-30.427173ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=10.232263ms Aug 31 15:15:08 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:08.026+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-30.427173ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=35.378092ms Aug 31 15:15:08 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:08.027+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-30.427173ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=36.640801ms Aug 31 15:15:08 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:08.100+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-30.427173ms timeout=10s endpoint=https://www.googleapis.com duration=109.622018ms Aug 31 15:15:08 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:08.170+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-30.427173ms timeout=10s endpoint=https://google.com duration=179.790486ms Aug 31 15:15:08 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:08.184+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-30.427173ms timeout=10s endpoint=http://pushupdates.volumio.org duration=191.085849ms Aug 31 15:15:08 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:08.231+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-30.427173ms timeout=10s endpoint=https://securetoken.googleapis.com duration=240.773403ms Aug 31 15:15:08 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:08.234+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-30.427173ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=244.153627ms Aug 31 15:15:08 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:08.240+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-30.427173ms timeout=10s endpoint=https://functions.volumio.cloud duration=249.496737ms Aug 31 15:15:08 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:08.244+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-30.427173ms timeout=10s endpoint=https://functions.volumio.cloud duration=251.604067ms Aug 31 15:15:08 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:08.504+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-30.427173ms timeout=10s endpoint=https://database.volumio.cloud duration=511.52701ms Aug 31 15:15:08 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:08.666+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-30.427173ms timeout=10s endpoint=http://cddb.volumio.org duration=674.909724ms Aug 31 15:15:08 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:08.705+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-30.427173ms timeout=10s endpoint=http://plugins.volumio.org duration=712.922288ms Aug 31 15:15:10 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 15:15:10 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 15:15:10 volumio volumio[1251]: info: Discovery: Getting this device information Aug 31 15:15:10 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Aug 31 15:15:10 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:15:10 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 15:15:10 volumio volumio[1251]: verbose: New Socket.io Connection to 192.168.1.144:3000 from 192.168.1.123 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Aug 31 15:15:10 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 31 15:15:10 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 31 15:15:10 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:10.404+07:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-41.10487ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 31 15:15:10 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:10.417+07:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-41.727094ms timeout=10s Aug 31 15:15:10 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:10.427+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-41.727094ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=9.995988ms Aug 31 15:15:10 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:10.453+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-41.727094ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=35.28476ms Aug 31 15:15:10 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:10.454+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-41.727094ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=36.553932ms Aug 31 15:15:10 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:10.497+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-41.727094ms timeout=10s endpoint=https://google.com duration=80.484064ms Aug 31 15:15:10 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:10.530+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-41.727094ms timeout=10s endpoint=https://www.googleapis.com duration=112.303305ms Aug 31 15:15:10 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:10.609+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-41.727094ms timeout=10s endpoint=http://pushupdates.volumio.org duration=191.62977ms Aug 31 15:15:10 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:10.661+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-41.727094ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=243.789223ms Aug 31 15:15:10 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:10.663+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-41.727094ms timeout=10s endpoint=https://database.volumio.cloud duration=245.191282ms Aug 31 15:15:10 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:10.676+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-41.727094ms timeout=10s endpoint=https://functions.volumio.cloud duration=259.001916ms Aug 31 15:15:10 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:10.680+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-41.727094ms timeout=10s endpoint=https://securetoken.googleapis.com duration=262.822413ms Aug 31 15:15:10 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:10.834+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-41.727094ms timeout=10s endpoint=http://cddb.volumio.org duration=417.401144ms Aug 31 15:15:10 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:10.897+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-41.727094ms timeout=10s endpoint=https://functions.volumio.cloud duration=480.080322ms Aug 31 15:15:10 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:10.898+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" latency=-41.727094ms timeout=10s endpoint=http://plugins.volumio.org duration=480.158098ms Aug 31 15:15:10 volumio sudo[5962]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 15:15:10 volumio sudo[5962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 15:15:10 volumio sudo[5962]: pam_unix(sudo:session): session closed for user root Aug 31 15:15:10 volumio sudo[5964]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 15:15:10 volumio sudo[5964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 15:15:10 volumio sudo[5964]: pam_unix(sudo:session): session closed for user root Aug 31 15:15:10 volumio volumio[1251]: verbose: New Socket.io Connection to 192.168.1.144 from 192.168.1.123 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Aug 31 15:15:11 volumio sudo[5968]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 15:15:11 volumio sudo[5968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 15:15:11 volumio sudo[5968]: pam_unix(sudo:session): session closed for user root Aug 31 15:15:11 volumio sudo[5970]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 15:15:11 volumio sudo[5970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 15:15:11 volumio sudo[5970]: pam_unix(sudo:session): session closed for user root Aug 31 15:15:11 volumio volumio[1251]: verbose: New Socket.io Connection to 192.168.1.144 from 192.168.1.123 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Aug 31 15:15:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 15:15:11 volumio volumio[1251]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 15:15:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 15:15:11 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Aug 31 15:15:11 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:15:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 15:15:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 15:15:11 volumio volumio[1251]: info: Received Get System Info Aug 31 15:15:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 15:15:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 15:15:11 volumio volumio[1251]: info: Discovery: Getting this device information Aug 31 15:15:11 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Aug 31 15:15:11 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:15:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 15:15:11 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Aug 31 15:15:11 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:15:11 volumio volumio[1251]: info: Listing playlists Aug 31 15:15:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 15:15:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 15:15:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 15:15:12 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 31 15:15:13 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 15:15:13 volumio volumio[1251]: info: Received Get System Info Aug 31 15:15:13 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 15:15:13 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 15:15:13 volumio volumio[1251]: info: Discovery: Getting this device information Aug 31 15:15:13 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Aug 31 15:15:13 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:15:13 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 15:15:13 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 31 15:15:14 volumio volumio[1251]: info: CoreCommandRouter::volumioPause Aug 31 15:15:14 volumio volumio[1251]: info: CoreStateMachine::pause Aug 31 15:15:14 volumio volumio[1251]: info: CoreStateMachine::stPlaybackTimer Aug 31 15:15:14 volumio volumio[1251]: info: CoreStateMachine::servicePause Aug 31 15:15:14 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:15:14 volumio volumio[1251]: info: CoreCommandRouter::servicePause Aug 31 15:15:14 volumio volumio[1251]: info: ControllerMpd::pause Aug 31 15:15:14 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand pause Aug 31 15:15:14 volumio volumio[1251]: info: Aug 31 15:15:14 volumio volumio[1251]: ---------------------------- MPD announces state update: player Aug 31 15:15:14 volumio volumio[1251]: info: sendMpdCommand pause took 48 milliseconds Aug 31 15:15:14 volumio volumio[1251]: info: ControllerMpd::getState Aug 31 15:15:14 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:15:14 volumio volumio[1251]: info: sendMpdCommand status took 2 milliseconds Aug 31 15:15:14 volumio volumio[1251]: verbose: ControllerMpd::parseState Aug 31 15:15:14 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:15:14 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 15:15:14 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:15:14 volumio volumio[1251]: info: ControllerMpd::pushState Aug 31 15:15:14 volumio volumio[1251]: info: CoreCommandRouter::servicePushState Aug 31 15:15:14 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:15:14 volumio volumio[1251]: verbose: STATE SERVICE {"status":"play","position":0,"seek":151989,"duration":512,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2937 Kbps","isStreaming":false,"title":"Salute To Benny","artist":"Lester Young-Roy Eldridge-Harry Edison","album":"Laughin' To Keep From Cryin'","uri":"USB/KINGSTON/Lester Young - Laughin To Keep From Cryin' (1958^2000)[24bit-96kHz FLAC]/01 - Salute To Benny.flac","trackType":"flac"} Aug 31 15:15:14 volumio volumio[1251]: verbose: CURRENT POSITION 0 Aug 31 15:15:14 volumio volumio[1251]: info: CoreStateMachine::syncState stateService play Aug 31 15:15:14 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus pause Aug 31 15:15:14 volumio volumio[1251]: info: CoreStateMachine::pushState Aug 31 15:15:14 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:15:14 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 15:15:14 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState Aug 31 15:15:14 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:14.384+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" state=STATUS_PLAYING positionMs=268863 volume=86 Aug 31 15:15:14 volumio volumio5-onboarding[1551]: time=2026-08-31T15:15:14.384+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" id="mnt/USB/KINGSTON/Lester Young - Laughin To Keep From Cryin' (1958^2000)[24bit-96kHz FLAC]/01 - Salute To Benny.flac" title="Salute To Benny" Aug 31 15:15:14 volumio volumio[1251]: info: ------------------------------ 17ms Aug 31 15:15:14 volumio volumio[1251]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 31 15:15:14 volumio sudo[5975]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 31 15:15:14 volumio sudo[5975]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 15:15:14 volumio sudo[5975]: pam_unix(sudo:session): session closed for user root Aug 31 15:15:14 volumio volumio[1251]: info: peppymeterbasic Daemon Started Aug 31 15:15:16 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 15:15:16 volumio volumio[1251]: info: Received Get System Info Aug 31 15:15:16 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 15:15:16 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 15:15:16 volumio volumio[1251]: info: Discovery: Getting this device information Aug 31 15:15:16 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Aug 31 15:15:16 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:15:16 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 15:15:16 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 15:15:16 volumio volumio[1251]: info: Received Get System Info Aug 31 15:15:16 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 15:15:16 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 15:15:16 volumio volumio[1251]: info: Discovery: Getting this device information Aug 31 15:15:16 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Aug 31 15:15:16 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:15:16 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 15:15:17 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 15:15:17 volumio volumio[1251]: info: Received Get System Info Aug 31 15:15:17 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 15:15:17 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 15:15:17 volumio volumio[1251]: info: Discovery: Getting this device information Aug 31 15:15:17 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Aug 31 15:15:17 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:15:17 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 15:15:19 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 15:15:20 volumio volumio[1251]: info: Received Get System Info Aug 31 15:15:20 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 15:15:20 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 15:15:20 volumio volumio[1251]: info: Discovery: Getting this device information Aug 31 15:15:20 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Aug 31 15:15:20 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:15:20 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 15:15:21 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 31 15:15:30 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 15:15:30 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 31 15:15:30 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 15:15:30 volumio volumio[1251]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 31 15:15:30 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 31 15:15:30 volumio volumio[1251]: info: Received Get System Version Aug 31 15:15:30 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 15:15:30 volumio volumio[1251]: info: Received Get System Info Aug 31 15:15:30 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 15:15:30 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 15:15:30 volumio volumio[1251]: info: Discovery: Getting this device information Aug 31 15:15:30 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Aug 31 15:15:30 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:15:30 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 15:15:39 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 15:15:39 volumio volumio[1251]: info: touch_display: About to detect HDMI ports... Aug 31 15:15:39 volumio volumio[1251]: info: touch_display: Detected HDMI ports: HDMI 0 (HDMI-1), HDMI 1 (HDMI-2) Aug 31 15:15:54 volumio volumio[1251]: info: CALLMETHOD: user_interface touch_display saveOrientationConf [object Object] Aug 31 15:15:54 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveOrientationConf Aug 31 15:15:54 volumio sudo[6049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Aug 31 15:15:54 volumio sudo[6049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 15:15:54 volumio sudo[6049]: pam_unix(sudo:session): session closed for user root Aug 31 15:15:54 volumio volumio[1251]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Aug 31 15:15:54 volumio volumio[1251]: info: touch_display: Rotation settings written to /etc/X11/xorg.conf.d/95-touch_display-plugin.conf. Aug 31 15:15:54 volumio sudo[6052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Aug 31 15:15:54 volumio sudo[6052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 15:15:54 volumio systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Aug 31 15:15:54 volumio volumio[5855]: X connection to :0 broken (explicit kill or server shutdown). Aug 31 15:15:54 volumio startx[1720]: xinit: connection to X server lost Aug 31 15:15:54 volumio startx[1720]: Aug 31 15:15:54 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=exited, status=1/FAILURE Aug 31 15:15:54 volumio systemd[1]: peppymeterbasic.service: Failed with result 'exit-code'. Aug 31 15:15:54 volumio systemd[1]: peppymeterbasic.service: Consumed 5.860s CPU time. Aug 31 15:16:04 volumio volumio[1251]: info: CoreCommandRouter::volumioPause Aug 31 15:16:04 volumio volumio[1251]: info: CoreStateMachine::pause Aug 31 15:16:04 volumio volumio[1251]: info: CoreStateMachine::stPlaybackTimer Aug 31 15:16:04 volumio volumio[1251]: info: CoreStateMachine::servicePause Aug 31 15:16:04 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:16:04 volumio volumio[1251]: info: CoreCommandRouter::servicePause Aug 31 15:16:04 volumio volumio[1251]: info: ControllerMpd::pause Aug 31 15:16:04 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand pause Aug 31 15:16:04 volumio volumio[1251]: info: Aug 31 15:16:04 volumio volumio[1251]: ---------------------------- MPD announces state update: player Aug 31 15:16:04 volumio volumio[1251]: info: sendMpdCommand pause took 2 milliseconds Aug 31 15:16:04 volumio volumio[1251]: info: ControllerMpd::getState Aug 31 15:16:04 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:16:04 volumio volumio[1251]: info: sendMpdCommand status took 0 milliseconds Aug 31 15:16:04 volumio volumio[1251]: verbose: ControllerMpd::parseState Aug 31 15:16:04 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:16:04 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 31 15:16:04 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:16:04 volumio volumio[1251]: info: ControllerMpd::pushState Aug 31 15:16:04 volumio volumio[1251]: info: CoreCommandRouter::servicePushState Aug 31 15:16:04 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:16:04 volumio volumio[1251]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":202241,"duration":512,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2992 Kbps","isStreaming":false,"title":"Salute To Benny","artist":"Lester Young-Roy Eldridge-Harry Edison","album":"Laughin' To Keep From Cryin'","uri":"USB/KINGSTON/Lester Young - Laughin To Keep From Cryin' (1958^2000)[24bit-96kHz FLAC]/01 - Salute To Benny.flac","trackType":"flac"} Aug 31 15:16:04 volumio volumio[1251]: verbose: CURRENT POSITION 0 Aug 31 15:16:04 volumio volumio[1251]: info: CoreStateMachine::syncState stateService pause Aug 31 15:16:04 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus pause Aug 31 15:16:04 volumio volumio[1251]: info: CoreStateMachine::pushState Aug 31 15:16:04 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:16:04 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 15:16:04 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState Aug 31 15:16:04 volumio volumio[1251]: info: CoreStateMachine::stPlaybackTimer Aug 31 15:16:04 volumio volumio[1251]: info: ------------------------------ 10ms Aug 31 15:16:04 volumio volumio5-onboarding[1551]: time=2026-08-31T15:16:04.554+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" state=STATUS_PAUSED positionMs=269114 volume=86 Aug 31 15:16:04 volumio volumio5-onboarding[1551]: time=2026-08-31T15:16:04.554+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" id="mnt/USB/KINGSTON/Lester Young - Laughin To Keep From Cryin' (1958^2000)[24bit-96kHz FLAC]/01 - Salute To Benny.flac" title="Salute To Benny" Aug 31 15:16:04 volumio volumio[1251]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 31 15:16:04 volumio sudo[6072]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 31 15:16:04 volumio sudo[6072]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 15:16:04 volumio sudo[6072]: pam_unix(sudo:session): session closed for user root Aug 31 15:16:04 volumio volumio[1251]: info: peppymeterbasic Daemon Stop Aug 31 15:16:04 volumio volumio[1251]: error: touch_display: Error setting screensaver timeout: Error: Command failed: /usr/bin/xset -display :0 s off +dpms dpms 0 0 120 Aug 31 15:16:04 volumio volumio[1251]: /usr/bin/xset: unable to open display ":0" Aug 31 15:16:04 volumio kernel: vc4-drm gpu: [drm] *ERROR* [CRTC:100:pixelvalve-2] flip_done timed out Aug 31 15:16:05 volumio startx[1720]: waiting for X server to shut down .......... Aug 31 15:16:05 volumio startx[1720]: xinit: X server slow to shut down, sending KILL signal Aug 31 15:16:05 volumio startx[1720]: Aug 31 15:16:05 volumio volumio[1251]: info: CoreCommandRouter::volumioPlay Aug 31 15:16:05 volumio volumio[1251]: info: CoreStateMachine::play index undefined Aug 31 15:16:05 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 15:16:05 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:16:05 volumio volumio[1251]: info: CoreStateMachine::startPlaybackTimer Aug 31 15:16:05 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:16:05 volumio volumio[1251]: info: ControllerMpd::resume Aug 31 15:16:05 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand play Aug 31 15:16:05 volumio volumio[1251]: info: Aug 31 15:16:05 volumio volumio[1251]: ---------------------------- MPD announces state update: player Aug 31 15:16:05 volumio volumio[1251]: info: sendMpdCommand play took 45 milliseconds Aug 31 15:16:05 volumio volumio[1251]: info: ControllerMpd::getState Aug 31 15:16:05 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:16:05 volumio volumio[1251]: info: sendMpdCommand status took 1 milliseconds Aug 31 15:16:05 volumio volumio[1251]: verbose: ControllerMpd::parseState Aug 31 15:16:05 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:16:05 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 15:16:05 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:16:05 volumio volumio[1251]: info: ControllerMpd::pushState Aug 31 15:16:05 volumio volumio[1251]: info: CoreCommandRouter::servicePushState Aug 31 15:16:05 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:16:05 volumio volumio[1251]: verbose: STATE SERVICE {"status":"play","position":0,"seek":202668,"duration":512,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3076 Kbps","isStreaming":false,"title":"Salute To Benny","artist":"Lester Young-Roy Eldridge-Harry Edison","album":"Laughin' To Keep From Cryin'","uri":"USB/KINGSTON/Lester Young - Laughin To Keep From Cryin' (1958^2000)[24bit-96kHz FLAC]/01 - Salute To Benny.flac","trackType":"flac"} Aug 31 15:16:05 volumio volumio[1251]: verbose: CURRENT POSITION 0 Aug 31 15:16:05 volumio volumio[1251]: info: CoreStateMachine::syncState stateService play Aug 31 15:16:05 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus pause Aug 31 15:16:05 volumio volumio[1251]: info: CoreStateMachine::pushState Aug 31 15:16:05 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:16:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 15:16:05 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState Aug 31 15:16:05 volumio volumio5-onboarding[1551]: time=2026-08-31T15:16:05.859+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" state=STATUS_PLAYING positionMs=269114 volume=86 Aug 31 15:16:05 volumio volumio5-onboarding[1551]: time=2026-08-31T15:16:05.860+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" id="mnt/USB/KINGSTON/Lester Young - Laughin To Keep From Cryin' (1958^2000)[24bit-96kHz FLAC]/01 - Salute To Benny.flac" title="Salute To Benny" Aug 31 15:16:05 volumio volumio[1251]: info: ------------------------------ 11ms Aug 31 15:16:05 volumio volumio[1251]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 31 15:16:05 volumio volumio[1251]: error: touch_display: Error waking up the screen: Error: Command failed: /usr/bin/xset -display :0 s reset dpms force on Aug 31 15:16:05 volumio volumio[1251]: /usr/bin/xset: unable to open display ":0" Aug 31 15:16:05 volumio volumio[1251]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X0' Aug 31 15:16:05 volumio sudo[6080]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 31 15:16:05 volumio sudo[6080]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 15:16:06 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Aug 31 15:16:06 volumio sudo[6080]: pam_unix(sudo:session): session closed for user root Aug 31 15:16:06 volumio volumio[1251]: info: peppymeterbasic Daemon Started Aug 31 15:16:06 volumio volumio[6084]: error: XDG_RUNTIME_DIR is invalid or not set in the environment. Aug 31 15:16:09 volumio startx[1720]: waiting for server to die ... Aug 31 15:16:09 volumio startx[1720]: xinit: X server refuses to die Aug 31 15:16:09 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Aug 31 15:16:12 volumio volumio[1251]: info: CALLMETHOD: user_interface touch_display saveOrientationConf [object Object] Aug 31 15:16:12 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveOrientationConf Aug 31 15:16:12 volumio sudo[6112]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Aug 31 15:16:12 volumio sudo[6112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 15:16:12 volumio sudo[6112]: pam_unix(sudo:session): session closed for user root Aug 31 15:16:12 volumio volumio[1251]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Aug 31 15:16:12 volumio volumio[1251]: info: touch_display: Rotation settings written to /etc/X11/xorg.conf.d/95-touch_display-plugin.conf. Aug 31 15:16:12 volumio sudo[6115]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Aug 31 15:16:12 volumio sudo[6115]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 15:16:13 volumio volumio[1251]: info: CALLMETHOD: user_interface touch_display saveOrientationConf [object Object] Aug 31 15:16:13 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveOrientationConf Aug 31 15:16:15 volumio kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Aug 31 15:16:15 volumio kernel: vc4-drm gpu: [drm] *ERROR* [CRTC:100:pixelvalve-2] commit wait timed out Aug 31 15:16:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPause Aug 31 15:16:21 volumio volumio[1251]: info: CoreStateMachine::pause Aug 31 15:16:21 volumio volumio[1251]: info: CoreStateMachine::stPlaybackTimer Aug 31 15:16:21 volumio volumio[1251]: info: CoreStateMachine::servicePause Aug 31 15:16:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:16:21 volumio volumio[1251]: info: CoreCommandRouter::servicePause Aug 31 15:16:21 volumio volumio[1251]: info: ControllerMpd::pause Aug 31 15:16:21 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand pause Aug 31 15:16:21 volumio volumio[1251]: info: Aug 31 15:16:21 volumio volumio[1251]: ---------------------------- MPD announces state update: player Aug 31 15:16:21 volumio volumio[1251]: info: sendMpdCommand pause took 1 milliseconds Aug 31 15:16:21 volumio volumio[1251]: info: ControllerMpd::getState Aug 31 15:16:21 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:16:21 volumio volumio[1251]: info: sendMpdCommand status took 1 milliseconds Aug 31 15:16:21 volumio volumio[1251]: verbose: ControllerMpd::parseState Aug 31 15:16:21 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:16:21 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 15:16:21 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:16:21 volumio volumio[1251]: info: ControllerMpd::pushState Aug 31 15:16:21 volumio volumio[1251]: info: CoreCommandRouter::servicePushState Aug 31 15:16:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:16:21 volumio volumio[1251]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":218796,"duration":512,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3042 Kbps","isStreaming":false,"title":"Salute To Benny","artist":"Lester Young-Roy Eldridge-Harry Edison","album":"Laughin' To Keep From Cryin'","uri":"USB/KINGSTON/Lester Young - Laughin To Keep From Cryin' (1958^2000)[24bit-96kHz FLAC]/01 - Salute To Benny.flac","trackType":"flac"} Aug 31 15:16:21 volumio volumio[1251]: verbose: CURRENT POSITION 0 Aug 31 15:16:21 volumio volumio[1251]: info: CoreStateMachine::syncState stateService pause Aug 31 15:16:21 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus pause Aug 31 15:16:21 volumio volumio[1251]: info: CoreStateMachine::pushState Aug 31 15:16:21 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:16:21 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 15:16:21 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState Aug 31 15:16:21 volumio volumio[1251]: info: CoreStateMachine::stPlaybackTimer Aug 31 15:16:21 volumio volumio5-onboarding[1551]: time=2026-08-31T15:16:21.794+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" state=STATUS_PAUSED positionMs=284882 volume=86 Aug 31 15:16:21 volumio volumio5-onboarding[1551]: time=2026-08-31T15:16:21.795+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" id="mnt/USB/KINGSTON/Lester Young - Laughin To Keep From Cryin' (1958^2000)[24bit-96kHz FLAC]/01 - Salute To Benny.flac" title="Salute To Benny" Aug 31 15:16:21 volumio volumio[1251]: info: ------------------------------ 8ms Aug 31 15:16:21 volumio volumio[1251]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 31 15:16:21 volumio volumio[1251]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X0' Aug 31 15:16:21 volumio sudo[6133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 31 15:16:21 volumio sudo[6133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 15:16:21 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Aug 31 15:16:22 volumio volumio[1251]: info: CoreCommandRouter::volumioPlay Aug 31 15:16:22 volumio volumio[1251]: info: CoreStateMachine::play index undefined Aug 31 15:16:22 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 15:16:22 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:16:22 volumio volumio[1251]: info: CoreStateMachine::startPlaybackTimer Aug 31 15:16:22 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:16:22 volumio volumio[1251]: info: ControllerMpd::resume Aug 31 15:16:22 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand play Aug 31 15:16:22 volumio volumio[1251]: info: sendMpdCommand play took 44 milliseconds Aug 31 15:16:22 volumio volumio[1251]: info: Aug 31 15:16:22 volumio volumio[1251]: ---------------------------- MPD announces state update: player Aug 31 15:16:22 volumio volumio[1251]: info: ControllerMpd::getState Aug 31 15:16:22 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status Aug 31 15:16:22 volumio volumio[1251]: info: sendMpdCommand status took 2 milliseconds Aug 31 15:16:22 volumio volumio[1251]: verbose: ControllerMpd::parseState Aug 31 15:16:22 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 15:16:22 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 15:16:22 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo Aug 31 15:16:22 volumio volumio[1251]: info: ControllerMpd::pushState Aug 31 15:16:22 volumio volumio[1251]: info: CoreCommandRouter::servicePushState Aug 31 15:16:22 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:16:22 volumio volumio[1251]: verbose: STATE SERVICE {"status":"play","position":0,"seek":219306,"duration":512,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3135 Kbps","isStreaming":false,"title":"Salute To Benny","artist":"Lester Young-Roy Eldridge-Harry Edison","album":"Laughin' To Keep From Cryin'","uri":"USB/KINGSTON/Lester Young - Laughin To Keep From Cryin' (1958^2000)[24bit-96kHz FLAC]/01 - Salute To Benny.flac","trackType":"flac"} Aug 31 15:16:22 volumio volumio[1251]: verbose: CURRENT POSITION 0 Aug 31 15:16:22 volumio volumio[1251]: info: CoreStateMachine::syncState stateService play Aug 31 15:16:22 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus pause Aug 31 15:16:22 volumio volumio[1251]: info: CoreStateMachine::pushState Aug 31 15:16:22 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Aug 31 15:16:22 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 15:16:22 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState Aug 31 15:16:22 volumio volumio5-onboarding[1551]: time=2026-08-31T15:16:22.813+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" state=STATUS_PLAYING positionMs=285133 volume=86 Aug 31 15:16:22 volumio volumio[1251]: info: ------------------------------ 14ms Aug 31 15:16:22 volumio volumio5-onboarding[1551]: time=2026-08-31T15:16:22.814+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.123:59245,00:00:00:00:00:00%01 @ 0x2e51620" id="mnt/USB/KINGSTON/Lester Young - Laughin To Keep From Cryin' (1958^2000)[24bit-96kHz FLAC]/01 - Salute To Benny.flac" title="Salute To Benny" Aug 31 15:16:22 volumio volumio[1251]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 31 15:16:22 volumio volumio[1251]: error: touch_display: Error waking up the screen: Error: Command failed: /usr/bin/xset -display :0 s reset dpms force on Aug 31 15:16:22 volumio volumio[1251]: /usr/bin/xset: unable to open display ":0" Aug 31 15:16:22 volumio volumio[1251]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X0' Aug 31 15:16:22 volumio sudo[6139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 31 15:16:22 volumio sudo[6139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 15:16:22 volumio systemd[1]: peppymeterbasic.service: State 'final-sigterm' timed out. Killing. Aug 31 15:16:22 volumio systemd[1]: peppymeterbasic.service: Killing process 6084 (python3) with signal SIGKILL. Aug 31 15:16:22 volumio sudo[6133]: pam_unix(sudo:session): session closed for user root Aug 31 15:16:22 volumio volumio[1251]: info: PeppyMeterBasic ---peppymeterbasic failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppymeterbasic.service Aug 31 15:16:22 volumio volumio[1251]: Job for peppymeterbasic.service canceled. Aug 31 15:16:24 volumio systemd[1]: peppymeterbasic.service: Processes still around after final SIGKILL. Entering failed mode. Aug 31 15:16:24 volumio systemd[1]: peppymeterbasic.service: Failed with result 'timeout'. Aug 31 15:16:24 volumio systemd[1]: peppymeterbasic.service: Unit process 6084 (python3) remains running after unit stopped. Aug 31 15:16:24 volumio systemd[1]: peppymeterbasic.service: Found left-over process 6084 (python3) in control group while starting unit. Ignoring. Aug 31 15:16:24 volumio systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies. Aug 31 15:16:24 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Aug 31 15:16:24 volumio sudo[6139]: pam_unix(sudo:session): session closed for user root Aug 31 15:16:24 volumio volumio[1251]: info: peppymeterbasic Daemon Started Aug 31 15:16:24 volumio volumio[6143]: error: XDG_RUNTIME_DIR is invalid or not set in the environment. Aug 31 15:16:25 volumio kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Aug 31 15:16:25 volumio kernel: vc4-drm gpu: [drm] *ERROR* [PLANE:89:plane-3] commit wait timed out Aug 31 15:16:29 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 15:16:29 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 31 15:16:35 volumio kernel: vc4-drm gpu: [drm] *ERROR* flip_done timed out Aug 31 15:16:35 volumio kernel: vc4-drm gpu: [drm] *ERROR* Timed out waiting for commit Aug 31 15:16:35 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Aug 31 15:16:35 volumio systemd[1]: Stopped volumio-kiosk.service - Volumio Kiosk. Aug 31 15:16:35 volumio systemd[1]: volumio-kiosk.service: Consumed 53.396s CPU time. Aug 31 15:16:35 volumio sudo[6052]: pam_unix(sudo:session): session closed for user root Aug 31 15:16:35 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Aug 31 15:16:35 volumio volumio[1251]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Aug 31 15:16:35 volumio volumio[1251]: info: touch_display: Volumio Kiosk restarted. Aug 31 15:16:35 volumio sudo[6115]: pam_unix(sudo:session): session closed for user root Aug 31 15:16:35 volumio volumio[1251]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Aug 31 15:16:35 volumio volumio[1251]: info: touch_display: Volumio Kiosk restarted. Aug 31 15:16:35 volumio volumio[1251]: info: touch_display: X display number found: 0 Aug 31 15:16:35 volumio startx[6189]: X.Org X Server 1.21.1.7 Aug 31 15:16:35 volumio startx[6189]: X Protocol Version 11, Revision 0 Aug 31 15:16:35 volumio startx[6189]: Current Operating System: Linux volumio 6.12.74-v7l+ #1948 SMP Mon Mar 2 11:27:49 GMT 2026 armv7l Aug 31 15:16:35 volumio startx[6189]: 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:640x480M@60D video=HDMI-A-2:1920x1080M@60,margin_left=48,margin_right=48,margin_top=48,margin_bottom=48 numa=fake=2 system_heap.max_order=0 iommu_dma_numa_policy=interleave smsc95xx.macaddr=E4:5F:01:6D:9D:C2 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=9caf6474-168f-4972-add4-ace36a467bc2 imgfile=/volumio_current.sqsh bootpart=UUID=807D-2966 datapart=UUID=99a33626-3e63-4955-bdae-c467a228871b 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 Aug 31 15:16:35 volumio startx[6189]: xorg-server 2:21.1.7-3+rpt3+deb12u13 (https://www.debian.org/support) Aug 31 15:16:35 volumio startx[6189]: Current version of pixman: 0.44.0 Aug 31 15:16:35 volumio startx[6189]: Before reporting problems, check http://wiki.x.org Aug 31 15:16:35 volumio startx[6189]: to make sure that you have the latest version. Aug 31 15:16:35 volumio startx[6189]: Markers: (--) probed, (**) from config file, (==) default setting, Aug 31 15:16:35 volumio startx[6189]: (++) from command line, (!!) notice, (II) informational, Aug 31 15:16:35 volumio startx[6189]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Aug 31 15:16:35 volumio startx[6189]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Aug 31 15:16:35 2026 Aug 31 15:16:35 volumio volumio[1251]: info: touch_display: X display number found: 0 Aug 31 15:16:35 volumio startx[6189]: (==) Using config directory: "/etc/X11/xorg.conf.d" Aug 31 15:16:35 volumio startx[6189]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Aug 31 15:16:35 volumio volumio[1251]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 31 15:16:35 volumio volumio[1251]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 31 15:16:36 volumio volumio[1251]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 15:16:36 volumio volumio[1251]: Error: connect EISCONN /tmp/.X11-unix/X0 Aug 31 15:16:36 volumio volumio[1251]: at PipeConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 31 15:16:36 volumio volumio[1251]: errno: -106, Aug 31 15:16:36 volumio volumio[1251]: code: 'EISCONN', Aug 31 15:16:36 volumio volumio[1251]: syscall: 'connect', Aug 31 15:16:36 volumio volumio[1251]: address: '/tmp/.X11-unix/X0' Aug 31 15:16:36 volumio volumio[1251]: } Aug 31 15:16:36 volumio volumio[1251]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 15:16:36 volumio volumio[6143]: libpng warning: iCCP: known incorrect sRGB profile Aug 31 15:16:36 volumio volumio[6143]: libpng warning: iCCP: known incorrect sRGB profile Aug 31 15:16:36 volumio volumio[6143]: libpng warning: iCCP: known incorrect sRGB profile Aug 31 15:16:36 volumio sudo[6215]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 15:15' Aug 31 15:16:36 volumio sudo[6215]: 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"