Aug 25 13:06:00 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:00 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:00 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:00 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:01 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:01 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:01 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:01 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:02 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:02 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:03 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:03 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:03 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:03 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:04 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:04 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:05 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:05 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:05 wizard-jan-jonkergouw-4.119 kernel: vc4-drm soc:gpu: [drm] *ERROR* flip_done timed out Aug 25 13:06:05 wizard-jan-jonkergouw-4.119 kernel: vc4-drm soc:gpu: [drm] *ERROR* [PLANE:84:plane-3] commit wait timed out Aug 25 13:06:09 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:09.413+02:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.178.158:60951 error="websocket: close 1006 (abnormal closure): unexpected EOF" Aug 25 13:06:09 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:09.432+02:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.178.158:60951 Aug 25 13:06:09 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:09.432+02:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.178.158:60951 Aug 25 13:06:09 wizard-jan-jonkergouw-4.119 dbus-daemon[714]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.26" (uid=0 pid=2122 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.4" (uid=0 pid=964 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:12.721+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.178.158:63100 Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:12.764+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.178.158:63100 @ 0x14004e0" latency=5.525386ms timeout=20s Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:12.764+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.178.158:63100 @ 0x14004e0" latency=5.443459ms platform=PLATFORM_IOS version=6.260807.0 Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:12.764+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.178.158:63100 @ 0x14004e0" Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Received Get System Info Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Discovery: Getting this device information Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetState Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:12.770+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.178.158:63100 @ 0x14004e0" name="Wizard Jan Jonkergouw 4.119" Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:12.777+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.178.158:63100 @ 0x14004e0" language=en Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:12.779+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.178.158:63100 @ 0x14004e0" timezone=Europe/Amsterdam Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:12.781+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.178.158:63100 @ 0x14004e0" available=true connected=true macAddress=b8:27:eb:d2:47:5f ip4Address=192.168.178.125/24 ip6Address= Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:12.783+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.178.158:63100 @ 0x14004e0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:12.784+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.178.158:63100 @ 0x14004e0" setupComplete=true Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: amixer -c 1 info | grep "bcm2835 Headphones" Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: amixer -c 2 info | grep "vc4-hdmi" Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: amixer -c 3 info | grep "Audiophonics I-Sabre ES9028Q2M" Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 25 13:06:12 wizard-jan-jonkergouw-4.119 volumio[1275]: amixer -c 3 info | grep "I-Sabre Q2M DAC" Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio[1275]: Card sysdefault:3 'DAC'/'I-Sabre Q2M DAC' Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:13.019+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.178.158:63100 @ 0x14004e0" selectedOutputId=3 Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Received Get System Info Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Discovery: Getting this device information Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetState Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:13.036+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.178.158:63100 @ 0x14004e0" currentVersion=4.119 latestVersion=4.119 Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:13.037+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.178.158:63100 @ 0x14004e0" status=UPDATE_STATUS_NONE progress=0 Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:13.037+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.178.158:63100 @ 0x14004e0" userId= Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:13.037+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.178.158:63100 @ 0x14004e0" providers=3 Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:13.038+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.178.158:63100 @ 0x14004e0" plugins=83 Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetState Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:13.043+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.158:63100 @ 0x14004e0" state=STATUS_PLAYING positionMs=55864 volume=100 Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:13.043+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.158:63100 @ 0x14004e0" id=qobuz://song/22608029 title="Sixteen Tons" Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:13.792+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.178.158:63100 @ 0x14004e0" latency=5.69716ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:13.827+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.158:63100 @ 0x14004e0" latency=6.849265ms timeout=10s Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:13.852+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.158:63100 @ 0x14004e0" latency=6.849265ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=25.235907ms Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Discovery: Getting this device information Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetState Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:13.879+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.158:63100 @ 0x14004e0" latency=6.849265ms timeout=10s endpoint=http://pushupdates.volumio.org duration=50.183587ms Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:13.879+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.158:63100 @ 0x14004e0" latency=6.849265ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=52.343514ms Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio[1275]: verbose: New Socket.io Connection to 192.168.178.125:3000 from 192.168.178.158 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:13.904+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.158:63100 @ 0x14004e0" latency=6.849265ms timeout=10s endpoint=https://google.com duration=77.103748ms Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:13.948+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.158:63100 @ 0x14004e0" latency=6.849265ms timeout=10s endpoint=https://www.googleapis.com duration=121.616815ms Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:13.969+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.158:63100 @ 0x14004e0" latency=6.849265ms timeout=10s endpoint=https://functions.volumio.cloud duration=141.617824ms Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:13.970+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.158:63100 @ 0x14004e0" latency=6.849265ms timeout=10s endpoint=https://functions.volumio.cloud duration=142.665887ms Aug 25 13:06:13 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:13.970+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.158:63100 @ 0x14004e0" latency=6.849265ms timeout=10s endpoint=https://securetoken.googleapis.com duration=143.329839ms Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:14.140+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.158:63100 @ 0x14004e0" latency=6.849265ms timeout=10s endpoint=https://database.volumio.cloud duration=312.185636ms Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:14.209+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.158:63100 @ 0x14004e0" latency=6.849265ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=381.39748ms Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:14.220+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.158:63100 @ 0x14004e0" latency=6.849265ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=392.782686ms Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 sudo[4699]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 sudo[4699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 sudo[4699]: pam_unix(sudo:session): session closed for user root Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 sudo[4701]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 sudo[4701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 sudo[4701]: pam_unix(sudo:session): session closed for user root Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:14.250+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.158:63100 @ 0x14004e0" latency=6.849265ms timeout=10s endpoint=http://cddb.volumio.org duration=422.770422ms Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: verbose: New Socket.io Connection to 192.168.178.125 from 192.168.178.158 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: 9 Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:14.298+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.158:63100 @ 0x14004e0" latency=6.849265ms timeout=10s endpoint=http://plugins.volumio.org duration=470.539866ms Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 sudo[4705]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 sudo[4705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 sudo[4705]: pam_unix(sudo:session): session closed for user root Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 sudo[4707]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 sudo[4707]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 sudo[4707]: pam_unix(sudo:session): session closed for user root Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: verbose: New Socket.io Connection to 192.168.178.125 from 192.168.178.158 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: 9 Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetVisibleSources Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetState Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Received Get System Info Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Discovery: Getting this device information Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetState Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetState Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Listing playlists Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 13:06:14 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 25 13:06:15 wizard-jan-jonkergouw-4.119 kernel: vc4-drm soc:gpu: [drm] *ERROR* flip_done timed out Aug 25 13:06:15 wizard-jan-jonkergouw-4.119 kernel: vc4-drm soc:gpu: [drm] *ERROR* Timed out waiting for commit Aug 25 13:06:16 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 25 13:06:16 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 13:06:16 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Received Get System Info Aug 25 13:06:16 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 13:06:16 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 13:06:16 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Discovery: Getting this device information Aug 25 13:06:16 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetState Aug 25 13:06:16 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 13:06:17 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 13:06:17 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Received Get System Info Aug 25 13:06:17 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 13:06:17 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 13:06:17 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Discovery: Getting this device information Aug 25 13:06:17 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetState Aug 25 13:06:17 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 13:06:18 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 13:06:18 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 25 13:06:21 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:21 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:22 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:22 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:22 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:22 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:22 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:22 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:24 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 systemd[1]: volumio-kiosk.service: Consumed 1min 19.965s CPU time. Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 kernel: vc4-drm soc:gpu: [drm] *ERROR* [CRTC:95:pixelvalve-2] flip_done timed out Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 sudo[4465]: pam_unix(sudo:session): session closed for user root Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 volumio[1275]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 volumio[1275]: info: touch_display: Volumio Kiosk started. Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetState Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetState Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 volumio[1275]: info: touch_display: X display number found: Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 volumio[1275]: info: touch_display: X display number found: 1 Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 startx[4775]: X.Org X Server 1.21.1.7 Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 startx[4775]: X Protocol Version 11, Revision 0 Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 startx[4775]: Current Operating System: Linux wizard-jan-jonkergouw-4.119 6.12.74-v7+ #1948 SMP Mon Mar 2 11:25:27 GMT 2026 armv7l Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 startx[4775]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0 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=bea3e05c-62cf-4b22-a58d-66213927f56a 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 video=HDMI-A-1:rotate=270 plymouth=270 fbcon=rotate:3 Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 startx[4775]: xorg-server 2:21.1.7-3+rpt3+deb12u12 (https://www.debian.org/support) Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 startx[4775]: Current version of pixman: 0.44.0 Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 startx[4775]: Before reporting problems, check http://wiki.x.org Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 startx[4775]: to make sure that you have the latest version. Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 startx[4775]: Markers: (--) probed, (**) from config file, (==) default setting, Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 startx[4775]: (++) from command line, (!!) notice, (II) informational, Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 startx[4775]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Aug 25 13:06:25 wizard-jan-jonkergouw-4.119 startx[4775]: (==) Log file: "/var/log/Xorg.1.log", Time: Tue Aug 25 13:06:25 2026 Aug 25 13:06:26 wizard-jan-jonkergouw-4.119 startx[4775]: (==) Using config directory: "/etc/X11/xorg.conf.d" Aug 25 13:06:26 wizard-jan-jonkergouw-4.119 startx[4775]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Aug 25 13:06:26 wizard-jan-jonkergouw-4.119 volumio[1275]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X1 Aug 25 13:06:26 wizard-jan-jonkergouw-4.119 volumio[1275]: info: touch_display: X display number found: 1 Aug 25 13:06:26 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:26 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:26 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:26 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:27 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:27 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:27 wizard-jan-jonkergouw-4.119 bluealsa[1022]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_56_2F_51_78_75_99, ...) Aug 25 13:06:28 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Enabling plugin now_playing Aug 25 13:06:28 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Loading plugin "now_playing"... Aug 25 13:06:28 wizard-jan-jonkergouw-4.119 volumio[1275]: info: PLUGIN START: now_playing Aug 25 13:06:28 wizard-jan-jonkergouw-4.119 volumio[1275]: info: [now-playing] ConfigUpdater: config is up to date. Aug 25 13:06:28 wizard-jan-jonkergouw-4.119 volumio[1275]: info: [now-playing] App is listening on port 4004. Aug 25 13:06:28 wizard-jan-jonkergouw-4.119 volumio[1275]: info: [now-playing] Backing up /opt/volumiokiosk.sh to /home/volumio/.now_playing/volumiokiosk.sh.bak Aug 25 13:06:28 wizard-jan-jonkergouw-4.119 sudo[4796]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:3000/localhost:4004/g /opt/volumiokiosk.sh Aug 25 13:06:28 wizard-jan-jonkergouw-4.119 sudo[4796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 13:06:28 wizard-jan-jonkergouw-4.119 sudo[4796]: pam_unix(sudo:session): session closed for user root Aug 25 13:06:28 wizard-jan-jonkergouw-4.119 volumio[1275]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Aug 25 13:06:28 wizard-jan-jonkergouw-4.119 sudo[4799]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Aug 25 13:06:28 wizard-jan-jonkergouw-4.119 sudo[4799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 13:06:28 wizard-jan-jonkergouw-4.119 sudo[4799]: pam_unix(sudo:session): session closed for user root Aug 25 13:06:28 wizard-jan-jonkergouw-4.119 volumio[1275]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl restart volumio-kiosk Aug 25 13:06:28 wizard-jan-jonkergouw-4.119 sudo[4802]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk Aug 25 13:06:28 wizard-jan-jonkergouw-4.119 sudo[4802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 13:06:28 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Aug 25 13:06:29 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:28.998+02:00 level=INFO msg="new address was allocated" component=ble/conn old=3 new=4 Aug 25 13:06:29 wizard-jan-jonkergouw-4.119 dbus-daemon[714]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.26" (uid=0 pid=2122 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.4" (uid=0 pid=964 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Aug 25 13:06:29 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:29 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:30 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:30 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:30 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:30 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:31 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:31 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:31 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:31 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:32 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:32 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:32 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 13:06:32 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 25 13:06:32 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 25 13:06:32 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Received Get System Version Aug 25 13:06:32 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 13:06:32 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Received Get System Info Aug 25 13:06:32 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 13:06:32 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 13:06:32 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Discovery: Getting this device information Aug 25 13:06:32 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetState Aug 25 13:06:32 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 13:06:34 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 13:06:34 wizard-jan-jonkergouw-4.119 volumio[1275]: warn: [now-playing] MyBackgroundMonitor is not running. Returning empty image list. Aug 25 13:06:34 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 13:06:34 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Discovery: Getting this device information Aug 25 13:06:34 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetState Aug 25 13:06:34 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 13:06:36 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 13:06:36 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 25 13:06:36 wizard-jan-jonkergouw-4.119 kernel: vc4-drm soc:gpu: [drm] *ERROR* flip_done timed out Aug 25 13:06:36 wizard-jan-jonkergouw-4.119 kernel: vc4-drm soc:gpu: [drm] *ERROR* [CRTC:95:pixelvalve-2] commit wait timed out Aug 25 13:06:39 wizard-jan-jonkergouw-4.119 sudo[4843]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:4004/localhost:3000/g /opt/volumiokiosk.sh Aug 25 13:06:39 wizard-jan-jonkergouw-4.119 sudo[4843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 13:06:39 wizard-jan-jonkergouw-4.119 sudo[4843]: pam_unix(sudo:session): session closed for user root Aug 25 13:06:39 wizard-jan-jonkergouw-4.119 volumio[1275]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Aug 25 13:06:39 wizard-jan-jonkergouw-4.119 sudo[4846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Aug 25 13:06:39 wizard-jan-jonkergouw-4.119 sudo[4846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 13:06:39 wizard-jan-jonkergouw-4.119 sudo[4846]: pam_unix(sudo:session): session closed for user root Aug 25 13:06:39 wizard-jan-jonkergouw-4.119 volumio[1275]: error: [now-playing] Failed to execute systemctl command status on volumio-kiosk: Error: Command failed: /usr/bin/sudo /bin/systemctl status volumio-kiosk Aug 25 13:06:39 wizard-jan-jonkergouw-4.119 volumio[1275]: at ChildProcess.exithandler (node:child_process:421:12) Aug 25 13:06:39 wizard-jan-jonkergouw-4.119 volumio[1275]: at ChildProcess.emit (node:events:514:28) Aug 25 13:06:39 wizard-jan-jonkergouw-4.119 volumio[1275]: at maybeClose (node:internal/child_process:1105:16) Aug 25 13:06:39 wizard-jan-jonkergouw-4.119 volumio[1275]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Aug 25 13:06:39 wizard-jan-jonkergouw-4.119 volumio[1275]: warn: [now-playing] MyBackgroundMonitor stopped Aug 25 13:06:39 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin now_playing Aug 25 13:06:39 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:40 wizard-jan-jonkergouw-4.119 bluealsa[1022]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_47_D3_F9_A9_0D_B5, ...) Aug 25 13:06:41 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:41 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:41 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:41 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:42 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:42 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:42 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Disabling plugin touch_display Aug 25 13:06:42 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Done. Aug 25 13:06:46 wizard-jan-jonkergouw-4.119 kernel: vc4-drm soc:gpu: [drm] *ERROR* flip_done timed out Aug 25 13:06:46 wizard-jan-jonkergouw-4.119 kernel: vc4-drm soc:gpu: [drm] *ERROR* [CONNECTOR:33:HDMI-A-1] commit wait timed out Aug 25 13:06:46 wizard-jan-jonkergouw-4.119 systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Aug 25 13:06:47 wizard-jan-jonkergouw-4.119 systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Aug 25 13:06:47 wizard-jan-jonkergouw-4.119 systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Aug 25 13:06:47 wizard-jan-jonkergouw-4.119 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. Aug 25 13:06:47 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetQueue Aug 25 13:06:47 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreStateMachine::getQueue Aug 25 13:06:47 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CorePlayQueue::getQueue Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioClearQueue Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreStateMachine::ClearQueue Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreStateMachine::stop Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreStateMachine::stPlaybackTimer Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreStateMachine::updateTrackBlock Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CorePlayQueue::getTrackBlock Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreStateMachine::pushState Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CorePlayQueue::getTrack 0 Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioPushState Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: MRS: Pushing multiroomSync output update for this device Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: MRS: Pushing multiroomSync output Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetState Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CorePlayQueue::getTrack 0 Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreStateMachine::serviceStop Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CorePlayQueue::getTrack 0 Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::serviceStop Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: [1787656008787] ControllerQobuz::stop Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: ControllerMpd::stop Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CorePlayQueue::clearPlayQueue Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CorePlayQueue::saveQueue Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioPushState Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: MRS: Pushing multiroomSync output update for this device Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: MRS: Pushing multiroomSync output Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetState Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioPushQueue Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:48.793+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1522750" state=STATUS_STOPPED positionMs=0 volume=100 Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:48.793+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1522750" state=STATUS_STOPPED positionMs=0 volume=100 Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 mpd[2345]: player: played "https://streaming-qobuz-std.akamaized.net/file?uid=339944&eid=22608029&fmt=6&profile=raw&app_id=539451548&cid=2276342&etsp=1787659515&hmac=3_VqbktmIC_5Qwq47hroUdpEH2A" Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: sendMpdCommand stop took 72 milliseconds Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: ---------------------------- MPD announces state update: player Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: ControllerMpd::getState Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: verbose: ControllerMpd::sendMpdCommand status Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: sendMpdCommand status took 1 milliseconds Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: verbose: ControllerMpd::parseState Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: verbose: ControllerMpd::parseTrackInfo Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: ControllerMpd::pushState Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::servicePushState Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreStateMachine::pushState Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioPushState Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: MRS: Pushing multiroomSync output update for this device Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: MRS: Pushing multiroomSync output Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetState Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CorePlayQueue::getTrack 0 Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=339944&eid=22608029&fmt=6&profile=raw&app_id=539451548&cid=2276342&etsp=1787659515&hmac=3_VqbktmIC_5Qwq47hroUdpEH2A","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=339944&eid=22608029&fmt=6&profile=raw&app_id=539451548&cid=2276342&etsp=1787659515&hmac=3_VqbktmIC_5Qwq47hroUdpEH2A","trackType":"qobuz"} Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: verbose: CURRENT POSITION 0 Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreStateMachine::syncState stateService stop Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreStateMachine::syncState currentStatus stop Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreStateMachine::pushState Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioPushState Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: MRS: Pushing multiroomSync output update for this device Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: MRS: Pushing multiroomSync output Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetState Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: No code Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreStateMachine::pushState Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioPushState Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: MRS: Pushing multiroomSync output update for this device Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: MRS: Pushing multiroomSync output Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::volumioGetState Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:48.894+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1522750" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:48.896+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1522750" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:48.897+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%02 @ 0x1522750" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 13:06:48 wizard-jan-jonkergouw-4.119 volumio[1275]: info: ------------------------------ 40ms Aug 25 13:06:50 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Executing endpoint metavolumio Aug 25 13:06:50 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 13:06:50 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Executing endpoint metavolumio Aug 25 13:06:50 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 13:06:50 wizard-jan-jonkergouw-4.119 volumio[1275]: info: Executing endpoint metavolumio Aug 25 13:06:50 wizard-jan-jonkergouw-4.119 volumio[1275]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 13:06:52 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:52.103+02:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=2 chunks=1 index=0 tries=11 Aug 25 13:06:52 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:52.104+02:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%02 @ 0x1522750" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Aug 25 13:06:52 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:52.104+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.215:58172 @ 0x1981410" state=STATUS_STOPPED positionMs=0 volume=100 Aug 25 13:06:52 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:52.104+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.158:63100,00:00:00:00:00:00%03 @ 0x14004e0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 25 13:06:52 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:52.104+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.215:58172 @ 0x1981410" id=qobuz://song/22608029 title="Sixteen Tons" Aug 25 13:06:52 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:52.105+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.158:63100,00:00:00:00:00:00%03 @ 0x14004e0" id=qobuz://song/22608029 title="Sixteen Tons" Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio[1275]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio[1275]: info: PLUGIN onShutdown : networkfs Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio[1275]: info: PLUGIN onShutdown : pi_screen_setup Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio[1275]: info: pi_screen_setup: System shutting down Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:55.604+02:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=2 chunks=1 index=0 tries=11 Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:55.605+02:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%02 @ 0x1522750" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="peer is gone" Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:55.605+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.215:58172 @ 0x1981410" state=STATUS_STOPPED positionMs=0 volume=100 Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:55.606+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.158:63100,00:00:00:00:00:00%03 @ 0x14004e0" state=STATUS_STOPPED positionMs=0 volume=100 Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:55.607+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.215:58172 @ 0x1981410" id= title= Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio5-onboarding[2122]: time=2026-08-25T13:06:55.607+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.158:63100,00:00:00:00:00:00%03 @ 0x14004e0" id= title= Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 sudo[4919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl poweroff Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 sudo[4919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd-logind[727]: The system will power off now! Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd-logind[727]: System is powering down. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 sudo[4802]: pam_unix(sudo:session): session closed for user root Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 sudo[4919]: pam_unix(sudo:session): session closed for user root Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio[1275]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio[1275]: TypeError: Cannot read properties of null (reading 'logger') Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio[1275]: at NowPlayingContext.getLogger (/data/plugins/user_interface/now_playing/dist/lib/NowPlayingContext.js:72:83) Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio[1275]: at /data/plugins/user_interface/now_playing/dist/lib/utils/System.js:62:45 Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio[1275]: at ChildProcess.exithandler (node:child_process:429:5) Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio[1275]: at ChildProcess.emit (node:events:514:28) Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio[1275]: at maybeClose (node:internal/child_process:1105:16) Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio[1275]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 volumio[1275]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Removed slice system-getty.slice - Slice /system/getty. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Removed slice system-modprobe.slice - Slice /system/modprobe. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped target bluetooth.target - Bluetooth Support. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped target graphical.target - Graphical Interface. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped target rpc_pipefs.target. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped target rpcbind.target - RPC Port Mapper. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped target timers.target - Timer Units. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: apt-daily-upgrade.timer: Deactivated successfully. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: apt-daily.timer: Deactivated successfully. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped apt-daily.timer - Daily apt download activities. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: dpkg-db-backup.timer: Deactivated successfully. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped dpkg-db-backup.timer - Daily dpkg database backup timer. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: e2scrub_all.timer: Deactivated successfully. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: fstrim.timer: Deactivated successfully. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped fstrim.timer - Discard unused blocks once a week. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: man-db.timer: Deactivated successfully. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped man-db.timer - Daily man-db regeneration. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: ntpsec-rotate-stats.timer: Deactivated successfully. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: setdatetime-helper.timer: Deactivated successfully. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: systemd-rfkill.socket: Deactivated successfully. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Closed systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Unmounting run-rpc_pipefs.mount - RPC Pipe File System... Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping bluealsa.service - BlueALSA service... Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 bluealsa[1022]: ../src/ba-adapter.c:144: Freeing adapter: hci0 Aug 25 13:06:55 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: rpi-display-backlight.service - Turns off Raspberry Pi display backlight on shutdown/reboot was skipped because of an unmet condition check (ConditionPathIsDirectory=/proc/device-tree/rpi_backlight). Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 autossh[2750]: received signal to exit (15) Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping systemd-random-seed.service - Load/Save Random Seed... Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping upower.service - Daemon for power management... Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping volumio5-onboarding.service - Volumio5 Onboarding Server... Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping volumiobt.service - Volumio Bluetooth Module... Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 qobuz-connect[2365]: 20260825 13:06:56.050 [2365.2365] INFO ContentFetcher: [0x1abefe8]: Canceling download Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: volumio5-onboarding.service: Deactivated successfully. Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: volumio5-onboarding.service: Consumed 12.722s CPU time. Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 qobuz-connect[2365]: 20260825 13:06:56.077 [2365.2365] INFO CloudClient: [0x1887288]: Disconnecting from the cloud Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 qobuz-connect[2365]: 20260825 13:06:56.077 [2365.2365] INFO CloudClient: [0x1887288]: Connection closed Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: vtcs.service: Killing process 2590 (vtcs) with signal SIGKILL. Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: vtcs.service: Deactivated successfully. Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: vtcs.service: Consumed 6.378s CPU time. Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: run-rpc_pipefs.mount: Deactivated successfully. Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: Unmounted run-rpc_pipefs.mount - RPC Pipe File System. Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: sshtunnel.service: Deactivated successfully. Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 volumiobt[4928]: INFO [BTSTART] Disconnecting all Bluetooth devices... Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: systemd-random-seed.service: Deactivated successfully. Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped systemd-random-seed.service - Load/Save Random Seed. Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: upower.service: Deactivated successfully. Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped upower.service - Daemon for power management. Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 qobuz-connect[2365]: 20260825 13:06:56.298 [2365.2365] INFO SampleApp: Stopping Local configuration server Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 bluetoothd[964]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/sink/2 Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 bluetoothd[964]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/source/1 Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 bluetoothd[964]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/source/2 Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 bluetoothd[964]: Endpoint unregistered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/sink/1 Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 bluetoothd[964]: Path / reserved for Adv Monitor app :1.44 Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 bluetoothd[964]: Adv Monitor app :1.44 disconnected from D-Bus Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 volumiobt[4935]: Attempting to disconnect from 56:2F:51:78:75:99 Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 volumiobt[4935]: [NEW] Media /org/bluez/hci0 Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 volumiobt[4935]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 volumiobt[4935]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 volumiobt[4935]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 bluetoothd[964]: Path / reserved for Adv Monitor app :1.45 Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 volumiobt[4935]: AdvertisementMonitor path registered Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: bluealsa.service: Deactivated successfully. Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped bluealsa.service - BlueALSA service. Aug 25 13:06:56 wizard-jan-jonkergouw-4.119 systemd[1]: bluealsa.service: Consumed 1.113s CPU time. Aug 25 13:06:57 wizard-jan-jonkergouw-4.119 kernel: vc4-drm soc:gpu: [drm] *ERROR* flip_done timed out Aug 25 13:06:57 wizard-jan-jonkergouw-4.119 kernel: vc4-drm soc:gpu: [drm] *ERROR* [PLANE:84:plane-3] commit wait timed out Aug 25 13:06:57 wizard-jan-jonkergouw-4.119 volumiobt[4935]: [SIGNAL] org.bluez.Device1.Disconnected org.bluez.Reason.Remote Connection terminated by remote user Aug 25 13:06:57 wizard-jan-jonkergouw-4.119 volumiobt[4935]: [CHG] Device 56:2F:51:78:75:99 Connected: no Aug 25 13:06:57 wizard-jan-jonkergouw-4.119 volumiobt[4935]: Disconnection successful Aug 25 13:06:57 wizard-jan-jonkergouw-4.119 bluetoothd[964]: Adv Monitor app :1.45 disconnected from D-Bus Aug 25 13:06:57 wizard-jan-jonkergouw-4.119 volumiobt[4947]: INFO [BTSTART] Disconnected device: 56:2F:51:78:75:99 Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 volumiobt[4948]: INFO [BTSTART] Unregistering Bluetooth agent... Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 volumiobt[4949]: No agent is registered Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 volumiobt[4949]: [NEW] Media /org/bluez/hci0 Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 volumiobt[4949]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 volumiobt[4949]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 volumiobt[4949]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 bluetoothd[964]: Adv Monitor app :1.46 disconnected from D-Bus Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 volumiobt[4950]: INFO [BTSTART] Stopping bluealsa-aplay (if any)... Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 qobuz-connect[2365]: 20260825 13:06:58.147 [2365.2365] INFO SampleApp: shat down connection on UNIX socket Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 volumiobt[4953]: INFO [BTSTART] Stopping A2DP agent (a2dp-agent)... Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: qobuz-connect.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: qobuz-connect.service: Consumed 34.812s CPU time. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: volumiobt.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: volumiobt.service: Consumed 2.855s CPU time. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped target multi-user.target - Multi-User System. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped target getty.target - Login Prompts. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping bluetooth.service - Bluetooth service... Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 bluetoothd[964]: Terminating Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping cpufrequtils.service - LSB: set CPUFreq kernel parameters... Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: glamor-test.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped glamor-test.service - Check for glamor. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping ifplugd.service - LSB: Brings up/down network automatically... Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 ntpd[920]: ERR: ntpd exiting on signal 15 (Terminated) Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 ntpd[920]: PROTO: 192.42.116.90 unlink local addr 192.168.178.125 -> Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping ntpsec.service - Network Time Service... Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 ntpd[920]: PROTO: 193.123.38.172 unlink local addr 192.168.178.125 -> Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: plymouth-quit-wait.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 ntpd[920]: PROTO: 141.144.246.224 unlink local addr 192.168.178.125 -> Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped plymouth-quit-wait.service - Hold until boot process finishes up. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 ntpd[920]: PROTO: 178.239.19.58 unlink local addr 192.168.178.125 -> Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 ntpd[920]: PROTO: 162.159.200.123 unlink local addr 192.168.178.125 -> Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 ntpd[920]: PROTO: 85.202.163.55 unlink local addr 192.168.178.125 -> Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: plymouth-quit.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 ntpd[920]: PROTO: 149.143.87.22 unlink local addr 192.168.178.125 -> Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped plymouth-quit.service - Terminate Plymouth Boot Screen. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 ntpd[920]: PROTO: 84.27.222.8 unlink local addr 192.168.178.125 -> Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: rc-local.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 ntpd[920]: PROTO: 144.21.38.190 unlink local addr 192.168.178.125 -> Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped rc-local.service - /etc/rc.local Compatibility. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 ntpd[920]: PROTO: 172.233.59.163 unlink local addr 192.168.178.125 -> Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: rp1-test.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 ntpd[920]: PROTO: 45.139.163.87 unlink local addr 192.168.178.125 -> Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped rp1-test.service - Check for RP1 displays for Xorg. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 ntpd[920]: PROTO: 194.104.0.153 unlink local addr 192.168.178.125 -> Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: rpi-eeprom-update.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 ntpd[920]: PROTO: 185.51.192.62 unlink local addr 192.168.178.125 -> Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 ntpd[920]: PROTO: 185.51.192.63 unlink local addr 192.168.178.125 -> Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping smbd.service - Samba SMB Daemon... Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping ssh.service - OpenBSD Secure Shell server... Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping systemd-logind.service - User Login Management... Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping systemd-user-sessions.service - Permit User Sessions... Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping triggerhappy.service - triggerhappy global hotkey daemon... Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping volumio-ipchange.service - Volumio IP Change Monitor... Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping volumio-remote-updater.service - Volumio Remote OTA Updater... Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: welcome.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped welcome.service - Show a welcome message on console. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: volumio-remote-updater.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped volumio-remote-updater.service - Volumio Remote OTA Updater. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: volumio-ipchange.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped volumio-ipchange.service - Volumio IP Change Monitor. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: ntpsec.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped ntpsec.service - Network Time Service. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 bluetoothd[964]: Destroy Adv Monitor Manager Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 bluetoothd[964]: Battery Provider Manager destroyed Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 bluetoothd[964]: Stopping SDP server Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: shairport-sync.service: Consumed 5.050s CPU time. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 bluetoothd[964]: Exit Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: systemd-logind.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped systemd-logind.service - User Login Management. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: systemd-user-sessions.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped systemd-user-sessions.service - Permit User Sessions. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 sshd[960]: Received signal 15; terminating. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 avahi-daemon[713]: Got SIGTERM, quitting. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 avahi-daemon[713]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.178.125. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 avahi-daemon[713]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: bluetooth.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped bluetooth.service - Bluetooth service. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: bluetooth.service: Consumed 2.668s CPU time. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 avahi-daemon[713]: avahi-daemon 0.8 exiting. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: ssh.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped ssh.service - OpenBSD Secure Shell server. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: avahi-daemon.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: avahi-daemon.service: Consumed 2.420s CPU time. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: smbd.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped smbd.service - Samba SMB Daemon. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping winbind.service - Samba Winbind Daemon... Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: triggerhappy.service: Deactivated successfully. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 winbindd[1236]: [2026/08/25 13:06:58.932921, 0] ../../source3/winbindd/winbindd_dual.c:1950(winbindd_sig_term_handler) Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 winbindd[1162]: [2026/08/25 13:06:58.932909, 0] ../../source3/winbindd/winbindd_dual.c:1950(winbindd_sig_term_handler) Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped triggerhappy.service - triggerhappy global hotkey daemon. Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 winbindd[1174]: [2026/08/25 13:06:58.932906, 0] ../../source3/winbindd/winbindd_dual.c:1950(winbindd_sig_term_handler) Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 winbindd[1162]: Got sig[15] terminate (is_parent=1) Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 winbindd[1236]: Got sig[15] terminate (is_parent=0) Aug 25 13:06:58 wizard-jan-jonkergouw-4.119 winbindd[1174]: Got sig[15] terminate (is_parent=0) Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 ifplugd(eth0)[933]: Exiting. Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 systemd[1]: cpufrequtils.service: Deactivated successfully. Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped cpufrequtils.service - LSB: set CPUFreq kernel parameters. Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 ifplugd[4956]: Network Interface Plugging Daemon...stop eth0...done. Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 systemd[1]: ifplugd.service: Deactivated successfully. Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped ifplugd.service - LSB: Brings up/down network automatically. Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 systemd[1]: loadcpufreq.service: Deactivated successfully. Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped target nfs-client.target - NFS client services. Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 systemd[1]: winbind.service: Deactivated successfully. Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped winbind.service - Samba Winbind Daemon. Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 nmbd[1106]: [2026/08/25 13:06:59.633010, 0] ../../source3/nmbd/nmbd.c:59(terminate) Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 systemd[1]: winbind.service: Consumed 4.520s CPU time. Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 nmbd[1106]: Got SIGTERM: going down... Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 systemd[1]: Stopping nmbd.service - Samba NMB Daemon... Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 systemd[1]: nmbd.service: Deactivated successfully. Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped nmbd.service - Samba NMB Daemon. Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 systemd[1]: nmbd.service: Consumed 2.061s CPU time. Aug 25 13:06:59 wizard-jan-jonkergouw-4.119 systemd[1]: Stopped target network-online.target - Network is Online. Aug 25 13:07:00 wizard-jan-jonkergouw-4.119 sudo[4986]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-25 13:06' Aug 25 13:07:00 wizard-jan-jonkergouw-4.119 sudo[4986]: 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"