Oct 14 23:53:01 volumio volumio[1348]: info: Received Get System Info Oct 14 23:53:01 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:53:01 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:53:01 volumio volumio[1348]: info: Discovery: Getting this device information Oct 14 23:53:01 volumio volumio[1348]: info: CoreCommandRouter::volumioGetState Oct 14 23:53:01 volumio volumio[1348]: info: CorePlayQueue::getTrack 0 Oct 14 23:53:01 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:53:01 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:53:01 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:53:01 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:01.252+04:00 level=INFO msg="enabling local network discovery" Oct 14 23:53:01 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:01.269+04:00 level=INFO msg="enabling BLE discovery" Oct 14 23:53:02 volumio volumio[1348]: info: BOOT COMPLETED Oct 14 23:53:02 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:02.391+04:00 level=INFO msg="service successfully established" component=discovery/localnet Oct 14 23:53:10 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 23:53:10 volumio volumio[1348]: info: Received Get System Info Oct 14 23:53:10 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:53:10 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:53:10 volumio volumio[1348]: info: Discovery: Getting this device information Oct 14 23:53:10 volumio volumio[1348]: info: CoreCommandRouter::volumioGetState Oct 14 23:53:10 volumio volumio[1348]: info: CorePlayQueue::getTrack 0 Oct 14 23:53:10 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:53:21 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:21.726+04:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.3.3:38686 Oct 14 23:53:21 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:21.767+04:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.3.3:38686 @ 0xc0000e3a40" latency=-957.459838ms timeout=20s Oct 14 23:53:21 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:21.767+04:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.3.3:38686 @ 0xc0000e3a40" Oct 14 23:53:21 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:21.767+04:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.3.3:38686 @ 0xc0000e3a40" latency=-954.701639ms platform=PLATFORM_ANDROID version=6.260930.0 Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:53:21 volumio volumio[1348]: info: Received Get System Info Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:53:21 volumio volumio[1348]: info: Discovery: Getting this device information Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::volumioGetState Oct 14 23:53:21 volumio volumio[1348]: info: CorePlayQueue::getTrack 0 Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:53:21 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:21.774+04:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.3.3:38686 @ 0xc0000e3a40" name=Volumio Oct 14 23:53:21 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:21.777+04:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.3.3:38686 @ 0xc0000e3a40" language=ru Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 14 23:53:21 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:21.780+04:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.3.3:38686 @ 0xc0000e3a40" timezone=Europe/Samara Oct 14 23:53:21 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:21.781+04:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.3.3:38686 @ 0xc0000e3a40" available=true connected=true macAddress=f4:4d:30:68:66:12 ip4Address=192.168.3.56/24 ip6Address= Oct 14 23:53:21 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:21.785+04:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.3.3:38686 @ 0xc0000e3a40" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 14 23:53:21 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:21.785+04:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.3.3:38686 @ 0xc0000e3a40" setupComplete=true Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 14 23:53:21 volumio volumio[1348]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Oct 14 23:53:21 volumio volumio[1348]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 14 23:53:21 volumio volumio[1348]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\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)"} Oct 14 23:53:21 volumio volumio[1348]: amixer -c 5 info | grep "HIFI USB AUDIO" Oct 14 23:53:21 volumio volumio[1348]: Card sysdefault:5 'AUDIO'/'SUCA HIFI USB AUDIO at usb-0000:00:14.0-4, high speed' Oct 14 23:53:21 volumio volumio[1348]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 1 Oct 14 23:53:21 volumio volumio[1348]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 14 23:53:21 volumio volumio[1348]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 1","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 1\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)"} Oct 14 23:53:21 volumio volumio[1348]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 Oct 14 23:53:21 volumio volumio[1348]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 14 23:53:21 volumio volumio[1348]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\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)"} Oct 14 23:53:21 volumio volumio[1348]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Oct 14 23:53:21 volumio volumio[1348]: /bin/sh: 1: /usr/local/bin/alsacap: not found Oct 14 23:53:21 volumio volumio[1348]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\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)"} Oct 14 23:53:21 volumio volumio[1348]: amixer -c 5 info | grep "HIFI USB AUDIO" Oct 14 23:53:21 volumio volumio[1348]: Card sysdefault:5 'AUDIO'/'SUCA HIFI USB AUDIO at usb-0000:00:14.0-4, high speed' Oct 14 23:53:21 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:21.899+04:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.3.3:38686 @ 0xc0000e3a40" selectedOutputId=5 Oct 14 23:53:21 volumio volumio[1348]: info: Received Get System Info Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:53:21 volumio volumio[1348]: info: Discovery: Getting this device information Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::volumioGetState Oct 14 23:53:21 volumio volumio[1348]: info: CorePlayQueue::getTrack 0 Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:53:21 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:21.952+04:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.3.3:38686 @ 0xc0000e3a40" currentVersion=4.119 latestVersion=4.119 Oct 14 23:53:21 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:21.952+04:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.3.3:38686 @ 0xc0000e3a40" status=UPDATE_STATUS_NONE progress=0 Oct 14 23:53:21 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:21.953+04:00 level=INFO msg="emitting user changed event" component=server peer="192.168.3.3:38686 @ 0xc0000e3a40" userId=upX1OxR1H3TrvOKutmGOhLt2ySM2 Oct 14 23:53:21 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:21.953+04:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.3.3:38686 @ 0xc0000e3a40" providers=9 Oct 14 23:53:21 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:21.953+04:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.3.3:38686 @ 0xc0000e3a40" plugins=65 Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::volumioGetState Oct 14 23:53:21 volumio volumio[1348]: info: CorePlayQueue::getTrack 0 Oct 14 23:53:21 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:21.958+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.3.3:38686 @ 0xc0000e3a40" state=STATUS_STOPPED positionMs=0 volume=100 Oct 14 23:53:21 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:21.958+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.3:38686 @ 0xc0000e3a40" id="mnt/USB/intel240/Music/Boytronic/1983 - The Working Model (Deluxe Edition)/001. Boytronic - Recycled.flac" title=Recycled Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:53:21 volumio volumio[1348]: info: Discovery: Getting this device information Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::volumioGetState Oct 14 23:53:21 volumio volumio[1348]: info: CorePlayQueue::getTrack 0 Oct 14 23:53:21 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:53:22 volumio volumio[1348]: verbose: New Socket.io Connection to 192.168.3.56:3000 from 192.168.3.3 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 14 23:53:22 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:53:22 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:53:22 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:22.911+04:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.3.3:38686 @ 0xc0000e3a40" latency=-955.079299ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 14 23:53:22 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:22.936+04:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686 @ 0xc0000e3a40" latency=-956.998562ms timeout=10s Oct 14 23:53:23 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:23.114+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686 @ 0xc0000e3a40" latency=-956.998562ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=175.386714ms Oct 14 23:53:23 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:23.131+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686 @ 0xc0000e3a40" latency=-956.998562ms timeout=10s endpoint=http://pushupdates.volumio.org duration=192.273027ms Oct 14 23:53:23 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:23.200+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686 @ 0xc0000e3a40" latency=-956.998562ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=263.974408ms Oct 14 23:53:23 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:23.231+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686 @ 0xc0000e3a40" latency=-956.998562ms timeout=10s endpoint=https://www.googleapis.com duration=290.837041ms Oct 14 23:53:23 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:23.232+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686 @ 0xc0000e3a40" latency=-956.998562ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=293.806811ms Oct 14 23:53:23 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:23.245+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686 @ 0xc0000e3a40" latency=-956.998562ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=308.262798ms Oct 14 23:53:23 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:23.253+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686 @ 0xc0000e3a40" latency=-956.998562ms timeout=10s endpoint=https://securetoken.googleapis.com duration=314.582565ms Oct 14 23:53:23 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:23.359+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686 @ 0xc0000e3a40" latency=-956.998562ms timeout=10s endpoint=https://functions.volumio.cloud duration=419.892821ms Oct 14 23:53:23 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:23.361+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686 @ 0xc0000e3a40" latency=-956.998562ms timeout=10s endpoint=https://functions.volumio.cloud duration=424.718415ms Oct 14 23:53:23 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:23.372+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686 @ 0xc0000e3a40" latency=-956.998562ms timeout=10s endpoint=https://google.com duration=433.153881ms Oct 14 23:53:23 volumio bluealsa[1075]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_5C_B2_F1_9A_44_7E, ...) Oct 14 23:53:23 volumio rfkill[2462]: unblock set for type bluetooth Oct 14 23:53:23 volumio sudo[2459]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 14 23:53:23 volumio sudo[2459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 23:53:23 volumio sudo[2463]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 14 23:53:23 volumio sudo[2463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 23:53:23 volumio sudo[2459]: pam_unix(sudo:session): session closed for user root Oct 14 23:53:23 volumio sudo[2463]: pam_unix(sudo:session): session closed for user root Oct 14 23:53:23 volumio volumio[1348]: verbose: New Socket.io Connection to 192.168.3.56 from 192.168.3.3 UA: Mozilla/5.0 (Linux; Android 16; RMX3851 Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/156.0.8078.25 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 14 23:53:23 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:23.671+04:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686 @ 0xc0000e3a40" latency=-956.998562ms timeout=10s endpoint=http://cddb.volumio.org duration=733.961736ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 14 23:53:23 volumio sudo[2467]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 14 23:53:23 volumio sudo[2467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 23:53:23 volumio sudo[2467]: pam_unix(sudo:session): session closed for user root Oct 14 23:53:23 volumio sudo[2469]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 14 23:53:23 volumio sudo[2469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 23:53:23 volumio sudo[2469]: pam_unix(sudo:session): session closed for user root Oct 14 23:53:23 volumio volumio[1348]: verbose: New Socket.io Connection to 192.168.3.56 from 192.168.3.3 UA: Mozilla/5.0 (Linux; Android 16; RMX3851 Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/156.0.8078.25 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 14 23:53:23 volumio volumio[1348]: info: CoreCommandRouter::volumioGetState Oct 14 23:53:23 volumio volumio[1348]: info: CorePlayQueue::getTrack 0 Oct 14 23:53:23 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 14 23:53:23 volumio volumio[1348]: info: Listing playlists Oct 14 23:53:23 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 14 23:53:23 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:53:23 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:53:23 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 14 23:53:24 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:24.126+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686 @ 0xc0000e3a40" latency=-956.998562ms timeout=10s endpoint=http://plugins.volumio.org duration=1.186389665s Oct 14 23:53:24 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:24.274+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686 @ 0xc0000e3a40" latency=-956.998562ms timeout=10s endpoint=https://database.volumio.cloud duration=1.334520536s Oct 14 23:53:25 volumio volumio[1348]: info: CoreCommandRouter::volumioGetQueue Oct 14 23:53:25 volumio volumio[1348]: info: CoreStateMachine::getQueue Oct 14 23:53:25 volumio volumio[1348]: info: CorePlayQueue::getQueue Oct 14 23:53:25 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:25.514+04:00 level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Oct 14 23:53:25 volumio dbus-daemon[933]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.21" (uid=0 pid=2320 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=983 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Oct 14 23:53:27 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 23:53:27 volumio volumio[1348]: info: Received Get System Info Oct 14 23:53:27 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:53:27 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:53:27 volumio volumio[1348]: info: Discovery: Getting this device information Oct 14 23:53:27 volumio volumio[1348]: info: CoreCommandRouter::volumioGetState Oct 14 23:53:27 volumio volumio[1348]: info: CorePlayQueue::getTrack 0 Oct 14 23:53:27 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:53:27 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 14 23:53:27 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 14 23:53:31 volumio volumio[1348]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 23:53:42 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 14 23:53:42 volumio volumio[1348]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 14 23:53:42 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 14 23:53:42 volumio volumio[1348]: info: Received Get System Version Oct 14 23:53:42 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 23:53:42 volumio volumio[1348]: info: Received Get System Info Oct 14 23:53:42 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:53:42 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:53:42 volumio volumio[1348]: info: Discovery: Getting this device information Oct 14 23:53:42 volumio volumio[1348]: info: CoreCommandRouter::volumioGetState Oct 14 23:53:42 volumio volumio[1348]: info: CorePlayQueue::getTrack 0 Oct 14 23:53:42 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:53:42 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:42.336+04:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-924.389192ms timeout=10s Oct 14 23:53:42 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:42.417+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-924.389192ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=79.918542ms Oct 14 23:53:42 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:42.418+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-924.389192ms timeout=10s endpoint=http://pushupdates.volumio.org duration=80.185403ms Oct 14 23:53:42 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:42.419+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-924.389192ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=80.736336ms Oct 14 23:53:42 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:42.423+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-924.389192ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=85.085817ms Oct 14 23:53:42 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:42.455+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-924.389192ms timeout=10s endpoint=https://google.com duration=118.098413ms Oct 14 23:53:42 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:42.485+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-924.389192ms timeout=10s endpoint=https://www.googleapis.com duration=147.808359ms Oct 14 23:53:42 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:53:42 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:53:42 volumio volumio[1348]: info: Discovery: Getting this device information Oct 14 23:53:42 volumio volumio[1348]: info: CoreCommandRouter::volumioGetState Oct 14 23:53:42 volumio volumio[1348]: info: CorePlayQueue::getTrack 0 Oct 14 23:53:42 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:53:42 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:42.515+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-924.389192ms timeout=10s endpoint=https://securetoken.googleapis.com duration=178.159866ms Oct 14 23:53:42 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:42.516+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-924.389192ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=178.312396ms Oct 14 23:53:42 volumio volumio[1348]: verbose: New Socket.io Connection to 192.168.3.56:3000 from 192.168.3.3 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Oct 14 23:53:42 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:53:42 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:53:42 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:42.717+04:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-924.389192ms timeout=10s endpoint=http://cddb.volumio.org duration=379.339293ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 14 23:53:42 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:42.762+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-924.389192ms timeout=10s endpoint=https://functions.volumio.cloud duration=424.140907ms Oct 14 23:53:42 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:42.765+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-924.389192ms timeout=10s endpoint=https://functions.volumio.cloud duration=427.155711ms Oct 14 23:53:42 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:42.780+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-924.389192ms timeout=10s endpoint=https://database.volumio.cloud duration=439.511862ms Oct 14 23:53:42 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:42.997+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-924.389192ms timeout=10s endpoint=http://plugins.volumio.org duration=658.535665ms Oct 14 23:53:47 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:47.698+04:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-941.818127ms timeout=10s Oct 14 23:53:47 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 23:53:47 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:53:47 volumio volumio[1348]: info: Discovery: Getting this device information Oct 14 23:53:47 volumio volumio[1348]: info: CoreCommandRouter::volumioGetState Oct 14 23:53:47 volumio volumio[1348]: info: CorePlayQueue::getTrack 0 Oct 14 23:53:47 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:53:47 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:47.776+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-941.818127ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=77.831329ms Oct 14 23:53:47 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:47.779+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-941.818127ms timeout=10s endpoint=http://pushupdates.volumio.org duration=80.148673ms Oct 14 23:53:47 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:47.781+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-941.818127ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=82.335239ms Oct 14 23:53:47 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:47.784+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-941.818127ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=85.167814ms Oct 14 23:53:47 volumio volumio[1348]: verbose: New Socket.io Connection to 192.168.3.56:3000 from 192.168.3.3 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Oct 14 23:53:47 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 23:53:47 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 23:53:47 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:47.809+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-941.818127ms timeout=10s endpoint=https://google.com duration=110.093375ms Oct 14 23:53:47 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:47.847+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-941.818127ms timeout=10s endpoint=https://www.googleapis.com duration=147.647535ms Oct 14 23:53:47 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:47.872+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-941.818127ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=173.188805ms Oct 14 23:53:47 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:47.878+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-941.818127ms timeout=10s endpoint=https://securetoken.googleapis.com duration=180.010984ms Oct 14 23:53:48 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:48.077+04:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-941.818127ms timeout=10s endpoint=http://cddb.volumio.org duration=377.600431ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 14 23:53:48 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:48.123+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-941.818127ms timeout=10s endpoint=https://functions.volumio.cloud duration=423.921877ms Oct 14 23:53:48 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:48.123+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-941.818127ms timeout=10s endpoint=https://functions.volumio.cloud duration=424.078546ms Oct 14 23:53:48 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:48.138+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-941.818127ms timeout=10s endpoint=https://database.volumio.cloud duration=439.05554ms Oct 14 23:53:48 volumio volumio5-onboarding[2320]: time=2026-10-14T23:53:48.350+04:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.3.3:38686,00:00:00:00:00:00%01 @ 0xc0000e3a40" latency=-941.818127ms timeout=10s endpoint=http://plugins.volumio.org duration=651.276169ms Oct 14 23:53:48 volumio volumio[1348]: info: CALLMETHOD: user_interface now_playing configureVolumioKiosk [object Object] Oct 14 23:53:48 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: now_playing , configureVolumioKiosk Oct 14 23:53:48 volumio volumio[1348]: info: [now-playing] Backing up /opt/volumiokiosk.sh to /home/volumio/.now_playing/volumiokiosk.sh.bak Oct 14 23:53:48 volumio sudo[2553]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:3000/localhost:4004/g /opt/volumiokiosk.sh Oct 14 23:53:48 volumio sudo[2553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 23:53:48 volumio sudo[2553]: pam_unix(sudo:session): session closed for user root Oct 14 23:53:48 volumio volumio[1348]: [sudo] password for volumio: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Oct 14 23:53:48 volumio sudo[2556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Oct 14 23:53:48 volumio sudo[2556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 23:53:48 volumio sudo[2556]: pam_unix(sudo:session): session closed for user root Oct 14 23:53:48 volumio volumio[1348]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl restart volumio-kiosk Oct 14 23:53:48 volumio sudo[2559]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk Oct 14 23:53:48 volumio sudo[2559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 23:53:48 volumio systemd[1]: Stopping volumio-kiosk.service - Start Volumio Kiosk... Oct 14 23:53:48 volumio startx[1419]: xinit: connection to X server lost Oct 14 23:53:48 volumio startx[1419]: Oct 14 23:53:48 volumio startx[1419]: waiting for X server to shut down Oct 14 23:53:48 volumio startx[1420]: (II) Server terminated successfully (0). Closing log file. Oct 14 23:53:49 volumio startx[1419]: xinit: unexpected signal 15 Oct 14 23:53:49 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Oct 14 23:53:49 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Oct 14 23:53:49 volumio systemd[1]: Stopped volumio-kiosk.service - Start Volumio Kiosk. Oct 14 23:53:49 volumio systemd[1]: volumio-kiosk.service: Consumed 27.617s CPU time. Oct 14 23:53:49 volumio systemd[1]: Started volumio-kiosk.service - Start Volumio Kiosk. Oct 14 23:53:49 volumio sudo[2559]: pam_unix(sudo:session): session closed for user root Oct 14 23:53:49 volumio volumio[1348]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 23:53:49 volumio startx[2588]: X.Org X Server 1.21.1.7 Oct 14 23:53:49 volumio startx[2588]: X Protocol Version 11, Revision 0 Oct 14 23:53:49 volumio startx[2588]: Current Operating System: Linux volumio 6.12.57-volumio #2 SMP PREEMPT_DYNAMIC Thu Nov 13 17:08:37 CET 2025 x86_64 Oct 14 23:53:49 volumio startx[2588]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet video=HDMI-A-1:panel_orientation=upside_down loglevel=0 bootdelay=5 biosdevname=0 imgpart=UUID=989265ff-d248-4f89-8e52-b7555c637af2 bootpart=UUID=DDF4-322F datapart=UUID=5a1dd212-cb91-4291-a486-23f2c88c837a hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=native net.ifnames=0 use_kmsg=no plymouth=0 video=efifb fbcon=rotate:0 Oct 14 23:53:49 volumio startx[2588]: xorg-server 2:21.1.7-3+deb12u11 (https://www.debian.org/support) Oct 14 23:53:49 volumio startx[2588]: Current version of pixman: 0.42.2 Oct 14 23:53:49 volumio startx[2588]: Before reporting problems, check http://wiki.x.org Oct 14 23:53:49 volumio startx[2588]: to make sure that you have the latest version. Oct 14 23:53:49 volumio startx[2588]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 14 23:53:49 volumio startx[2588]: (++) from command line, (!!) notice, (II) informational, Oct 14 23:53:49 volumio startx[2588]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 14 23:53:49 volumio startx[2588]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Oct 14 23:53:49 2026 Oct 14 23:53:49 volumio startx[2588]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 14 23:53:52 volumio volumio[1348]: info: [now-playing] Weather not configured: Weather is not set up: add Geographic Coordinates in Localization. Oct 14 23:53:52 volumio volumio[1348]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 14 23:53:53 volumio volumio[1348]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Oct 14 23:53:53 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Oct 14 23:53:53 volumio volumio[1348]: info: Listing playlists Oct 14 23:53:53 volumio volumio[1348]: info: CoreCommandRouter::volumioGetQueue Oct 14 23:53:53 volumio volumio[1348]: info: CoreStateMachine::getQueue Oct 14 23:53:53 volumio volumio[1348]: info: CorePlayQueue::getQueue Oct 14 23:53:53 volumio volumio[1348]: info: CoreCommandRouter::volumioGetVisibleSources Oct 14 23:53:53 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 23:53:53 volumio volumio[1348]: info: CoreCommandRouter::volumioGetState Oct 14 23:53:53 volumio volumio[1348]: info: CorePlayQueue::getTrack 0 Oct 14 23:53:59 volumio volumio[1348]: info: CALLMETHOD: user_interface now_playing configRestoreConfigFromBackup [object Object] Oct 14 23:53:59 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: now_playing , configRestoreConfigFromBackup Oct 14 23:53:59 volumio volumio[1348]: info: [now-playing] ConfigUpdater: config is up to date. Oct 14 23:53:59 volumio sudo[2836]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:4004/localhost:3000/g /opt/volumiokiosk.sh Oct 14 23:53:59 volumio sudo[2836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 23:53:59 volumio sudo[2836]: pam_unix(sudo:session): session closed for user root Oct 14 23:53:59 volumio volumio[1348]: [sudo] password for volumio: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Oct 14 23:53:59 volumio sudo[2839]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Oct 14 23:53:59 volumio sudo[2839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 23:53:59 volumio sudo[2839]: pam_unix(sudo:session): session closed for user root Oct 14 23:53:59 volumio volumio[1348]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl restart volumio-kiosk Oct 14 23:53:59 volumio sudo[2842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk Oct 14 23:53:59 volumio sudo[2842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 23:53:59 volumio startx[2587]: xinit: connection to X server lost Oct 14 23:53:59 volumio systemd[1]: Stopping volumio-kiosk.service - Start Volumio Kiosk... Oct 14 23:53:59 volumio startx[2587]: Oct 14 23:54:00 volumio startx[2587]: waiting for X server to shut down Oct 14 23:54:00 volumio startx[2588]: (II) Server terminated successfully (0). Closing log file. Oct 14 23:54:00 volumio startx[2587]: xinit: unexpected signal 15 Oct 14 23:54:00 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Oct 14 23:54:00 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Oct 14 23:54:00 volumio systemd[1]: Stopped volumio-kiosk.service - Start Volumio Kiosk. Oct 14 23:54:00 volumio systemd[1]: volumio-kiosk.service: Consumed 9.076s CPU time. Oct 14 23:54:00 volumio systemd[1]: Started volumio-kiosk.service - Start Volumio Kiosk. Oct 14 23:54:00 volumio sudo[2842]: pam_unix(sudo:session): session closed for user root Oct 14 23:54:00 volumio volumio[1348]: warn: [now-playing] MyBackgroundMonitor stopped Oct 14 23:54:00 volumio volumio[1348]: info: [now-playing] ConfigUpdater: config is up to date. Oct 14 23:54:00 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 23:54:00 volumio volumio[1348]: info: Discovery: Getting this device information Oct 14 23:54:00 volumio volumio[1348]: info: CoreCommandRouter::volumioGetState Oct 14 23:54:00 volumio volumio[1348]: info: CorePlayQueue::getTrack 0 Oct 14 23:54:00 volumio volumio[1348]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 23:54:00 volumio volumio[1348]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 23:54:00 volumio volumio[1348]: Error: Invalid coordinates Oct 14 23:54:00 volumio volumio[1348]: at OpenMeteoAPI.setCoordinates (/data/plugins/user_interface/now_playing/dist/lib/api/open-meteo/index.js:47:15) Oct 14 23:54:00 volumio volumio[1348]: at WeatherAPI.setConfig (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:79:64) Oct 14 23:54:00 volumio volumio[1348]: at ControllerNowPlaying._ControllerNowPlaying_configureWeatherApi (/data/plugins/user_interface/now_playing/dist/index.js:609:39) Oct 14 23:54:00 volumio volumio[1348]: at ControllerNowPlaying._ControllerNowPlaying_doOnStart (/data/plugins/user_interface/now_playing/dist/index.js:629:115) Oct 14 23:54:00 volumio volumio[1348]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 14 23:54:00 volumio volumio[1348]: at async ControllerNowPlaying.configRestoreConfigFromBackup (/data/plugins/user_interface/now_playing/dist/index.js:518:9) Oct 14 23:54:00 volumio volumio[1348]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 23:54:00 volumio startx[2882]: X.Org X Server 1.21.1.7 Oct 14 23:54:00 volumio startx[2882]: X Protocol Version 11, Revision 0 Oct 14 23:54:00 volumio startx[2882]: Current Operating System: Linux volumio 6.12.57-volumio #2 SMP PREEMPT_DYNAMIC Thu Nov 13 17:08:37 CET 2025 x86_64 Oct 14 23:54:00 volumio startx[2882]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet video=HDMI-A-1:panel_orientation=upside_down loglevel=0 bootdelay=5 biosdevname=0 imgpart=UUID=989265ff-d248-4f89-8e52-b7555c637af2 bootpart=UUID=DDF4-322F datapart=UUID=5a1dd212-cb91-4291-a486-23f2c88c837a hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=native net.ifnames=0 use_kmsg=no plymouth=0 video=efifb fbcon=rotate:0 Oct 14 23:54:00 volumio startx[2882]: xorg-server 2:21.1.7-3+deb12u11 (https://www.debian.org/support) Oct 14 23:54:00 volumio startx[2882]: Current version of pixman: 0.42.2 Oct 14 23:54:00 volumio startx[2882]: Before reporting problems, check http://wiki.x.org Oct 14 23:54:00 volumio startx[2882]: to make sure that you have the latest version. Oct 14 23:54:00 volumio startx[2882]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 14 23:54:00 volumio startx[2882]: (++) from command line, (!!) notice, (II) informational, Oct 14 23:54:00 volumio startx[2882]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 14 23:54:00 volumio startx[2882]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Oct 14 23:54:00 2026 Oct 14 23:54:00 volumio startx[2882]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 14 23:54:01 volumio sudo[2888]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 23:53' Oct 14 23:54:01 volumio sudo[2888]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"