Oct 11 15:09:02 volumio kernel: vc4-drm soc:gpu: [drm] *ERROR* [CRTC:95:pixelvalve-2] flip_done timed out
Oct 11 15:09:05 volumio volumio[2745]: info: Creating directories
Oct 11 15:09:05 volumio volumio[2745]: info: Creating data directory
Oct 11 15:09:05 volumio volumio5-onboarding[1699]: time=2026-10-11T07:09:05.684Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Oct 11 15:09:05 volumio volumio5-onboarding[1699]: time=2026-10-11T07:09:05.689Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Oct 11 15:09:05 volumio volumio[2745]: info: Creating Start Script
Oct 11 15:09:05 volumio volumio5-onboarding[1699]: time=2026-10-11T07:09:05.696Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Oct 11 15:09:05 volumio systemd[1]: Reloading.
Oct 11 15:09:06 volumio kernel: hwmon hwmon1: Undervoltage detected!
Oct 11 15:09:08 volumio sudo[8599]: pam_unix(sudo:session): session closed for user root
Oct 11 15:09:08 volumio volumio5-onboarding[1699]: time=2026-10-11T07:09:08.566Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Oct 11 15:09:08 volumio volumio[2745]: Plugin install end detected on script
Oct 11 15:09:08 volumio volumio[2745]: info: Install script completed
Oct 11 15:09:08 volumio volumio[2745]: info: Adding reference to registry
Oct 11 15:09:08 volumio volumio5-onboarding[1699]: time=2026-10-11T07:09:08.586Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Oct 11 15:09:08 volumio volumio[2745]: info: Done installing plugin.
Oct 11 15:09:08 volumio volumio[2745]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 11 15:09:08 volumio volumio[2745]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Oct 11 15:09:08 volumio volumio[2745]: info: Folder /tmp/plugins removed
Oct 11 15:09:08 volumio volumio[2745]: info: Folder /tmp/downloaded_plugin.zip removed
Oct 11 15:09:08 volumio volumio[2745]: info: Folder /data/temp removed
Oct 11 15:09:10 volumio kernel: hwmon hwmon1: Voltage normalised
Oct 11 15:09:12 volumio kernel: vc4-drm soc:gpu: [drm] *ERROR* flip_done timed out
Oct 11 15:09:12 volumio kernel: vc4-drm soc:gpu: [drm] *ERROR* [CRTC:95:pixelvalve-2] commit wait timed out
Oct 11 15:09:22 volumio kernel: vc4-drm soc:gpu: [drm] *ERROR* flip_done timed out
Oct 11 15:09:22 volumio kernel: vc4-drm soc:gpu: [drm] *ERROR* [CONNECTOR:33:HDMI-A-1] commit wait timed out
Oct 11 15:09:26 volumio volumio[2745]: info: Enabling plugin spop
Oct 11 15:09:26 volumio volumio[2745]: info: Loading plugin "spop"...
Oct 11 15:09:27 volumio volumio[2745]: info: PLUGIN START: spop
Oct 11 15:09:27 volumio volumio[2745]: info: Creating Spotify config file
Oct 11 15:09:27 volumio volumio[2745]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 15:09:27 volumio volumio[2745]: info: Done.
Oct 11 15:09:27 volumio volumio[2745]: info: Spotify config file written
Oct 11 15:09:27 volumio volumio[2745]: info: No need to fix Spotify hosts
Oct 11 15:09:27 volumio sudo[8813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Oct 11 15:09:27 volumio sudo[8813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Oct 11 15:09:27 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Oct 11 15:09:27 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Oct 11 15:09:27 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Oct 11 15:09:27 volumio go-librespot[8815]: go-librespot daemon starting...
Oct 11 15:09:27 volumio sudo[8813]: pam_unix(sudo:session): session closed for user root
Oct 11 15:09:27 volumio kernel: hwmon hwmon1: Undervoltage detected!
Oct 11 15:09:27 volumio go-librespot[8816]: time="2026-10-11T15:09:27+08:00" level=info msg="running go-librespot 0.9.1"
Oct 11 15:09:27 volumio go-librespot[8816]: time="2026-10-11T15:09:27+08:00" level=info msg="api server listening on 127.0.0.1:9879"
Oct 11 15:09:27 volumio go-librespot[8816]: time="2026-10-11T15:09:27+08:00" level=debug msg="no app state found"
Oct 11 15:09:27 volumio go-librespot[8816]: time="2026-10-11T15:09:27+08:00" level=debug msg="stored credentials not found"
Oct 11 15:09:27 volumio go-librespot[8816]: time="2026-10-11T15:09:27+08:00" level=info msg="generated new device id: c3d7b3b5db9c4340e3679dbecff6060afbcd7e71"
Oct 11 15:09:27 volumio go-librespot[8816]: time="2026-10-11T15:09:27+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]"
Oct 11 15:09:27 volumio go-librespot[8816]: time="2026-10-11T15:09:27+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Oct 11 15:09:27 volumio go-librespot[8816]: time="2026-10-11T15:09:27+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Oct 11 15:09:27 volumio go-librespot[8816]: time="2026-10-11T15:09:27+08:00" level=info msg="zeroconf server listening on port 42331"
Oct 11 15:09:27 volumio go-librespot[8816]: time="2026-10-11T15:09:27+08:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration"
Oct 11 15:09:28 volumio go-librespot[8816]: time="2026-10-11T15:09:28+08:00" level=debug msg="avahi service \"Volumio\" established"
Oct 11 15:09:30 volumio volumio[2745]: info: go-librespot daemon successfully initialized
Oct 11 15:09:31 volumio kernel: hwmon hwmon1: Voltage normalised
Oct 11 15:09:32 volumio kernel: vc4-drm soc:gpu: [drm] *ERROR* flip_done timed out
Oct 11 15:09:32 volumio kernel: vc4-drm soc:gpu: [drm] *ERROR* [PLANE:84:plane-3] commit wait timed out
Oct 11 15:09:33 volumio volumio[2745]: info: Initializing connection to go-librespot Websocket
Oct 11 15:09:33 volumio go-librespot[8816]: time="2026-10-11T15:09:33+08:00" level=debug msg="new websocket client"
Oct 11 15:09:33 volumio volumio[2745]: info: Connection to go-librespot Websocket established
Oct 11 15:09:36 volumio volumio[2745]: info: Getting Spotify volume
Oct 11 15:09:36 volumio volumio[2745]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Oct 11 15:09:36 volumio volumio[2745]: info: CoreCommandRouter::volumioGetState
Oct 11 15:09:36 volumio volumio[2745]: info: CorePlayQueue::getTrack 0
Oct 11 15:09:36 volumio volumio[2745]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86
Oct 11 15:09:36 volumio volumio[2745]: SPOTIFY: SPOTIFY VOLUME undefined
Oct 11 15:09:36 volumio volumio[2745]: SPOTIFY: VOLUMIO VOLUME 86
Oct 11 15:09:36 volumio volumio[2745]: info: Aligning Spotify Volume to Volumio Volume
Oct 11 15:09:36 volumio volumio[2745]: info: CoreCommandRouter::volumioGetState
Oct 11 15:09:36 volumio volumio[2745]: info: CorePlayQueue::getTrack 0
Oct 11 15:09:36 volumio volumio[2745]: info: Setting Spotify Volume from Volumio: 86
Oct 11 15:09:38 volumio volumio[2745]: SPOTIFY: SETTING SPOTIFY VOLUME 86
Oct 11 15:09:38 volumio volumio[2745]: info: Sending Spotify command with payload to local API: /player/volume
Oct 11 15:09:39 volumio volumio[2745]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 11 15:09:39 volumio volumio[2745]: info: touch_display: About to detect HDMI ports...
Oct 11 15:09:43 volumio kernel: vc4-drm soc:gpu: [drm] *ERROR* flip_done timed out
Oct 11 15:09:43 volumio kernel: vc4-drm soc:gpu: [drm] *ERROR* Timed out waiting for commit
Oct 11 15:09:53 volumio kernel: vc4-drm soc:gpu: [drm] *ERROR* [CRTC:95:pixelvalve-2] flip_done timed out
Oct 11 15:09:55 volumio volumio[2745]: info: CoreCommandRouter::volumioGetState
Oct 11 15:09:55 volumio volumio[2745]: info: CorePlayQueue::getTrack 0
Oct 11 15:10:00 volumio volumio[2745]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 11 15:10:00 volumio volumio[2745]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Oct 11 15:10:04 volumio kernel: vc4-drm soc:gpu: [drm] *ERROR* flip_done timed out
Oct 11 15:10:04 volumio kernel: vc4-drm soc:gpu: [drm] *ERROR* Timed out waiting for commit
Oct 11 15:10:08 volumio sudo[8907]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf
Oct 11 15:10:08 volumio sudo[8907]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Oct 11 15:10:08 volumio sudo[8907]: pam_unix(sudo:session): session closed for user root
Oct 11 15:10:08 volumio sudo[8909]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service
Oct 11 15:10:08 volumio sudo[8909]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Oct 11 15:10:08 volumio sudo[8911]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service
Oct 11 15:10:08 volumio sudo[8911]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Oct 11 15:10:08 volumio volumio[2745]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set.
Oct 11 15:10:08 volumio volumio[2745]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 11 15:10:08 volumio sudo[8914]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service
Oct 11 15:10:08 volumio sudo[8914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Oct 11 15:10:08 volumio volumio[2745]: TypeError: Cannot read properties of undefined (reading 'split')
Oct 11 15:10:08 volumio volumio[2745]: at /data/plugins/user_interface/touch_display/index.js:1298:37
Oct 11 15:10:08 volumio volumio[2745]: at ChildProcess.exithandler (node:child_process:413:7)
Oct 11 15:10:08 volumio volumio[2745]: at ChildProcess.emit (node:events:514:28)
Oct 11 15:10:08 volumio volumio[2745]: at maybeClose (node:internal/child_process:1105:16)
Oct 11 15:10:08 volumio volumio[2745]: at ChildProcess._handle.onexit (node:internal/child_process:305:5)
Oct 11 15:10:08 volumio volumio[2745]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 11 15:10:08 volumio systemd[1]: Reloading.
Oct 11 15:10:09 volumio kernel: hwmon hwmon1: Undervoltage detected!
Oct 11 15:10:10 volumio sudo[8951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-11 15:09'
Oct 11 15:10:10 volumio sudo[8951]: 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"