Mar 04 16:55:40 volumio ntpd[1181]: CLOCK: time stepped by 2339290.658029 Mar 04 16:55:40 volumio ntpd[1181]: CLOCK: time changed from 2026-02-05 to 2026-03-04 Mar 04 16:55:40 volumio ntpd[1181]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Mar 04 16:55:40 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Mar 04 16:55:40 volumio systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Mar 04 16:55:40 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Mar 04 16:55:40 volumio systemd[1]: e2scrub_all.service: Deactivated successfully. Mar 04 16:55:40 volumio systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Mar 04 16:55:40 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Mar 04 16:55:40 volumio wireless.js[1935]: WIRELESS.JS - INFO: Failed to determine most appropriate reg domain: Error: spawnSync /bin/sh ETIMEDOUT Mar 04 16:55:40 volumio wireless.js[1935]: WIRELESS.JS - INFO: netconfigured file not found, starting hotspot Mar 04 16:55:40 volumio wireless.js[1935]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Mar 04 16:55:40 volumio wireless.js[1935]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Mar 04 16:55:40 volumio wireless.js[1935]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Mar 04 16:55:40 volumio sudo[2228]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 04 16:55:40 volumio sudo[2228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 04 16:55:40 volumio sudo[2228]: pam_unix(sudo:session): session closed for user root Mar 04 16:55:40 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Mar 04 16:55:40 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Mar 04 16:55:40 volumio sudo[2238]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 04 16:55:40 volumio sudo[2238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 04 16:55:40 volumio sudo[2238]: pam_unix(sudo:session): session closed for user root Mar 04 16:55:40 volumio wpa_supplicant[2243]: Successfully initialized wpa_supplicant Mar 04 16:55:40 volumio kernel: Unable to handle kernel NULL pointer dereference at virtual address 0000000000000008 Mar 04 16:55:40 volumio kernel: Mem abort info: Mar 04 16:55:40 volumio kernel: ESR = 0x0000000096000007 Mar 04 16:55:40 volumio kernel: EC = 0x25: DABT (current EL), IL = 32 bits Mar 04 16:55:40 volumio kernel: SET = 0, FnV = 0 Mar 04 16:55:40 volumio kernel: EA = 0, S1PTW = 0 Mar 04 16:55:40 volumio kernel: FSC = 0x07: level 3 translation fault Mar 04 16:55:40 volumio kernel: Data abort info: Mar 04 16:55:40 volumio kernel: ISV = 0, ISS = 0x00000007, ISS2 = 0x00000000 Mar 04 16:55:40 volumio kernel: CM = 0, WnR = 0, TnD = 0, TagAccess = 0 Mar 04 16:55:40 volumio kernel: GCS = 0, Overlay = 0, DirtyBit = 0, Xs = 0 Mar 04 16:55:40 volumio kernel: user pgtable: 4k pages, 39-bit VAs, pgdp=0000000003548000 Mar 04 16:55:40 volumio kernel: [0000000000000008] pgd=0800000013ced003, p4d=0800000013ced003, pud=0800000013ced003, pmd=08000000036f9003, pte=0000000000000000 Mar 04 16:55:40 volumio kernel: Internal error: Oops: 0000000096000007 [#1] PREEMPT SMP Mar 04 16:55:40 volumio kernel: Modules linked in: sha3_generic jitterentropy_rng sha512_generic sha512_arm64 drbg algif_hash algif_skcipher af_alg bnep nft_chain_nat xt_REDIRECT nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 xt_tcpudp nft_compat nf_tables nfnetlink 8021q garp stp llc aes_ce_blk aes_ce_cipher ghash_ce binfmt_misc brcmfmac_wcc gf128mul sha2_ce hci_uart btbcm sha256_arm64 bluetooth rpi_hevc_dec sha1_ce brcmfmac sha1_generic pisp_be v4l2_mem2mem brcmutil ecdh_generic ecc raspberrypi_hwmon videobuf2_dma_contig cfg80211 libaes videobuf2_memops videobuf2_v4l2 videodev rfkill raspberrypi_gpiomem videobuf2_common mc nvmem_rmem rp1_adc uio_pdrv_genirq uio i2c_dev dm_mod ip_tables x_tables ipv6 ads7846 fb_hx8357d(C) fb_st7789v(C) fb_st7735r(C) fb_ili9341(C) fb_ili9340(C) fbtft(C) panel_waveshare_dsi_v2 panel_waveshare_dsi panel_ilitek_ili9881c panel_raspberrypi_touchscreen i2c_bcm2835 snd_soc_bcm2835_i2s snd_bcm2835(C) squashfs overlay nls_iso8859_1 fuse spidev vc4 snd_soc_hdmi_codec drm_display_helper cec drm_dma_helper Mar 04 16:55:40 volumio kernel: snd_soc_core snd_compress snd_pcm_dmaengine i2c_brcmstb snd_pcm i2c_designware_platform spi_bcm2835 snd_timer i2c_designware_core v3d gpu_sched rp1_pio drm_shmem_helper drm_kms_helper snd rp1_mailbox drm drm_panel_orientation_quirks backlight rp1_fw gpio_keys Mar 04 16:55:40 volumio kernel: CPU: 1 UID: 0 PID: 272 Comm: kworker/1:3 Tainted: G C 6.12.47-v8+ #1904 Mar 04 16:55:40 volumio kernel: Tainted: [C]=CRAP Mar 04 16:55:40 volumio kernel: Hardware name: Raspberry Pi 5 Model B Rev 1.0 (DT) Mar 04 16:55:40 volumio kernel: Workqueue: events brcmf_fweh_event_worker [brcmfmac] Mar 04 16:55:40 volumio kernel: pstate: 60400009 (nZCv daif +PAN -UAO -TCO -DIT -SSBS BTYPE=--) Mar 04 16:55:40 volumio kernel: pc : brcmf_p2p_ifp_removed+0x44/0xc8 [brcmfmac] Mar 04 16:55:40 volumio kernel: lr : brcmf_p2p_ifp_removed+0x40/0xc8 [brcmfmac] Mar 04 16:55:40 volumio kernel: sp : ffffffc080abbc40 Mar 04 16:55:40 volumio kernel: x29: ffffffc080abbc40 x28: ffffff800287f700 x27: ffffff800287f750 Mar 04 16:55:40 volumio kernel: x26: ffffff800cbf88c8 x25: 0000000000000000 x24: 0000000000000000 Mar 04 16:55:40 volumio kernel: x23: 0000000000000001 x22: 0000000000000000 x21: 0000000000000000 Mar 04 16:55:40 volumio kernel: x20: ffffffd07508f4c8 x19: 0000000000000000 x18: 0000000000000000 Mar 04 16:55:40 volumio kernel: x17: 0000000000000000 x16: ffffffd08d1adc90 x15: 000000005e67cf2e Mar 04 16:55:40 volumio kernel: x14: 0000000000000000 x13: 000000000000f8e5 x12: 0000000000000001 Mar 04 16:55:40 volumio kernel: x11: 0000000000000000 x10: 0000000000306c77 x9 : ffffffd07507d000 Mar 04 16:55:40 volumio kernel: x8 : ffffff8002d98000 x7 : 0000000000000000 x6 : ffffffc080abbbe8 Mar 04 16:55:40 volumio kernel: x5 : ffffffc080abbc10 x4 : 00000000ffffffd8 x3 : 0000000000000000 Mar 04 16:55:40 volumio kernel: x2 : 0000000000000000 x1 : 0000000000000000 x0 : ffffff8002d98000 Mar 04 16:55:40 volumio kernel: Call trace: Mar 04 16:55:40 volumio kernel: brcmf_p2p_ifp_removed+0x44/0xc8 [brcmfmac] Mar 04 16:55:40 volumio kernel: brcmf_remove_interface+0x178/0x288 [brcmfmac] Mar 04 16:55:40 volumio kernel: brcmf_fweh_event_worker+0x35c/0x510 [brcmfmac] Mar 04 16:55:40 volumio kernel: process_one_work+0x15c/0x3c0 Mar 04 16:55:40 volumio kernel: worker_thread+0x2e4/0x3f0 Mar 04 16:55:40 volumio kernel: kthread+0x120/0x130 Mar 04 16:55:40 volumio kernel: ret_from_fork+0x10/0x20 Mar 04 16:55:40 volumio kernel: Code: 910fa001 52800080 940001e7 f9400673 (f9400660) Mar 04 16:55:40 volumio kernel: ---[ end trace 0000000000000000 ]--- Mar 04 16:55:40 volumio bluealsa[1108]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_65_06_40_AF_22_CF, ...) Mar 04 16:55:40 volumio volumio[1387]: info: Discovery: A device disappeared from network Mar 04 16:55:40 volumio volumio[1387]: info: Discovery: Device green disappeared from network Mar 04 16:55:40 volumio volumio[1387]: info: Discovery: Disconnected from remote: 192.168.1.33 Mar 04 16:55:40 volumio volumio[1387]: info: Discovery: A device disappeared from network Mar 04 16:55:40 volumio volumio[1387]: info: Discovery: Device blue disappeared from network Mar 04 16:55:40 volumio volumio[1387]: info: Discovery: Disconnected from remote: 192.168.1.32 Mar 04 16:55:41 volumio volumio[1387]: info: Discovery: adding effec07d-3eae-4dbd-b53e-28e886c82f1b Mar 04 16:55:41 volumio volumio[1387]: info: Discovery: Found device Blue Mar 04 16:55:41 volumio volumio[1387]: info: Discovery: Connecting to remote: 192.168.1.32 Mar 04 16:55:41 volumio volumio[1387]: info: Discovery: Connected to remote: 192.168.1.32 Mar 04 16:55:41 volumio volumio[1387]: info: Discovery: adding a61ef014-c25f-4dac-aa74-0b5142cc939c Mar 04 16:55:41 volumio volumio[1387]: info: Discovery: Found device Green Mar 04 16:55:41 volumio volumio[1387]: info: Discovery: Connecting to remote: 192.168.1.33 Mar 04 16:55:41 volumio volumio[1387]: info: Discovery: Connected to remote: 192.168.1.33 Mar 04 16:55:41 volumio volumio5-onboarding[1783]: time=2026-03-04T15:55:41.391Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.13:48646 @ 0x2542840" latency=-149.943469ms timeout=10s Mar 04 16:55:41 volumio volumio5-onboarding[1783]: time=2026-03-04T15:55:41.391Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.13:48646 @ 0x2542840" Mar 04 16:55:41 volumio volumio5-onboarding[1783]: time=2026-03-04T15:55:41.391Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.13:48646 @ 0x2542840" latency=-147.552691ms platform=PLATFORM_ANDROID version=5.260120.0 Mar 04 16:55:41 volumio volumio[1387]: info: Received Get System Info Mar 04 16:55:41 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 16:55:41 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 16:55:41 volumio volumio[1387]: info: Discovery: Getting this device information Mar 04 16:55:41 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Mar 04 16:55:41 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Mar 04 16:55:41 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 16:55:41 volumio volumio5-onboarding[1783]: time=2026-03-04T15:55:41.393Z level=INFO msg="emitting device name changed event" component=server peer="192.168.1.13:48646 @ 0x2542840" name=Volumio Mar 04 16:55:41 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 04 16:55:41 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 04 16:55:41 volumio volumio5-onboarding[1783]: time=2026-03-04T15:55:41.393Z level=INFO msg="emitting device language changed event" component=server peer="192.168.1.13:48646 @ 0x2542840" language=en Mar 04 16:55:41 volumio volumio5-onboarding[1783]: time=2026-03-04T15:55:41.393Z level=INFO msg="updating device settings from app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.13:48646 @ 0x2542840" latency=-147.552691ms language=en timezone=Europe/Paris Mar 04 16:55:41 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 04 16:55:41 volumio volumio5-onboarding[1783]: time=2026-03-04T15:55:41.394Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.13:48646 @ 0x2542840" timezone=UTC Mar 04 16:55:41 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 04 16:55:41 volumio volumio[1387]: info: Loading i18n strings for locale en Mar 04 16:55:41 volumio volumio[1387]: Updating browse sources language Mar 04 16:55:41 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 16:55:41 volumio volumio5-onboarding[1783]: time=2026-03-04T15:55:41.404Z level=INFO msg="emitting device language changed event" component=server peer="192.168.1.13:48646 @ 0x2542840" language=en Mar 04 16:55:41 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Mar 04 16:55:41 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 04 16:55:41 volumio volumio[1387]: info: Setting timezone to Europe/Paris Mar 04 16:55:41 volumio wpa_supplicant[2243]: Failed to create interface p2p-dev-wlan0: -5 (Input/output error) Mar 04 16:55:41 volumio wpa_supplicant[2243]: nl80211: Failed to create a P2P Device interface p2p-dev-wlan0 Mar 04 16:55:41 volumio wpa_supplicant[2243]: P2P: Failed to enable P2P Device interface Mar 04 16:55:41 volumio volumio5-onboarding[1783]: time=2026-03-04T15:55:41.681Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.13:48646 @ 0x2542840" available=true connected=true macAddress=2c:cf:67:5e:e5:f7 ip4Address=192.168.1.111/24 ip6Address= Mar 04 16:55:41 volumio volumio5-onboarding[1783]: time=2026-03-04T15:55:41.682Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.13:48646 @ 0x2542840" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 04 16:55:41 volumio volumio5-onboarding[1783]: time=2026-03-04T15:55:41.682Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.13:48646 @ 0x2542840" setupComplete=false Mar 04 16:55:41 volumio kernel: ieee80211 phy0: brcmf_p2p_create_p2pdev: timeout occurred Mar 04 16:55:41 volumio kernel: ieee80211 phy0: brcmf_cfg80211_add_iface: add iface p2p-dev-wlan0 type 10 failed: err=-5 Mar 04 16:55:41 volumio wireless.js[1935]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 2339302984ms Mar 04 16:55:41 volumio wireless.js[1935]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Mar 04 16:55:41 volumio sudo[2252]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 04 16:55:41 volumio sudo[2252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 16:55:41 volumio sudo[2252]: pam_unix(sudo:session): session closed for user root Mar 04 16:55:41 volumio wireless.js[1935]: Failed to connect to non-global ctrl_ifname: wlan0 error: No such file or directory Mar 04 16:55:41 volumio sudo[2265]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Paris /etc/localtime Mar 04 16:55:41 volumio sudo[2265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 16:55:41 volumio sudo[2265]: pam_unix(sudo:session): session closed for user root Mar 04 16:55:41 volumio sudo[2276]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 04 16:55:41 volumio sudo[2276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 16:55:41 volumio sudo[2276]: pam_unix(sudo:session): session closed for user root Mar 04 16:55:41 volumio sudo[2280]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Paris Mar 04 16:55:41 volumio sudo[2280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 16:55:41 volumio dbus-daemon[908]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.22' (uid=0 pid=2281 comm="/usr/bin/timedatectl set-timezone Europe/Paris") Mar 04 16:55:42 volumio kernel: Bluetooth: hci0: command tx timeout Mar 04 16:55:44 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:55:44 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:55:44 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:55:46 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:55:46 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:55:46 volumio wireless.js[1935]: WIRELESS.JS - INFO: Could not notify systemd about wireless ready: Error: Command failed: systemd-notify --ready Mar 04 16:55:46 volumio wireless.js[1935]: Failed to invoke barrier: Connection timed out Mar 04 16:55:48 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:55:48 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:55:50 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:55:50 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:55:51 volumio volumio5-onboarding[1783]: time=2026-03-04T15:55:51.391Z level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.13:48646 @ 0x2542840" latency=-149.943469ms timeout=10s error="failed to get output devices: could not get extended output devices: context deadline exceeded" Mar 04 16:55:52 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:55:52 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:55:54 volumio volumio5-onboarding[1783]: time=2026-03-04T15:55:54.210Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.13:48646 @ 0x2542840" latency=-120.903403ms timeout=10s Mar 04 16:55:54 volumio volumio5-onboarding[1783]: time=2026-03-04T15:55:54.210Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.13:48646 @ 0x2542840" Mar 04 16:55:54 volumio volumio5-onboarding[1783]: time=2026-03-04T15:55:54.210Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.13:48646 @ 0x2542840" latency=-118.707606ms platform=PLATFORM_ANDROID version=5.260120.0 Mar 04 16:55:54 volumio volumio5-onboarding[1783]: time=2026-03-04T15:55:54.210Z level=INFO msg="updating device settings from app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.13:48646 @ 0x2542840" latency=-118.707606ms language=en timezone=Europe/Paris Mar 04 16:55:54 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:55:54 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:55:56 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:55:56 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:55:58 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:55:58 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:00 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:00 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:02 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:02 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:04 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:04.210Z level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.13:48646 @ 0x2542840" latency=-120.903403ms timeout=10s error="failed to get system info: could not get system info: context deadline exceeded" Mar 04 16:56:04 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:04 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:06 volumio dbus-daemon[908]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Mar 04 16:56:06 volumio sudo[2280]: pam_unix(sudo:session): session closed for user root Mar 04 16:56:06 volumio volumio[1387]: Failed to set time zone: Connection timed out Mar 04 16:56:06 volumio volumio[1387]: info: Could not set timezone, retrying Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 04 16:56:06 volumio volumio[1387]: info: Fetching Streaming Services browse cache Mar 04 16:56:06 volumio volumio[1387]: info: Discovery: Started advertising with name: Volumio Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::volumioGetBrowseSources Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 16:56:06 volumio volumio[1387]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 04 16:56:06 volumio volumio[1387]: info: Completed starting MyVolumio Plugin Mar 04 16:56:06 volumio volumio[1387]: [Metrics] CommandRouter: 41s 346.65ms Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::volumiosetStartupVolume Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::Close All Modals sent Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::Close All Modals sent Mar 04 16:56:06 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:06 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 04 16:56:06 volumio volumio[1387]: info: Received Get System Info Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 16:56:06 volumio volumio[1387]: info: Discovery: Getting this device information Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::volumioGetState Mar 04 16:56:06 volumio volumio[1387]: info: CorePlayQueue::getTrack 0 Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 04 16:56:06 volumio volumio[1387]: info: Loading i18n strings for locale en Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 04 16:56:06 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:06.853Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.13:48646 @ 0x2542840" timezone=Europe/Paris Mar 04 16:56:06 volumio volumio[1387]: amixer -c 0 info | grep "vc4-hdmi-0" Mar 04 16:56:06 volumio volumio[1387]: Card sysdefault:0 'vc4hdmi0'/'vc4-hdmi-0' Mar 04 16:56:06 volumio volumio[1387]: amixer -c 0 info | grep "vc4-hdmi-0" Mar 04 16:56:06 volumio volumio[1387]: Card sysdefault:0 'vc4hdmi0'/'vc4-hdmi-0' Mar 04 16:56:06 volumio volumio[1387]: amixer -c 1 info | grep "vc4-hdmi-1" Mar 04 16:56:06 volumio volumio[1387]: Card sysdefault:1 'vc4hdmi1'/'vc4-hdmi-1' Mar 04 16:56:06 volumio volumio[1387]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Mar 04 16:56:06 volumio volumio[1387]: aplay: main:831: audio open error: Unknown error 524 Mar 04 16:56:06 volumio volumio[1387]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 04 16:56:06 volumio volumio[1387]: Updating browse sources language Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 16:56:06 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:06.913Z level=INFO msg="emitting device language changed event" component=server peer="192.168.1.13:48646 @ 0x2542840" language=en Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 04 16:56:06 volumio volumio-remote-updater[918]: Test mode disabled Mar 04 16:56:06 volumio volumio-remote-updater[918]: Alpha mode disabled Mar 04 16:56:06 volumio volumio-remote-updater[918]: Alpha legacy test mode disabled Mar 04 16:56:06 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 04 16:56:06 volumio volumio[1387]: info: Setting timezone to Europe/Paris Mar 04 16:56:06 volumio sudo[2353]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 04 16:56:06 volumio sudo[2353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 16:56:06 volumio sudo[2353]: pam_unix(sudo:session): session closed for user root Mar 04 16:56:06 volumio sudo[2357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Paris /etc/localtime Mar 04 16:56:06 volumio sudo[2357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 16:56:06 volumio sudo[2357]: pam_unix(sudo:session): session closed for user root Mar 04 16:56:06 volumio sudo[2361]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 04 16:56:06 volumio sudo[2361]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 16:56:06 volumio sudo[2361]: pam_unix(sudo:session): session closed for user root Mar 04 16:56:07 volumio sudo[2365]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Paris Mar 04 16:56:07 volumio sudo[2365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 16:56:07 volumio dbus-daemon[908]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.27' (uid=0 pid=2366 comm="/usr/bin/timedatectl set-timezone Europe/Paris") Mar 04 16:56:08 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:08 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0001/char0002, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0001/char0004, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0001/char0006, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0001/char0008, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0001, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0014/char0015, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0014/char0017, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0014/char0019, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0014, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char0029/desc002b, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char0029, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char002c/desc002e, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char002c, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char002f/desc0031, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char002f, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char0032/desc0034, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char0032, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char0035/desc0037, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char0035, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char0038/desc003a, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char0038, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char003b/desc003d, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char003b, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char003e/desc0040, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char003e, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char0041, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char0043/desc0045, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char0043, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char0046/desc0048, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char0046, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char0049/desc004b, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char0049, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028/char004c, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0028, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char005b/desc005d, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char005b, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char005e, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char0060/desc0062, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char0060, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char0063/desc0065, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char0063, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char0066/desc0068, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char0066, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char0069, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char006b/desc006d, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char006b, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char006e/desc0070, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char006e, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char0071/desc0073, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char0071, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char0074, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char0076/desc0078, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char0076, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char0079/desc007b, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char0079, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char007c/desc007e, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a/char007c, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service005a, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0082/char0083, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0082, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0090/char0091, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0090, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0093/char0094/desc0096, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0093/char0094/desc0097, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0093/char0094, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7/service0093, ...) Mar 04 16:56:09 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_86_64_BE_DD_A7, ...) Mar 04 16:56:09 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:09.444Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.13:48646 @ 0x2542840" latency=-149.829578ms platform=PLATFORM_ANDROID version=5.260120.0 Mar 04 16:56:09 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:09.444Z level=INFO msg="updating device settings from app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.13:48646 @ 0x2542840" latency=-149.829578ms language=en timezone=Europe/Paris Mar 04 16:56:09 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:09.444Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.13:48646 @ 0x2542840" latency=-150.406023ms timeout=10s Mar 04 16:56:09 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:09.444Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.13:48646 @ 0x2542840" Mar 04 16:56:10 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:10 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:12 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:12 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:14 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:14 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:16 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:16 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:18 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:18 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:19 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:19.445Z level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.13:48646 @ 0x2542840" latency=-150.406023ms timeout=10s error="failed to get system info: could not get system info: context deadline exceeded" Mar 04 16:56:20 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:20 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:22 volumio bluealsa[1108]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_65_06_40_AF_22_CF, ...) Mar 04 16:56:22 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:22 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:24 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:24 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:26 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:26 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:28 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:28 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:29 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:29.499Z level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.13:48646 Mar 04 16:56:29 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:29.499Z level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.13:48646 Mar 04 16:56:29 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:29.511Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.13:52756 Mar 04 16:56:30 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:30 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:32 volumio dbus-daemon[908]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Mar 04 16:56:32 volumio sudo[2365]: pam_unix(sudo:session): session closed for user root Mar 04 16:56:32 volumio volumio[1387]: Failed to set time zone: Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Mar 04 16:56:32 volumio volumio[1387]: info: Could not set timezone, retrying Mar 04 16:56:32 volumio volumio[1387]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 04 16:56:32 volumio volumio[1387]: info: Fetching Streaming Services browse cache Mar 04 16:56:32 volumio sudo[2397]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Paris Mar 04 16:56:32 volumio sudo[2397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 16:56:32 volumio dbus-daemon[908]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.28' (uid=0 pid=2398 comm="/usr/bin/timedatectl set-timezone Europe/Paris") Mar 04 16:56:33 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:33 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:33 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:33.176Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.13:52756 @ 0x24e0ab0" latency=-135.025685ms timeout=10s Mar 04 16:56:33 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:33.176Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.13:52756 @ 0x24e0ab0" Mar 04 16:56:33 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:33.176Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.13:52756 @ 0x24e0ab0" latency=-133.100833ms platform=PLATFORM_ANDROID version=5.260120.0 Mar 04 16:56:33 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:33.176Z level=INFO msg="updating device settings from app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.13:52756 @ 0x24e0ab0" latency=-133.100833ms language=en timezone=Europe/Paris Mar 04 16:56:35 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:35 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:37 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:37 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:39 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:39 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:41 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:41 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:43 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:43 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:43 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:43.176Z level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.13:52756 @ 0x24e0ab0" latency=-135.025685ms timeout=10s error="failed to get system info: could not get system info: context deadline exceeded" Mar 04 16:56:45 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:45 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:47 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:47 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:49 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:49 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:51 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:51 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:53 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:53 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:54 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:54.210Z level=ERROR msg="failed to broadcast device timezone" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.13:48646 @ 0x2542840" latency=-118.707606ms error="failed to get current timezone: could not get current timezone: context deadline exceeded" Mar 04 16:56:54 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:54.956Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.13:52756 @ 0x24e0ab0" latency=-153.62942ms timeout=10s Mar 04 16:56:54 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:54.956Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.13:52756 @ 0x24e0ab0" Mar 04 16:56:54 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:54.956Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.13:52756 @ 0x24e0ab0" latency=-151.472865ms platform=PLATFORM_ANDROID version=5.260120.0 Mar 04 16:56:54 volumio volumio5-onboarding[1783]: time=2026-03-04T15:56:54.956Z level=INFO msg="updating device settings from app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.13:52756 @ 0x24e0ab0" latency=-151.472865ms language=en timezone=Europe/Paris Mar 04 16:56:55 volumio kernel: Bluetooth: hci0: command 0x2021 tx timeout Mar 04 16:56:55 volumio kernel: Bluetooth: hci0: Opcode 0x2006 failed: -110 Mar 04 16:56:57 volumio dbus-daemon[908]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Mar 04 16:56:57 volumio sudo[2397]: pam_unix(sudo:session): session closed for user root Mar 04 16:56:57 volumio volumio[1387]: Failed to set time zone: Connection timed out Mar 04 16:56:57 volumio volumio[1387]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 16:56:57 volumio volumio[1387]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'Europe/Paris' Mar 04 16:56:57 volumio volumio[1387]: Failed to set time zone: Connection timed out Mar 04 16:56:57 volumio volumio[1387]: at checkExecSyncError (node:child_process:887:11) Mar 04 16:56:57 volumio volumio[1387]: at execSync (node:child_process:959:15) Mar 04 16:56:57 volumio volumio[1387]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1680:9) Mar 04 16:56:57 volumio volumio[1387]: at listOnTimeout (node:internal/timers:573:17) Mar 04 16:56:57 volumio volumio[1387]: at process.processTimers (node:internal/timers:514:7) { Mar 04 16:56:57 volumio volumio[1387]: status: 1, Mar 04 16:56:57 volumio volumio[1387]: signal: null, Mar 04 16:56:57 volumio volumio[1387]: output: [ null, '', 'Failed to set time zone: Connection timed out\n' ], Mar 04 16:56:57 volumio volumio[1387]: pid: 2396, Mar 04 16:56:57 volumio volumio[1387]: stdout: '', Mar 04 16:56:57 volumio volumio[1387]: stderr: 'Failed to set time zone: Connection timed out\n' Mar 04 16:56:57 volumio volumio[1387]: } Mar 04 16:56:57 volumio volumio[1387]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 16:56:57 volumio sudo[2455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 16:55' Mar 04 16:56:57 volumio sudo[2455]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"