Dec 08 21:02:04 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 08 21:02:04 volumio volumio[1245]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 08 21:02:04 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 08 21:02:04 volumio volumio[1245]: info: Received Get System Version Dec 08 21:02:04 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 08 21:02:04 volumio volumio[1245]: info: Received Get System Info Dec 08 21:02:04 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 08 21:02:04 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 08 21:02:04 volumio volumio[1245]: info: Discovery: Getting this device information Dec 08 21:02:04 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Dec 08 21:02:04 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 08 21:02:04 volumio go-librespot[3074]: time="2025-12-08T21:02:04+01:00" level=trace msg="received accesspoint ping" Dec 08 21:02:04 volumio go-librespot[3074]: time="2025-12-08T21:02:04+01:00" level=trace msg="received accesspoint pong ack" Dec 08 21:02:05 volumio go-librespot[3074]: time="2025-12-08T21:02:05+01:00" level=trace msg="sent dealer ping" Dec 08 21:02:05 volumio go-librespot[3074]: time="2025-12-08T21:02:05+01:00" level=trace msg="received dealer pong" Dec 08 21:02:22 volumio thd[4221]: {"time":1765224098412,"response":"volume Success"} Dec 08 21:02:22 volumio thd[3405]: Error reading device '/dev/input/event1' Dec 08 21:02:22 volumio systemd[1]: Stopping triggerhappy.service - triggerhappy global hotkey daemon... Dec 08 21:02:22 volumio kernel: input: BPR2S Mouse as /devices/virtual/misc/uhid/0005:1D5A:C081.0003/input/input7 Dec 08 21:02:22 volumio kernel: input: BPR2S as /devices/virtual/misc/uhid/0005:1D5A:C081.0003/input/input8 Dec 08 21:02:22 volumio kernel: input: BPR2S Keyboard as /devices/virtual/misc/uhid/0005:1D5A:C081.0003/input/input9 Dec 08 21:02:22 volumio systemd[1]: triggerhappy.service: Deactivated successfully. Dec 08 21:02:22 volumio systemd[1]: Stopped triggerhappy.service - triggerhappy global hotkey daemon. Dec 08 21:02:22 volumio systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Dec 08 21:02:22 volumio thd[4406]: Device /dev/input/event2 not suitable. Dec 08 21:02:22 volumio systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Dec 08 21:02:22 volumio kernel: hid-generic 0005:1D5A:C081.0003: input,hidraw0: BLUETOOTH HID v0.00 Mouse [BPR2S] on b8:27:eb:44:23:dd Dec 08 21:02:22 volumio systemd[1]: Stopping triggerhappy.service - triggerhappy global hotkey daemon... Dec 08 21:02:23 volumio systemd[1]: triggerhappy.service: Deactivated successfully. Dec 08 21:02:23 volumio systemd[1]: Stopped triggerhappy.service - triggerhappy global hotkey daemon. Dec 08 21:02:23 volumio systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Dec 08 21:02:23 volumio thd[4415]: Device /dev/input/event2 not suitable. Dec 08 21:02:23 volumio systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Dec 08 21:02:23 volumio systemd-logind[688]: Watching system buttons on /dev/input/event3 (BPR2S Keyboard) Dec 08 21:02:26 volumio thd[4415]: Executing trigger action: volumio volume minus Dec 08 21:02:26 volumio thd[4427]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 08 21:02:26 volumio thd[4427]: Dload Upload Total Spent Left Speed Dec 08 21:02:26 volumio volumio[1245]: info: Dec 08 21:02:26 volumio volumio[1245]: ---------------------------- Client requests Volume - Dec 08 21:02:26 volumio volumio[1245]: info: VolumeController::SetAlsaVolume- Dec 08 21:02:26 volumio volumio[1245]: info: CoreStateMachine::pushState Dec 08 21:02:26 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 08 21:02:26 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Dec 08 21:02:26 volumio thd[4427]: [158B blob data] Dec 08 21:02:26 volumio volumio[1245]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 08 21:02:26 volumio volumio[1245]: SPOTIFY: SPOTIFY VOLUME 80 Dec 08 21:02:26 volumio volumio[1245]: SPOTIFY: VOLUMIO VOLUME 75 Dec 08 21:02:26 volumio volumio[1245]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 08 21:02:26 volumio volumio[1245]: info: Setting Spotify Volume from Volumio: 75 Dec 08 21:02:28 volumio volumio[1245]: SPOTIFY: SETTING SPOTIFY VOLUME 75 Dec 08 21:02:28 volumio volumio[1245]: info: Sending Spotify command with payload to local API: /player/volume Dec 08 21:02:28 volumio go-librespot[3074]: time="2025-12-08T21:02:28+01:00" level=debug msg="update volume requested to 49151/65535" Dec 08 21:02:28 volumio go-librespot[3074]: time="2025-12-08T21:02:28+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 08 21:02:28 volumio go-librespot[3074]: time="2025-12-08T21:02:28+01:00" level=trace msg="emitting websocket event: volume" Dec 08 21:02:28 volumio volumio[1245]: SPOTIFY: received: {"type":"volume","data":{"value":75,"max":100}} Dec 08 21:02:28 volumio volumio[1245]: SPOTIFY: RECEIVED SPOTIFY VOLUME 75 Dec 08 21:02:28 volumio volumio[1245]: SPOTIFY: received: {"type":"volume","data":{"value":75,"max":100}} Dec 08 21:02:28 volumio volumio[1245]: SPOTIFY: RECEIVED SPOTIFY VOLUME 75 Dec 08 21:02:28 volumio volumio[1245]: SPOTIFY: received: {"type":"volume","data":{"value":75,"max":100}} Dec 08 21:02:28 volumio volumio[1245]: SPOTIFY: RECEIVED SPOTIFY VOLUME 75 Dec 08 21:02:35 volumio go-librespot[3074]: time="2025-12-08T21:02:35+01:00" level=trace msg="sent dealer ping" Dec 08 21:02:35 volumio go-librespot[3074]: time="2025-12-08T21:02:35+01:00" level=trace msg="received dealer pong" Dec 08 21:02:42 volumio volumio[1245]: info: Dec 08 21:02:42 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Dec 08 21:02:42 volumio volumio[1245]: info: Ignoring MPD Status Update Dec 08 21:02:42 volumio volumio[1245]: info: Dec 08 21:02:42 volumio volumio[1245]: ---------------------------- MPD announces state update: player Dec 08 21:02:42 volumio volumio[1245]: info: ControllerMpd::getState Dec 08 21:02:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:02:42 volumio volumio[1245]: info: Dec 08 21:02:42 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Dec 08 21:02:42 volumio volumio[1245]: info: Ignoring MPD Status Update Dec 08 21:02:42 volumio volumio[1245]: info: Dec 08 21:02:42 volumio volumio[1245]: ---------------------------- MPD announces state update: player Dec 08 21:02:42 volumio volumio[1245]: info: ControllerMpd::getState Dec 08 21:02:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:02:42 volumio volumio[1245]: info: Dec 08 21:02:42 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Dec 08 21:02:42 volumio volumio[1245]: info: Ignoring MPD Status Update Dec 08 21:02:42 volumio volumio[1245]: info: Dec 08 21:02:42 volumio volumio[1245]: ---------------------------- MPD announces state update: player Dec 08 21:02:42 volumio volumio[1245]: info: ControllerMpd::getState Dec 08 21:02:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Dec 08 21:02:42 volumio volumio[1245]: info: ------------------------------ 6ms Dec 08 21:02:42 volumio volumio[1245]: info: sendMpdCommand status took 7 milliseconds Dec 08 21:02:42 volumio volumio[1245]: info: ------------------------------ 6ms Dec 08 21:02:42 volumio volumio[1245]: info: sendMpdCommand status took 6 milliseconds Dec 08 21:02:42 volumio volumio[1245]: info: ------------------------------ 5ms Dec 08 21:02:42 volumio volumio[1245]: info: sendMpdCommand status took 4 milliseconds Dec 08 21:02:42 volumio volumio[1245]: verbose: ControllerMpd::parseState Dec 08 21:02:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 08 21:02:42 volumio volumio[1245]: verbose: ControllerMpd::parseState Dec 08 21:02:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 08 21:02:42 volumio volumio[1245]: verbose: ControllerMpd::parseState Dec 08 21:02:42 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 08 21:02:42 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 08 21:02:42 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 08 21:02:42 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 08 21:02:42 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Dec 08 21:02:42 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Dec 08 21:02:42 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Dec 08 21:02:42 volumio volumio[1245]: info: ControllerMpd::pushState Dec 08 21:02:42 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Dec 08 21:02:42 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Dec 08 21:02:42 volumio volumio[1245]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49583,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Skin - Grimes","artist":"RMF CHILLOUT","album":null,"uri":"http://www.rmfon.pl/tunein/tunein.php?host=rs202-krk-cyfronet.rmfstream.pl&mount=rmf_chillout&secure=0","trackType":""} Dec 08 21:02:42 volumio volumio[1245]: verbose: CURRENT POSITION 0 Dec 08 21:02:42 volumio volumio[1245]: info: CoreStateMachine::syncState stateService play Dec 08 21:02:42 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus play Dec 08 21:02:42 volumio volumio[1245]: info: Received an update from plugin. extracting info from payload Dec 08 21:02:42 volumio volumio[1245]: info: CoreStateMachine::pushState Dec 08 21:02:42 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 08 21:02:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Dec 08 21:02:42 volumio volumio[1245]: info: CoreStateMachine::pushState Dec 08 21:02:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Dec 08 21:02:42 volumio volumio[1245]: info: ControllerMpd::pushState Dec 08 21:02:42 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Dec 08 21:02:42 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Dec 08 21:02:42 volumio volumio[1245]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49583,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Skin - Grimes","artist":"RMF CHILLOUT","album":null,"uri":"http://www.rmfon.pl/tunein/tunein.php?host=rs202-krk-cyfronet.rmfstream.pl&mount=rmf_chillout&secure=0","trackType":""} Dec 08 21:02:42 volumio volumio[1245]: verbose: CURRENT POSITION 0 Dec 08 21:02:42 volumio volumio[1245]: info: CoreStateMachine::syncState stateService play Dec 08 21:02:42 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus play Dec 08 21:02:42 volumio volumio[1245]: info: Received an update from plugin. extracting info from payload Dec 08 21:02:42 volumio volumio[1245]: info: CoreStateMachine::pushState Dec 08 21:02:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Dec 08 21:02:42 volumio volumio[1245]: info: CoreStateMachine::pushState Dec 08 21:02:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Dec 08 21:02:42 volumio volumio[1245]: info: ControllerMpd::pushState Dec 08 21:02:42 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Dec 08 21:02:42 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Dec 08 21:02:42 volumio volumio[1245]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49583,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Skin - Grimes","artist":"RMF CHILLOUT","album":null,"uri":"http://www.rmfon.pl/tunein/tunein.php?host=rs202-krk-cyfronet.rmfstream.pl&mount=rmf_chillout&secure=0","trackType":""} Dec 08 21:02:42 volumio volumio[1245]: verbose: CURRENT POSITION 0 Dec 08 21:02:42 volumio volumio[1245]: info: CoreStateMachine::syncState stateService play Dec 08 21:02:42 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus play Dec 08 21:02:42 volumio volumio[1245]: info: Received an update from plugin. extracting info from payload Dec 08 21:02:42 volumio volumio[1245]: info: CoreStateMachine::pushState Dec 08 21:02:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Dec 08 21:02:42 volumio volumio[1245]: info: CoreStateMachine::pushState Dec 08 21:02:42 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Dec 08 21:02:42 volumio volumio[1245]: info: ------------------------------ 103ms Dec 08 21:02:42 volumio volumio[1245]: info: ------------------------------ 102ms Dec 08 21:02:42 volumio volumio[1245]: info: ------------------------------ 100ms Dec 08 21:02:42 volumio volumio[1245]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 08 21:02:42 volumio volumio[1245]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 08 21:02:42 volumio volumio[1245]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 08 21:02:42 volumio volumio[1245]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 08 21:02:42 volumio volumio[1245]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 08 21:02:42 volumio volumio[1245]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Dec 08 21:02:44 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 08 21:02:44 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 08 21:02:52 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 21:02:56 volumio volumio[1245]: info: CALLMETHOD: user_interface touch_display saveScreensaverConf [object Object] Dec 08 21:02:56 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveScreensaverConf Dec 08 21:02:56 volumio volumio[1245]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X' Dec 08 21:03:05 volumio go-librespot[3074]: time="2025-12-08T21:03:05+01:00" level=trace msg="sent dealer ping" Dec 08 21:03:05 volumio go-librespot[3074]: time="2025-12-08T21:03:05+01:00" level=trace msg="received dealer pong" Dec 08 21:03:05 volumio volumio[1245]: info: CALLMETHOD: user_interface touch_display saveOrientationConf [object Object] Dec 08 21:03:05 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveOrientationConf Dec 08 21:03:05 volumio sudo[4578]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 08 21:03:05 volumio sudo[4578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 21:03:05 volumio sudo[4578]: pam_unix(sudo:session): session closed for user root Dec 08 21:03:05 volumio volumio[1245]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 08 21:03:05 volumio volumio[1245]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 08 21:03:05 volumio volumio[1245]: TypeError: Cannot read properties of undefined (reading 'split') Dec 08 21:03:05 volumio volumio[1245]: at /data/plugins/user_interface/touch_display/index.js:1255:37 Dec 08 21:03:05 volumio volumio[1245]: at ChildProcess.exithandler (node:child_process:413:7) Dec 08 21:03:05 volumio volumio[1245]: at ChildProcess.emit (node:events:514:28) Dec 08 21:03:05 volumio volumio[1245]: at maybeClose (node:internal/child_process:1105:16) Dec 08 21:03:05 volumio volumio[1245]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Dec 08 21:03:05 volumio volumio[1245]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 08 21:03:06 volumio sudo[4595]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-08 21:02' Dec 08 21:03:06 volumio sudo[4595]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"