-- Logs begin at Thu 2019-02-14 12:11:58 SAST, end at Thu 2025-12-11 12:13:07 SAST. -- Dec 11 12:12:03 primo-plus wpa_supplicant[8728]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=50:d4:f7:91:bb:b3 status_code=16 Dec 11 12:12:03 primo-plus wpa_supplicant[8728]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Lerner Family" auth_failures=18 duration=180 reason=CONN_FAILED Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.023Z level=DEBUG msg="network info updated" component=volumio/wifi ssid= ip4= ip6= Dec 11 12:12:04 primo-plus volumio[889]: info: Received Get System Info Dec 11 12:12:04 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 12:12:04 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 12:12:04 primo-plus volumio[889]: info: Discovery: Getting this device information Dec 11 12:12:04 primo-plus volumio[889]: info: CoreCommandRouter::volumioGetState Dec 11 12:12:04 primo-plus volumio[889]: info: CorePlayQueue::getTrack 7 Dec 11 12:12:04 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.026Z level=DEBUG msg="sending event" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" event=SERVER_EVENT_TYPE_WIFI_INFO_CHANGED Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.026Z level=DEBUG msg="writing packet" component=conn/multi size=17 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.027Z level=DEBUG msg="received event" component=volumio/socket event=pushSystemInfo Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.028Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=01130000110891cd8ac704520920baf295e7b0330806 Dec 11 12:12:04 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 11 12:12:04 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.029Z level=DEBUG msg="received event" component=volumio/socket event=pushWizard Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.031Z level=INFO msg="BLE descriptor updated" deviceId=143c0a21bb7583caa5031b474e4867f4 deviceName="Primo Plus" deviceModel=0x2e82098 Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.033Z level=INFO msg="mDNS descriptor updated" deviceId=143c0a21bb7583caa5031b474e4867f4 deviceName="Primo Plus" deviceModel=0x2e82098 Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.130Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.130Z level=DEBUG msg="sent event" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" event=SERVER_EVENT_TYPE_WIFI_INFO_CHANGED Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.130Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.895Z level=DEBUG msg="received last data packet" component=conn/ble data=0113001608cfbdf487055a0e0819120018e6f895e7b033208827 addr=19 Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.895Z level=DEBUG msg="received request" component=server src="00:00:00:00:00:00%13 @ 0x2f407b0" packet=08cfbdf487055a0e0819120018e6f895e7b033208827 type=REQUEST_TYPE_PING id=1358765775 Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.895Z level=INFO msg="received request" component=server type=REQUEST_TYPE_PING peer="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.896Z level=DEBUG msg="writing packet" component=conn/multi size=19 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.896Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=011300001308cfbdf48705620b08191001209ff995e7b033 Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.985Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.985Z level=DEBUG msg="sent response" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" id=1358765775 status=STATUS_OK Dec 11 12:12:04 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:04.985Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:12:08 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:08.329Z level=DEBUG msg="network info updated" component=volumio/wifi ssid= ip4= ip6= Dec 11 12:12:08 primo-plus volumio[889]: info: Received Get System Info Dec 11 12:12:08 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 12:12:08 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 12:12:08 primo-plus volumio[889]: info: Discovery: Getting this device information Dec 11 12:12:08 primo-plus volumio[889]: info: CoreCommandRouter::volumioGetState Dec 11 12:12:08 primo-plus volumio[889]: info: CorePlayQueue::getTrack 7 Dec 11 12:12:08 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 12:12:08 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:08.333Z level=DEBUG msg="sending event" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" event=SERVER_EVENT_TYPE_WIFI_INFO_CHANGED Dec 11 12:12:08 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:08.334Z level=DEBUG msg="writing packet" component=conn/multi size=17 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:08 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:08.334Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=011300001108ba81ee97035209208d9496e7b0330806 Dec 11 12:12:08 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:08.336Z level=DEBUG msg="received event" component=volumio/socket event=pushSystemInfo Dec 11 12:12:08 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 11 12:12:08 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 11 12:12:08 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:08.340Z level=DEBUG msg="received event" component=volumio/socket event=pushWizard Dec 11 12:12:08 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:08.341Z level=INFO msg="BLE descriptor updated" deviceId=143c0a21bb7583caa5031b474e4867f4 deviceName="Primo Plus" deviceModel=0x2d15040 Dec 11 12:12:08 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:08.343Z level=INFO msg="mDNS descriptor updated" deviceId=143c0a21bb7583caa5031b474e4867f4 deviceName="Primo Plus" deviceModel=0x2d15040 Dec 11 12:12:08 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:08.450Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:12:08 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:08.450Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:12:08 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:08.450Z level=DEBUG msg="sent event" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" event=SERVER_EVENT_TYPE_WIFI_INFO_CHANGED Dec 11 12:12:09 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:09.981Z level=DEBUG msg="received last data packet" component=conn/ble data=0113001608b89fffb3065a0e0819120018c5a096e7b033208827 addr=19 Dec 11 12:12:09 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:09.982Z level=DEBUG msg="received request" component=server src="00:00:00:00:00:00%13 @ 0x2f407b0" packet=08b89fffb3065a0e0819120018c5a096e7b033208827 type=REQUEST_TYPE_PING id=1719652280 Dec 11 12:12:09 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:09.982Z level=INFO msg="received request" component=server type=REQUEST_TYPE_PING peer="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:09 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:09.982Z level=DEBUG msg="writing packet" component=conn/multi size=19 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:09 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:09.982Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=011300001308b89fffb306620b0819100120fea096e7b033 Dec 11 12:12:10 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:10.073Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:12:10 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:10.073Z level=DEBUG msg="sent response" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" id=1719652280 status=STATUS_OK Dec 11 12:12:10 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:10.074Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:12:15 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:15.065Z level=DEBUG msg="received last data packet" component=conn/ble data=0113001608a0efd5f4025a0e0819120018a5c896e7b033208827 addr=19 Dec 11 12:12:15 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:15.065Z level=DEBUG msg="received request" component=server src="00:00:00:00:00:00%13 @ 0x2f407b0" packet=08a0efd5f4025a0e0819120018a5c896e7b033208827 type=REQUEST_TYPE_PING id=781547424 Dec 11 12:12:15 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:15.065Z level=INFO msg="received request" component=server type=REQUEST_TYPE_PING peer="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:15 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:15.065Z level=DEBUG msg="writing packet" component=conn/multi size=19 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:15 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:15.066Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=011300001308a0efd5f402620b0819100120d9c896e7b033 Dec 11 12:12:15 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:15.155Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:12:15 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:15.156Z level=DEBUG msg="sent response" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" id=781547424 status=STATUS_OK Dec 11 12:12:15 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:15.156Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:12:18 primo-plus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Dec 11 12:12:18 primo-plus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 11 12:12:18 primo-plus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 11 12:12:19 primo-plus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 11 12:12:20 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:20.150Z level=DEBUG msg="received last data packet" component=conn/ble data=0113001608c8a881eb055a0e081912001881f096e7b033208827 addr=19 Dec 11 12:12:20 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:20.151Z level=DEBUG msg="received request" component=server src="00:00:00:00:00:00%13 @ 0x2f407b0" packet=08c8a881eb055a0e081912001881f096e7b033208827 type=REQUEST_TYPE_PING id=1566594120 Dec 11 12:12:20 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:20.151Z level=INFO msg="received request" component=server type=REQUEST_TYPE_PING peer="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:20 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:20.151Z level=DEBUG msg="writing packet" component=conn/multi size=19 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:20 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:20.151Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=011300001308c8a881eb05620b0819100120b7f096e7b033 Dec 11 12:12:20 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:20.241Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:12:20 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:20.241Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:12:20 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:20.241Z level=DEBUG msg="sent response" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" id=1566594120 status=STATUS_OK Dec 11 12:12:21 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:21.345Z level=DEBUG msg="network info updated" component=volumio/wifi ssid= ip4= ip6= Dec 11 12:12:21 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:21.347Z level=DEBUG msg="sending event" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" event=SERVER_EVENT_TYPE_WIFI_INFO_CHANGED Dec 11 12:12:21 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:21.347Z level=DEBUG msg="writing packet" component=conn/multi size=17 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:21 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:21.347Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=011300001108a1d494f804520920e3f996e7b0330806 Dec 11 12:12:21 primo-plus volumio[889]: info: Received Get System Info Dec 11 12:12:21 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 12:12:21 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 12:12:21 primo-plus volumio[889]: info: Discovery: Getting this device information Dec 11 12:12:21 primo-plus volumio[889]: info: CoreCommandRouter::volumioGetState Dec 11 12:12:21 primo-plus volumio[889]: info: CorePlayQueue::getTrack 7 Dec 11 12:12:21 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 12:12:21 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:21.350Z level=DEBUG msg="received event" component=volumio/socket event=pushSystemInfo Dec 11 12:12:21 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 11 12:12:21 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 11 12:12:21 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:21.353Z level=DEBUG msg="received event" component=volumio/socket event=pushWizard Dec 11 12:12:21 primo-plus wpa_supplicant[8728]: wlan0: Failed to initiate sched scan Dec 11 12:12:21 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:21.358Z level=INFO msg="BLE descriptor updated" deviceId=143c0a21bb7583caa5031b474e4867f4 deviceName="Primo Plus" deviceModel=0x2d14590 Dec 11 12:12:21 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:21.359Z level=INFO msg="mDNS descriptor updated" deviceId=143c0a21bb7583caa5031b474e4867f4 deviceName="Primo Plus" deviceModel=0x2d14590 Dec 11 12:12:21 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:21.455Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:12:21 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:21.455Z level=DEBUG msg="sent event" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" event=SERVER_EVENT_TYPE_WIFI_INFO_CHANGED Dec 11 12:12:21 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:21.455Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:12:25 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:25.235Z level=DEBUG msg="received last data packet" component=conn/ble data=01130016088fe0ca9c045a0e0819120018db9797e7b033208827 addr=19 Dec 11 12:12:25 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:25.235Z level=DEBUG msg="received request" component=server src="00:00:00:00:00:00%13 @ 0x2f407b0" packet=088fe0ca9c045a0e0819120018db9797e7b033208827 type=REQUEST_TYPE_PING id=1133686799 Dec 11 12:12:25 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:25.236Z level=INFO msg="received request" component=server type=REQUEST_TYPE_PING peer="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:25 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:25.236Z level=DEBUG msg="writing packet" component=conn/multi size=19 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:25 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:25.236Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=0113000013088fe0ca9c04620b0819100120949897e7b033 Dec 11 12:12:25 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:25.325Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:12:25 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:25.325Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:12:25 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:25.325Z level=DEBUG msg="sent response" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" id=1133686799 status=STATUS_OK Dec 11 12:12:30 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:30.320Z level=DEBUG msg="received last data packet" component=conn/ble data=0113001608eedeca8e035a0e0819120018babf97e7b033208827 addr=19 Dec 11 12:12:30 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:30.320Z level=DEBUG msg="received request" component=server src="00:00:00:00:00:00%13 @ 0x2f407b0" packet=08eedeca8e035a0e0819120018babf97e7b033208827 type=REQUEST_TYPE_PING id=835891054 Dec 11 12:12:30 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:30.324Z level=INFO msg="received request" component=server type=REQUEST_TYPE_PING peer="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:30 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:30.327Z level=DEBUG msg="writing packet" component=conn/multi size=19 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:30 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:30.328Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=011300001308eedeca8e03620b0819100120f7bf97e7b033 Dec 11 12:12:30 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:30.411Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:12:30 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:30.411Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:12:30 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:30.411Z level=DEBUG msg="sent response" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" id=835891054 status=STATUS_OK Dec 11 12:12:31 primo-plus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Dec 11 12:12:31 primo-plus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 11 12:12:31 primo-plus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 11 12:12:31 primo-plus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 11 12:12:34 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:34.253Z level=DEBUG msg="network info updated" component=volumio/wifi ssid= ip4= ip6= Dec 11 12:12:34 primo-plus volumio[889]: info: Received Get System Info Dec 11 12:12:34 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 12:12:34 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 12:12:34 primo-plus volumio[889]: info: Discovery: Getting this device information Dec 11 12:12:34 primo-plus volumio[889]: info: CoreCommandRouter::volumioGetState Dec 11 12:12:34 primo-plus volumio[889]: info: CorePlayQueue::getTrack 7 Dec 11 12:12:34 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 12:12:34 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:34.262Z level=DEBUG msg="received event" component=volumio/socket event=pushSystemInfo Dec 11 12:12:34 primo-plus wpa_supplicant[8728]: wlan0: Failed to initiate sched scan Dec 11 12:12:34 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:34.267Z level=DEBUG msg="sending event" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" event=SERVER_EVENT_TYPE_WIFI_INFO_CHANGED Dec 11 12:12:34 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:34.267Z level=DEBUG msg="writing packet" component=conn/multi size=17 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:34 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:34.267Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=011300001108cce69da902520920dade97e7b0330806 Dec 11 12:12:34 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 11 12:12:34 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 11 12:12:34 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:34.268Z level=DEBUG msg="received event" component=volumio/socket event=pushWizard Dec 11 12:12:34 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:34.273Z level=INFO msg="BLE descriptor updated" deviceId=143c0a21bb7583caa5031b474e4867f4 deviceName="Primo Plus" deviceModel=0x2e82450 Dec 11 12:12:34 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:34.281Z level=INFO msg="mDNS descriptor updated" deviceId=143c0a21bb7583caa5031b474e4867f4 deviceName="Primo Plus" deviceModel=0x2e82450 Dec 11 12:12:34 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:34.369Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:12:34 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:34.370Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:12:34 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:34.370Z level=DEBUG msg="sent event" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" event=SERVER_EVENT_TYPE_WIFI_INFO_CHANGED Dec 11 12:12:35 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:35.408Z level=DEBUG msg="received last data packet" component=conn/ble data=011300160880f0f2f2055a0e08191200189ae797e7b033208827 addr=19 Dec 11 12:12:35 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:35.409Z level=DEBUG msg="received request" component=server src="00:00:00:00:00:00%13 @ 0x2f407b0" packet=0880f0f2f2055a0e08191200189ae797e7b033208827 type=REQUEST_TYPE_PING id=1583134720 Dec 11 12:12:35 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:35.409Z level=INFO msg="received request" component=server type=REQUEST_TYPE_PING peer="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:35 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:35.409Z level=DEBUG msg="writing packet" component=conn/multi size=19 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:35 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:35.409Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=01130000130880f0f2f205620b0819100120d1e797e7b033 Dec 11 12:12:35 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:35.495Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:12:35 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:35.495Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:12:35 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:35.495Z level=DEBUG msg="sent response" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" id=1583134720 status=STATUS_OK Dec 11 12:12:40 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:40.490Z level=DEBUG msg="received last data packet" component=conn/ble data=01130016089cc091fd025a0e0819120018f48e98e7b033208827 addr=19 Dec 11 12:12:40 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:40.491Z level=DEBUG msg="received request" component=server src="00:00:00:00:00:00%13 @ 0x2f407b0" packet=089cc091fd025a0e0819120018f48e98e7b033208827 type=REQUEST_TYPE_PING id=799301660 Dec 11 12:12:40 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:40.491Z level=INFO msg="received request" component=server type=REQUEST_TYPE_PING peer="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:40 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:40.491Z level=DEBUG msg="writing packet" component=conn/multi size=19 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:40 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:40.491Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=0113000013089cc091fd02620b0819100120ab8f98e7b033 Dec 11 12:12:40 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:40.580Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:12:40 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:40.580Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:12:40 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:40.581Z level=DEBUG msg="sent response" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" id=799301660 status=STATUS_OK Dec 11 12:12:44 primo-plus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Dec 11 12:12:44 primo-plus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 11 12:12:44 primo-plus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 11 12:12:44 primo-plus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 11 12:12:45 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:45.575Z level=DEBUG msg="received last data packet" component=conn/ble data=0113001508bed9832b5a0e0819120018d0b698e7b033208827 addr=19 Dec 11 12:12:45 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:45.575Z level=DEBUG msg="received request" component=server src="00:00:00:00:00:00%13 @ 0x2f407b0" packet=08bed9832b5a0e0819120018d0b698e7b033208827 type=REQUEST_TYPE_PING id=90238142 Dec 11 12:12:45 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:45.575Z level=INFO msg="received request" component=server type=REQUEST_TYPE_PING peer="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:45 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:45.576Z level=DEBUG msg="writing packet" component=conn/multi size=18 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:45 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:45.576Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=011300001208bed9832b620b081910012088b798e7b033 Dec 11 12:12:45 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:45.665Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:12:45 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:45.665Z level=DEBUG msg="sent response" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" id=90238142 status=STATUS_OK Dec 11 12:12:45 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:45.665Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:12:47 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:47.085Z level=DEBUG msg="network info updated" component=volumio/wifi ssid= ip4= ip6= Dec 11 12:12:47 primo-plus volumio[889]: info: Received Get System Info Dec 11 12:12:47 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 12:12:47 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 12:12:47 primo-plus volumio[889]: info: Discovery: Getting this device information Dec 11 12:12:47 primo-plus volumio[889]: info: CoreCommandRouter::volumioGetState Dec 11 12:12:47 primo-plus volumio[889]: info: CorePlayQueue::getTrack 7 Dec 11 12:12:47 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 12:12:47 primo-plus wpa_supplicant[8728]: wlan0: Failed to initiate sched scan Dec 11 12:12:47 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:47.091Z level=DEBUG msg="received event" component=volumio/socket event=pushSystemInfo Dec 11 12:12:47 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:47.092Z level=DEBUG msg="sending event" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" event=SERVER_EVENT_TYPE_WIFI_INFO_CHANGED Dec 11 12:12:47 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:47.092Z level=DEBUG msg="writing packet" component=conn/multi size=16 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:47 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:47.093Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=011300001008e08fe550520920f4c298e7b0330806 Dec 11 12:12:47 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 11 12:12:47 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 11 12:12:47 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:47.096Z level=DEBUG msg="received event" component=volumio/socket event=pushWizard Dec 11 12:12:47 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:47.098Z level=INFO msg="BLE descriptor updated" deviceId=143c0a21bb7583caa5031b474e4867f4 deviceName="Primo Plus" deviceModel=0x2e82960 Dec 11 12:12:47 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:47.100Z level=INFO msg="mDNS descriptor updated" deviceId=143c0a21bb7583caa5031b474e4867f4 deviceName="Primo Plus" deviceModel=0x2e82960 Dec 11 12:12:47 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:47.241Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:12:47 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:47.241Z level=DEBUG msg="sent event" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" event=SERVER_EVENT_TYPE_WIFI_INFO_CHANGED Dec 11 12:12:47 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:47.241Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:12:50 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:50.664Z level=DEBUG msg="received last data packet" component=conn/ble data=01130016089ab2e7b1055a0e0819120018adde98e7b033208827 addr=19 Dec 11 12:12:50 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:50.665Z level=DEBUG msg="received request" component=server src="00:00:00:00:00:00%13 @ 0x2f407b0" packet=089ab2e7b1055a0e0819120018adde98e7b033208827 type=REQUEST_TYPE_PING id=1446631706 Dec 11 12:12:50 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:50.665Z level=INFO msg="received request" component=server type=REQUEST_TYPE_PING peer="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:50 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:50.665Z level=DEBUG msg="writing packet" component=conn/multi size=19 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:50 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:50.665Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=0113000013089ab2e7b105620b0819100120e9de98e7b033 Dec 11 12:12:50 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:50.750Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:12:50 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:50.750Z level=DEBUG msg="sent response" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" id=1446631706 status=STATUS_OK Dec 11 12:12:50 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:50.750Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:12:55 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:55.745Z level=DEBUG msg="received last data packet" component=conn/ble data=0113001608cda5c9d8015a0e08191200188d8699e7b033208827 addr=19 Dec 11 12:12:55 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:55.745Z level=DEBUG msg="received request" component=server src="00:00:00:00:00:00%13 @ 0x2f407b0" packet=08cda5c9d8015a0e08191200188d8699e7b033208827 type=REQUEST_TYPE_PING id=454185677 Dec 11 12:12:55 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:55.746Z level=INFO msg="received request" component=server type=REQUEST_TYPE_PING peer="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:55 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:55.746Z level=DEBUG msg="writing packet" component=conn/multi size=19 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:55 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:55.746Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=011300001308cda5c9d801620b0819100120c28699e7b033 Dec 11 12:12:55 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:55.843Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:12:55 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:55.843Z level=DEBUG msg="sent response" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" id=454185677 status=STATUS_OK Dec 11 12:12:55 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:55.844Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:12:57 primo-plus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Dec 11 12:12:57 primo-plus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 11 12:12:57 primo-plus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 11 12:12:57 primo-plus kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 11 12:12:59 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 11 12:12:59 primo-plus volumio[889]: info: CURURI: artists:// Dec 11 12:12:59 primo-plus volumio[889]: info: Preload queue cleared Dec 11 12:12:59 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:59.953Z level=DEBUG msg="network info updated" component=volumio/wifi ssid= ip4= ip6= Dec 11 12:12:59 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:59.954Z level=DEBUG msg="sending event" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" event=SERVER_EVENT_TYPE_WIFI_INFO_CHANGED Dec 11 12:12:59 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:59.955Z level=DEBUG msg="writing packet" component=conn/multi size=17 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:12:59 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:59.955Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=011300001108cec7ece904520920b2a799e7b0330806 Dec 11 12:12:59 primo-plus volumio[889]: info: Received Get System Info Dec 11 12:12:59 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 12:12:59 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 12:12:59 primo-plus volumio[889]: info: Discovery: Getting this device information Dec 11 12:12:59 primo-plus volumio[889]: info: CoreCommandRouter::volumioGetState Dec 11 12:12:59 primo-plus volumio[889]: info: CorePlayQueue::getTrack 7 Dec 11 12:12:59 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 12:12:59 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:59.959Z level=DEBUG msg="received event" component=volumio/socket event=pushSystemInfo Dec 11 12:12:59 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 11 12:12:59 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 11 12:12:59 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:59.964Z level=DEBUG msg="received event" component=volumio/socket event=pushWizard Dec 11 12:12:59 primo-plus wpa_supplicant[8728]: wlan0: Failed to initiate sched scan Dec 11 12:12:59 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:59.969Z level=INFO msg="BLE descriptor updated" deviceId=143c0a21bb7583caa5031b474e4867f4 deviceName="Primo Plus" deviceModel=0x30fc050 Dec 11 12:12:59 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:12:59.970Z level=INFO msg="mDNS descriptor updated" deviceId=143c0a21bb7583caa5031b474e4867f4 deviceName="Primo Plus" deviceModel=0x30fc050 Dec 11 12:13:00 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:00.064Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:13:00 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:00.065Z level=DEBUG msg="sent event" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" event=SERVER_EVENT_TYPE_WIFI_INFO_CHANGED Dec 11 12:13:00 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:00.065Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:13:00 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:00.830Z level=DEBUG msg="received last data packet" component=conn/ble data=0113001608cbd682ff055a0e0819120018ebad99e7b033208827 addr=19 Dec 11 12:13:00 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:00.830Z level=DEBUG msg="received request" component=server src="00:00:00:00:00:00%13 @ 0x2f407b0" packet=08cbd682ff055a0e0819120018ebad99e7b033208827 type=REQUEST_TYPE_PING id=1608559435 Dec 11 12:13:00 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:00.830Z level=INFO msg="received request" component=server type=REQUEST_TYPE_PING peer="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:13:00 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:00.830Z level=DEBUG msg="writing packet" component=conn/multi size=19 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:13:00 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:00.831Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=011300001308cbd682ff05620b08191001209eae99e7b033 Dec 11 12:13:00 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:00.968Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:13:00 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:00.969Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:13:00 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:00.969Z level=DEBUG msg="sent response" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" id=1608559435 status=STATUS_OK Dec 11 12:13:04 primo-plus volumio[889]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 11 12:13:04 primo-plus volumio[889]: info: CURURI: artists://9Bach Dec 11 12:13:04 primo-plus volumio[889]: info: Preload queue cleared Dec 11 12:13:04 primo-plus volumio[889]: info: Preload queue cleared Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/01 - Lliwia.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/02 - Llwynog.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/03 - Pebyll.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/04 - Plentyn.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/05 - Wedi Torri.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/06 - Pa Le.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/07 - Ffarwel.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/08 - Llwybrau.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/09 - Babi'r Eirlys.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/10 - Asteri Mou.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/01 Llyn Du.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/02 Anian.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/03 Yr Olaf.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/04 Ifan.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/05 Si Hwi Hwi.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/06 Cyfaddefa.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/07 Brain.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/08 Heno.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/09 Deryn.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/10 Ambell Hiraeth.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Preloading song: music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/11 Breuddwyd y Bardd.flac Dec 11 12:13:04 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/01 - Lliwia.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Tincian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202014%20-%20Tincian%2F01%20-%20Lliwia.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/01 - Lliwia.flac Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/02 - Llwynog.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/05 - Wedi Torri.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/03 - Pebyll.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/04 - Plentyn.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/06 - Pa Le.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/07 - Ffarwel.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/08 - Llwybrau.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/09 - Babi'r Eirlys.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/10 - Asteri Mou.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Tincian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202014%20-%20Tincian%2F02%20-%20Llwynog.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/02 - Llwynog.flac Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Tincian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202014%20-%20Tincian%2F05%20-%20Wedi%20Torri.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/05 - Wedi Torri.flac Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Tincian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202014%20-%20Tincian%2F03%20-%20Pebyll.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/03 - Pebyll.flac Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Tincian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202014%20-%20Tincian%2F04%20-%20Plentyn.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/04 - Plentyn.flac Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Tincian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202014%20-%20Tincian%2F06%20-%20Pa%20Le.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/06 - Pa Le.flac Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Tincian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202014%20-%20Tincian%2F07%20-%20Ffarwel.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/07 - Ffarwel.flac Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Tincian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202014%20-%20Tincian%2F08%20-%20Llwybrau.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/08 - Llwybrau.flac Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Tincian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202014%20-%20Tincian%2F09%20-%20Babi'r%20Eirlys.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/09 - Babi'r Eirlys.flac Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Tincian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202014%20-%20Tincian%2F10%20-%20Asteri%20Mou.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2014 - Tincian/10 - Asteri Mou.flac Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/01 Llyn Du.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/02 Anian.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/03 Yr Olaf.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/04 Ifan.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/05 Si Hwi Hwi.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Anian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202016%20-%20Anian%2F01%20Llyn%20Du.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/01 Llyn Du.flac Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Anian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202016%20-%20Anian%2F02%20Anian.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/02 Anian.flac Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Anian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202016%20-%20Anian%2F03%20Yr%20Olaf.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/03 Yr Olaf.flac Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Anian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202016%20-%20Anian%2F04%20Ifan.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/04 Ifan.flac Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Anian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202016%20-%20Anian%2F05%20Si%20Hwi%20Hwi.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/05 Si Hwi Hwi.flac Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/06 Cyfaddefa.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Anian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202016%20-%20Anian%2F06%20Cyfaddefa.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/06 Cyfaddefa.flac Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/07 Brain.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Anian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202016%20-%20Anian%2F07%20Brain.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/07 Brain.flac Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/08 Heno.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Anian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202016%20-%20Anian%2F08%20Heno.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/08 Heno.flac Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/09 Deryn.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/10 Ambell Hiraeth.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Anian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202016%20-%20Anian%2F09%20Deryn.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/09 Deryn.flac Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Anian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202016%20-%20Anian%2F10%20Ambell%20Hiraeth.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/10 Ambell Hiraeth.flac Dec 11 12:13:05 primo-plus volumio[889]: info: Exploding uri music-library/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/11 Breuddwyd y Bardd.flac in service mpd Dec 11 12:13:05 primo-plus volumio[889]: info: ALBUMART /albumart?cacheid=820&web=9Bach/Anian/extralarge&path=%2Fmnt%2FNAS%2FMUSIC_1%2F9Bach%2F9Bach%20-%202016%20-%20Anian%2F11%20Breuddwyd%20y%20Bardd.flac&metadata=false Dec 11 12:13:05 primo-plus volumio[889]: info: URI /mnt/NAS/MUSIC_1/9Bach/9Bach - 2016 - Anian/11 Breuddwyd y Bardd.flac Dec 11 12:13:06 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:06.007Z level=DEBUG msg="received last data packet" component=conn/ble data=0113001608ee8298ae015a0e08191200188fd699e7b033208827 addr=19 Dec 11 12:13:06 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:06.007Z level=DEBUG msg="received request" component=server src="00:00:00:00:00:00%13 @ 0x2f407b0" packet=08ee8298ae015a0e08191200188fd699e7b033208827 type=REQUEST_TYPE_PING id=365298030 Dec 11 12:13:06 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:06.007Z level=INFO msg="received request" component=server type=REQUEST_TYPE_PING peer="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:13:06 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:06.007Z level=DEBUG msg="writing packet" component=conn/multi size=19 addr="00:00:00:00:00:00%13 @ 0x2f407b0" Dec 11 12:13:06 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:06.007Z level=DEBUG msg="writing next data packet" component=conn/ble addr=19 tries=0 index=0 chunks=1 data=011300001308ee8298ae01620b0819100120d7d699e7b033 Dec 11 12:13:06 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:06.095Z level=DEBUG msg="all chunks acknowledged, removing pending write" component=conn/ble data=1300 addr=19 Dec 11 12:13:06 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:06.095Z level=DEBUG msg="sent response" component=server dst="00:00:00:00:00:00%13 @ 0x2f407b0" id=365298030 status=STATUS_OK Dec 11 12:13:06 primo-plus volumio5-onboarding[1417]: time=2025-12-11T10:13:06.096Z level=DEBUG msg="no pending writes to acknowledge" component=conn/ble Dec 11 12:13:07 primo-plus volumio[889]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 12:13:07 primo-plus volumio[889]: TypeError: Cannot read property 'slice' of null Dec 11 12:13:07 primo-plus volumio[889]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Dec 11 12:13:07 primo-plus volumio[889]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Dec 11 12:13:07 primo-plus volumio[889]: at Object.onceWrapper (events.js:422:26) Dec 11 12:13:07 primo-plus volumio[889]: at ClientRequest.emit (events.js:315:20) Dec 11 12:13:07 primo-plus volumio[889]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Dec 11 12:13:07 primo-plus volumio[889]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Dec 11 12:13:07 primo-plus volumio[889]: at TLSSocket.socketOnData (_http_client.js:509:22) Dec 11 12:13:07 primo-plus volumio[889]: at TLSSocket.emit (events.js:315:20) Dec 11 12:13:07 primo-plus volumio[889]: at addChunk (internal/streams/readable.js:309:12) Dec 11 12:13:07 primo-plus volumio[889]: at readableAddChunk (internal/streams/readable.js:284:9) Dec 11 12:13:07 primo-plus volumio[889]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Dec 11 12:13:07 primo-plus volumio[889]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Dec 11 12:13:07 primo-plus volumio[889]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 12:13:07 primo-plus sudo[14951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-11 12:12 Dec 11 12:13:07 primo-plus sudo[14951]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="primoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 09:38:58 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Primo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo Plus" VOLUMIO_HASH="ef6308e29b3a8116db9f5e14b8c03dab"