May 27 11:42:04 primo-plus volumio[1175]: info: CoreCommandRouter::getUIConfigOnPlugin May 27 11:42:04 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 27 11:42:04 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 27 11:42:04 primo-plus sudo[2583]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 27 11:42:04 primo-plus sudo[2583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:04 primo-plus sudo[2588]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 27 11:42:04 primo-plus sudo[2588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:04 primo-plus sudo[2588]: pam_unix(sudo:session): session closed for user root May 27 11:42:04 primo-plus sudo[2595]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 27 11:42:04 primo-plus sudo[2595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:04 primo-plus sudo[2583]: pam_unix(sudo:session): session closed for user root May 27 11:42:04 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 27 11:42:04 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 27 11:42:04 primo-plus sudo[2595]: pam_unix(sudo:session): session closed for user root May 27 11:42:04 primo-plus sudo[2600]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 27 11:42:04 primo-plus sudo[2600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:04 primo-plus sudo[2600]: pam_unix(sudo:session): session closed for user root May 27 11:42:04 primo-plus sudo[2605]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 27 11:42:04 primo-plus sudo[2605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:04 primo-plus sudo[2605]: pam_unix(sudo:session): session closed for user root May 27 11:42:04 primo-plus sudo[2608]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:04 primo-plus sudo[2608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:04 primo-plus sudo[2608]: pam_unix(sudo:session): session closed for user root May 27 11:42:04 primo-plus sudo[2614]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 27 11:42:04 primo-plus sudo[2614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:07 primo-plus sudo[2614]: pam_unix(sudo:session): session closed for user root May 27 11:42:07 primo-plus volumio[1175]: info: Received Get System Info May 27 11:42:07 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 27 11:42:07 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 27 11:42:07 primo-plus volumio[1175]: info: Discovery: Getting this device information May 27 11:42:07 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:07 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:07.891Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" available=true connected=false macAddress= ip4Address= ip6Address= ssid= May 27 11:42:07 primo-plus volumio[1175]: info: CorePlayQueue::getTrack 0 May 27 11:42:07 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 27 11:42:07 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 27 11:42:07 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 27 11:42:08 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:08.736Z level=INFO msg="service successfully established" component=discovery/localnet May 27 11:42:33 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings May 27 11:42:33 primo-plus volumio[1175]: info: Saving new wireless network May 27 11:42:33 primo-plus sudo[2662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf May 27 11:42:33 primo-plus sudo[2662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:33 primo-plus sudo[2662]: pam_unix(sudo:session): session closed for user root May 27 11:42:33 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 27 11:42:33 primo-plus sudo[2665]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 27 11:42:33 primo-plus sudo[2665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:33 primo-plus systemd[1]: Stopping wireless.service - Wireless Services... May 27 11:42:33 primo-plus systemd[1]: wireless.service: Killing process 1458 (wpa_supplicant) with signal SIGKILL. May 27 11:42:33 primo-plus systemd[1]: wireless.service: Deactivated successfully. May 27 11:42:33 primo-plus systemd[1]: Stopped wireless.service - Wireless Services. May 27 11:42:33 primo-plus systemd[1]: wireless.service: Consumed 3.065s CPU time. May 27 11:42:33 primo-plus systemd[1]: Starting wireless.service - Wireless Services... May 27 11:42:33 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: Single Network Mode enabled (default) - only one network device can be active at a time between ethernet and wireless May 27 11:42:33 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: === SNM TRANSITION === May 27 11:42:33 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: Previous ethernet state: disconnected May 27 11:42:33 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: New ethernet state: connected May 27 11:42:33 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: Single Network Mode: enabled May 27 11:42:33 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: First start: yes May 27 11:42:33 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) May 27 11:42:33 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: === END TRANSITION === May 27 11:42:33 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow May 27 11:42:33 primo-plus sudo[2693]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 27 11:42:33 primo-plus sudo[2693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 11:42:33 primo-plus sudo[2693]: pam_unix(sudo:session): session closed for user root May 27 11:42:33 primo-plus sudo[2695]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 27 11:42:33 primo-plus sudo[2695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 11:42:34 primo-plus sudo[2695]: pam_unix(sudo:session): session closed for user root May 27 11:42:34 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: Cleaning previous... May 27 11:42:34 primo-plus sudo[2698]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:34 primo-plus sudo[2698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:34 primo-plus sudo[2698]: pam_unix(sudo:session): session closed for user root May 27 11:42:34 primo-plus sudo[2700]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 27 11:42:34 primo-plus sudo[2700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 11:42:34 primo-plus sudo[2700]: pam_unix(sudo:session): session closed for user root May 27 11:42:34 primo-plus kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 27 11:42:34 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: InterfaceValidator: READY - wlan0 is ready for operations May 27 11:42:34 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: InterfaceValidator: wlan0 became ready after 3ms May 27 11:42:34 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: ensureInterfaceReady: Interface ready (MAC: 88:a2:9e:ba:9b:c0) May 27 11:42:34 primo-plus sudo[2708]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 27 11:42:34 primo-plus sudo[2708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:34 primo-plus sudo[2708]: pam_unix(sudo:session): session closed for user root May 27 11:42:34 primo-plus sudo[2716]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 27 11:42:34 primo-plus sudo[2716]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:34 primo-plus volumio[1175]: info: Received Get System Info May 27 11:42:34 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 27 11:42:34 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 27 11:42:34 primo-plus volumio[1175]: info: Discovery: Getting this device information May 27 11:42:34 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:34 primo-plus volumio[1175]: info: CorePlayQueue::getTrack 0 May 27 11:42:34 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 27 11:42:34 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:34.990Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" available=true connected=false macAddress= ip4Address= ip6Address= ssid= May 27 11:42:34 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 27 11:42:34 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 27 11:42:35 primo-plus sudo[2735]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:35 primo-plus sudo[2735]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:35 primo-plus sudo[2735]: pam_unix(sudo:session): session closed for user root May 27 11:42:35 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:35.839Z level=INFO msg="service successfully established" component=discovery/localnet May 27 11:42:36 primo-plus sudo[2738]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:36 primo-plus sudo[2738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:36 primo-plus sudo[2738]: pam_unix(sudo:session): session closed for user root May 27 11:42:37 primo-plus sudo[2741]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:37 primo-plus sudo[2741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:37 primo-plus sudo[2741]: pam_unix(sudo:session): session closed for user root May 27 11:42:37 primo-plus sudo[2716]: pam_unix(sudo:session): session closed for user root May 27 11:42:37 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: Regdomain already correct: VN May 27 11:42:37 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability May 27 11:42:37 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) May 27 11:42:37 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active May 27 11:42:37 primo-plus sudo[2748]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 27 11:42:37 primo-plus sudo[2748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 11:42:37 primo-plus sudo[2748]: pam_unix(sudo:session): session closed for user root May 27 11:42:37 primo-plus sudo[2751]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 27 11:42:37 primo-plus sudo[2751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 11:42:37 primo-plus sudo[2751]: pam_unix(sudo:session): session closed for user root May 27 11:42:37 primo-plus wpa_supplicant[2754]: Successfully initialized wpa_supplicant May 27 11:42:37 primo-plus wpa_supplicant[2754]: nl80211: kernel reports: Registration to specific type not supported May 27 11:42:37 primo-plus wpa_supplicant[2757]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all May 27 11:42:37 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 3944ms May 27 11:42:37 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable May 27 11:42:37 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: wlan0 state: wpa_state=SCANNING (expected DISCONNECTED or INACTIVE) May 27 11:42:37 primo-plus wireless.js[2670]: WIRELESS.JS - INFO: Notified systemd about wireless ready May 27 11:42:37 primo-plus kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 27 11:42:37 primo-plus systemd[1]: Started wireless.service - Wireless Services. May 27 11:42:37 primo-plus sudo[2665]: pam_unix(sudo:session): session closed for user root May 27 11:42:37 primo-plus volumio[1175]: info: Received Get System Info May 27 11:42:37 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 27 11:42:37 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 27 11:42:37 primo-plus volumio[1175]: info: Discovery: Getting this device information May 27 11:42:37 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:37 primo-plus volumio[1175]: info: CorePlayQueue::getTrack 0 May 27 11:42:37 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 27 11:42:37 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:37.790Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" available=true connected=false macAddress= ip4Address= ip6Address= ssid= May 27 11:42:37 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 27 11:42:37 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 27 11:42:38 primo-plus sudo[2775]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:38 primo-plus sudo[2775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:38 primo-plus sudo[2775]: pam_unix(sudo:session): session closed for user root May 27 11:42:38 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:38.630Z level=INFO msg="service successfully established" component=discovery/localnet May 27 11:42:39 primo-plus sudo[2778]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:39 primo-plus sudo[2778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:39 primo-plus sudo[2778]: pam_unix(sudo:session): session closed for user root May 27 11:42:40 primo-plus sudo[2781]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:40 primo-plus sudo[2781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:40 primo-plus sudo[2781]: pam_unix(sudo:session): session closed for user root May 27 11:42:40 primo-plus volumio[1175]: info: Received Get System Info May 27 11:42:40 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 27 11:42:40 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 27 11:42:40 primo-plus volumio[1175]: info: Discovery: Getting this device information May 27 11:42:40 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:40 primo-plus volumio[1175]: info: CorePlayQueue::getTrack 0 May 27 11:42:40 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 27 11:42:40 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:40.761Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" available=true connected=false macAddress= ip4Address= ip6Address= ssid= May 27 11:42:40 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 27 11:42:40 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 27 11:42:41 primo-plus sudo[2784]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:41 primo-plus sudo[2784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:41 primo-plus sudo[2784]: pam_unix(sudo:session): session closed for user root May 27 11:42:41 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:41.610Z level=INFO msg="service successfully established" component=discovery/localnet May 27 11:42:42 primo-plus sudo[2787]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:42 primo-plus sudo[2787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:42 primo-plus sudo[2787]: pam_unix(sudo:session): session closed for user root May 27 11:42:42 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection May 27 11:42:42 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo May 27 11:42:42 primo-plus sudo[2790]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:42 primo-plus sudo[2790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:42 primo-plus sudo[2790]: pam_unix(sudo:session): session closed for user root May 27 11:42:43 primo-plus volumio[1175]: info: Discovery: Networking Restart detected, restarting advertisement and browsing May 27 11:42:43 primo-plus volumio[1175]: info: Discovery: Restarting Advertising May 27 11:42:43 primo-plus volumio[1175]: info: Discovery: Stopping existing advertisement May 27 11:42:43 primo-plus volumio[1175]: info: Discovery: Restarting Browsing May 27 11:42:43 primo-plus sudo[2793]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:43 primo-plus sudo[2793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:43 primo-plus sudo[2793]: pam_unix(sudo:session): session closed for user root May 27 11:42:44 primo-plus volumio[1175]: info: Discovery: A device disappeared from network May 27 11:42:44 primo-plus volumio[1175]: info: Discovery: A device disappeared from network May 27 11:42:44 primo-plus sudo[2796]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:44 primo-plus sudo[2796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:44 primo-plus sudo[2796]: pam_unix(sudo:session): session closed for user root May 27 11:42:45 primo-plus sudo[2813]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:45 primo-plus sudo[2813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:45 primo-plus sudo[2813]: pam_unix(sudo:session): session closed for user root May 27 11:42:46 primo-plus sudo[2816]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:46 primo-plus sudo[2816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:46 primo-plus sudo[2816]: pam_unix(sudo:session): session closed for user root May 27 11:42:47 primo-plus sudo[2819]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:47 primo-plus sudo[2819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:47 primo-plus sudo[2819]: pam_unix(sudo:session): session closed for user root May 27 11:42:47 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart May 27 11:42:47 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart May 27 11:42:47 primo-plus volumio[1175]: info: Refreshing Cached IP Addresses May 27 11:42:47 primo-plus sudo[2823]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli May 27 11:42:47 primo-plus sudo[2823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:47 primo-plus sudo[2825]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 27 11:42:47 primo-plus sudo[2825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:47 primo-plus sudo[2825]: pam_unix(sudo:session): session closed for user root May 27 11:42:47 primo-plus sudo[2827]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:47 primo-plus sudo[2827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:47 primo-plus sudo[2823]: pam_unix(sudo:session): session closed for user root May 27 11:42:47 primo-plus sudo[2827]: pam_unix(sudo:session): session closed for user root May 27 11:42:48 primo-plus volumio[1175]: info: Discovery: Started advertising with name: Primo Plus May 27 11:42:48 primo-plus sudo[2834]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:48 primo-plus sudo[2834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:48 primo-plus sudo[2834]: pam_unix(sudo:session): session closed for user root May 27 11:42:48 primo-plus systemd[1]: upmpdcli.service: Deactivated successfully. May 27 11:42:48 primo-plus systemd[1]: upmpdcli.service: Consumed 40.496s CPU time. May 27 11:42:49 primo-plus sudo[2842]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:49 primo-plus sudo[2842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:49 primo-plus sudo[2842]: pam_unix(sudo:session): session closed for user root May 27 11:42:49 primo-plus volumio[1175]: info: Discovery: adding 1ce33c84-a0f9-4997-aaf3-559773fbf15d May 27 11:42:49 primo-plus volumio[1175]: info: Discovery: Found device Primo Plus May 27 11:42:49 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:49 primo-plus volumio[1175]: info: CorePlayQueue::getTrack 0 May 27 11:42:49 primo-plus volumio[1175]: info: Discovery: this is already registered, 1ce33c84-a0f9-4997-aaf3-559773fbf15d May 27 11:42:49 primo-plus volumio[1175]: info: Discovery: Found device Primo Plus May 27 11:42:49 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:49 primo-plus volumio[1175]: info: CorePlayQueue::getTrack 0 May 27 11:42:49 primo-plus volumio[1175]: info: Discovery: this is already registered, 1ce33c84-a0f9-4997-aaf3-559773fbf15d May 27 11:42:49 primo-plus volumio[1175]: info: Discovery: Found device Primo Plus May 27 11:42:49 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:49 primo-plus volumio[1175]: info: CorePlayQueue::getTrack 0 May 27 11:42:49 primo-plus volumio[1175]: info: Discovery: this is already registered, 1ce33c84-a0f9-4997-aaf3-559773fbf15d May 27 11:42:49 primo-plus volumio[1175]: info: Discovery: Found device Primo Plus May 27 11:42:49 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:49 primo-plus volumio[1175]: info: CorePlayQueue::getTrack 0 May 27 11:42:50 primo-plus sudo[2845]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:50 primo-plus sudo[2845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:50 primo-plus sudo[2845]: pam_unix(sudo:session): session closed for user root May 27 11:42:51 primo-plus sudo[2848]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:51 primo-plus sudo[2848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:51 primo-plus sudo[2848]: pam_unix(sudo:session): session closed for user root May 27 11:42:52 primo-plus sudo[2851]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:52 primo-plus sudo[2851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:52 primo-plus sudo[2851]: pam_unix(sudo:session): session closed for user root May 27 11:42:53 primo-plus sudo[2854]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:53 primo-plus sudo[2854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:53 primo-plus sudo[2854]: pam_unix(sudo:session): session closed for user root May 27 11:42:54 primo-plus sudo[2857]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:54 primo-plus sudo[2857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:54 primo-plus sudo[2857]: pam_unix(sudo:session): session closed for user root May 27 11:42:55 primo-plus sudo[2874]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:55 primo-plus sudo[2874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:55 primo-plus sudo[2874]: pam_unix(sudo:session): session closed for user root May 27 11:42:56 primo-plus sudo[2877]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:56 primo-plus sudo[2877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:56 primo-plus sudo[2877]: pam_unix(sudo:session): session closed for user root May 27 11:42:57 primo-plus sudo[2880]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:57 primo-plus sudo[2880]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:57 primo-plus sudo[2880]: pam_unix(sudo:session): session closed for user root May 27 11:42:57 primo-plus volumio[1175]: info: Airplay started streaming, receiving metadatas May 27 11:42:57 primo-plus volumio[1175]: info: CorePlayQueue::getTrack 0 May 27 11:42:57 primo-plus volumio[1175]: info: CoreCommandRouter::volumioStop May 27 11:42:57 primo-plus volumio[1175]: info: CoreStateMachine::stop May 27 11:42:57 primo-plus volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 11:42:57 primo-plus volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 11:42:57 primo-plus volumio[1175]: info: Airplay started streaming, receiving metadatas May 27 11:42:57 primo-plus volumio[1175]: info: CorePlayQueue::getTrack 0 May 27 11:42:57 primo-plus volumio[1175]: info: CoreCommandRouter::volumioStop May 27 11:42:57 primo-plus volumio[1175]: info: CoreStateMachine::stop May 27 11:42:57 primo-plus volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 11:42:57 primo-plus volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 11:42:57 primo-plus volumio[1175]: info: Airplay started streaming, receiving metadatas May 27 11:42:57 primo-plus volumio[1175]: info: CorePlayQueue::getTrack 0 May 27 11:42:57 primo-plus volumio[1175]: info: CoreCommandRouter::volumioStop May 27 11:42:57 primo-plus volumio[1175]: info: CoreStateMachine::stop May 27 11:42:57 primo-plus volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 11:42:57 primo-plus volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 11:42:57 primo-plus shairport-sync[2888]: % Total % Received % Xferd Average Speed Time Time Time Current May 27 11:42:57 primo-plus shairport-sync[2888]: Dload Upload Total Spent Left Speed May 27 11:42:57 primo-plus volumio[1175]: info: May 27 11:42:57 primo-plus volumio[1175]: ---------------------------- Client requests Start Airplay PlaybackRoutine May 27 11:42:57 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback May 27 11:42:57 primo-plus volumio[1175]: info: Airplay playback start May 27 11:42:57 primo-plus volumio[1175]: info: CorePlayQueue::getTrack 0 May 27 11:42:57 primo-plus volumio[1175]: info: CoreCommandRouter::volumioStop May 27 11:42:57 primo-plus volumio[1175]: info: CoreStateMachine::stop May 27 11:42:57 primo-plus volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 11:42:57 primo-plus volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 11:42:57 primo-plus shairport-sync[2888]: [79B blob data] May 27 11:42:57 primo-plus shairport-sync[2887]: % Total % Received % Xferd Average Speed Time Time Time Current May 27 11:42:57 primo-plus shairport-sync[2887]: Dload Upload Total Spent Left Speed May 27 11:42:57 primo-plus shairport-sync[2887]: [79B blob data] May 27 11:42:57 primo-plus shairport-sync[2888]: [158B blob data] May 27 11:42:57 primo-plus volumio[1175]: info: May 27 11:42:57 primo-plus volumio[1175]: ---------------------------- Client requests AirplayActive May 27 11:42:57 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive May 27 11:42:57 primo-plus shairport-sync[2888]: {"time":1779882177777,"response":"startAirplayPlayback Success"} May 27 11:42:57 primo-plus shairport-sync[2887]: [79B blob data] May 27 11:42:57 primo-plus volumio[1175]: info: Updating volume from AirPlay: -20; 33% May 27 11:42:57 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume May 27 11:42:57 primo-plus volumio[1175]: info: CoreStateMachine::pushState May 27 11:42:57 primo-plus volumio[1175]: info: CorePlayQueue::getTrack 0 May 27 11:42:57 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 11:42:57 primo-plus volumio[1175]: info: CoreCommandRouter::volumioPushState May 27 11:42:57 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:57 primo-plus volumio[1175]: info: CorePlayQueue::getTrack 0 May 27 11:42:57 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output update for this device May 27 11:42:57 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output May 27 11:42:57 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:57.826Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" state=STATUS_STOPPED positionMs=0 volume=33 May 27 11:42:57 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:57.826Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" id= title= May 27 11:42:57 primo-plus volumio[1175]: info: Updating RAAT Signal Path May 27 11:42:57 primo-plus sudo[2894]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 27 11:42:57 primo-plus sudo[2894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:57 primo-plus sudo[2896]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:57 primo-plus sudo[2896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:57 primo-plus sudo[2894]: pam_unix(sudo:session): session closed for user root May 27 11:42:57 primo-plus sudo[2896]: pam_unix(sudo:session): session closed for user root May 27 11:42:57 primo-plus volumio[1175]: info: CoreStateMachine::pushState May 27 11:42:57 primo-plus volumio[1175]: info: CorePlayQueue::getTrack 0 May 27 11:42:57 primo-plus volumio[1175]: info: CoreCommandRouter::volumioPushState May 27 11:42:57 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:57 primo-plus volumio[1175]: info: CorePlayQueue::getTrack 0 May 27 11:42:57 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output update for this device May 27 11:42:57 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output May 27 11:42:57 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:57.915Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" state=STATUS_STOPPED positionMs=0 volume=33 May 27 11:42:57 primo-plus sudo[2899]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 27 11:42:57 primo-plus sudo[2899]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:57 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:57.916Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" id= title= May 27 11:42:57 primo-plus volumio[1175]: info: Updating RAAT Signal Path May 27 11:42:58 primo-plus sudo[2903]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:58 primo-plus sudo[2903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:58 primo-plus sudo[2903]: pam_unix(sudo:session): session closed for user root May 27 11:42:58 primo-plus volumio[1175]: info: CoreCommandRouter::servicePushState May 27 11:42:58 primo-plus volumio[1175]: info: CoreStateMachine::pushState May 27 11:42:58 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 11:42:58 primo-plus volumio[1175]: info: CoreCommandRouter::volumioPushState May 27 11:42:58 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:58 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output update for this device May 27 11:42:58 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output May 27 11:42:58 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:58.774Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" state=STATUS_PLAYING positionMs=0 volume=33 May 27 11:42:58 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:58.775Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" id= title= May 27 11:42:58 primo-plus volumio[1175]: info: Signalling Playback active due to playback status change May 27 11:42:58 primo-plus volumio[1175]: info: Updating RAAT Signal Path May 27 11:42:58 primo-plus volumio[1175]: info: CoreCommandRouter::servicePushState May 27 11:42:58 primo-plus volumio[1175]: info: CoreStateMachine::pushState May 27 11:42:58 primo-plus volumio[1175]: info: CoreCommandRouter::volumioPushState May 27 11:42:58 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:58 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output update for this device May 27 11:42:58 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output May 27 11:42:58 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:58.817Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" state=STATUS_PLAYING positionMs=0 volume=33 May 27 11:42:58 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:58.817Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" id= title= May 27 11:42:58 primo-plus volumio[1175]: info: CoreCommandRouter::servicePushState May 27 11:42:58 primo-plus volumio[1175]: info: CoreStateMachine::pushState May 27 11:42:58 primo-plus volumio[1175]: info: CoreCommandRouter::volumioPushState May 27 11:42:58 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:58 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output update for this device May 27 11:42:58 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output May 27 11:42:58 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:58.834Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" state=STATUS_PLAYING positionMs=0 volume=33 May 27 11:42:58 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:58.835Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" id= title= May 27 11:42:58 primo-plus volumio[1175]: info: CoreCommandRouter::servicePushState May 27 11:42:58 primo-plus volumio[1175]: info: CoreStateMachine::pushState May 27 11:42:58 primo-plus volumio[1175]: info: CoreCommandRouter::volumioPushState May 27 11:42:58 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:58 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output update for this device May 27 11:42:58 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output May 27 11:42:58 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:58.849Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" state=STATUS_PLAYING positionMs=0 volume=33 May 27 11:42:58 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:58.852Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" id= title= May 27 11:42:58 primo-plus volumio[1175]: info: Signalling Playback active due to playback status change May 27 11:42:58 primo-plus volumio[1175]: info: Signalling Playback active due to playback status change May 27 11:42:58 primo-plus volumio[1175]: info: Signalling Playback active due to playback status change May 27 11:42:58 primo-plus volumio[1175]: info: Updating RAAT Signal Path May 27 11:42:58 primo-plus volumio[1175]: info: Updating RAAT Signal Path May 27 11:42:58 primo-plus volumio[1175]: info: Updating RAAT Signal Path May 27 11:42:58 primo-plus volumio[1175]: info: MCU Signalled Playback Active May 27 11:42:59 primo-plus sudo[2915]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:42:59 primo-plus sudo[2915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:42:59 primo-plus sudo[2915]: pam_unix(sudo:session): session closed for user root May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::servicePushState May 27 11:42:59 primo-plus volumio[1175]: info: CoreStateMachine::pushState May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::volumioPushState May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:59 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output update for this device May 27 11:42:59 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::servicePushState May 27 11:42:59 primo-plus volumio[1175]: info: CoreStateMachine::pushState May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::volumioPushState May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:59 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output update for this device May 27 11:42:59 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::servicePushState May 27 11:42:59 primo-plus volumio[1175]: info: CoreStateMachine::pushState May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::volumioPushState May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:59 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output update for this device May 27 11:42:59 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output May 27 11:42:59 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:59.586Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" state=STATUS_PLAYING positionMs= volume=33 May 27 11:42:59 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:59.587Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" id= title= May 27 11:42:59 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:59.588Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" state=STATUS_PLAYING positionMs= volume=33 May 27 11:42:59 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:59.588Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" id= title= May 27 11:42:59 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:59.590Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" state=STATUS_PLAYING positionMs= volume=33 May 27 11:42:59 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:59.590Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" id= title= May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::servicePushState May 27 11:42:59 primo-plus volumio[1175]: info: CoreStateMachine::pushState May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::volumioPushState May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:59 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output update for this device May 27 11:42:59 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::servicePushState May 27 11:42:59 primo-plus volumio[1175]: info: CoreStateMachine::pushState May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::volumioPushState May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:59 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output update for this device May 27 11:42:59 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::servicePushState May 27 11:42:59 primo-plus volumio[1175]: info: CoreStateMachine::pushState May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::volumioPushState May 27 11:42:59 primo-plus volumio[1175]: info: CoreCommandRouter::volumioGetState May 27 11:42:59 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output update for this device May 27 11:42:59 primo-plus volumio[1175]: info: MRS: Pushing multiroomSync output May 27 11:42:59 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:59.638Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" state=STATUS_PLAYING positionMs= volume=33 May 27 11:42:59 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:59.639Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" id= title="The Sound of Silence" May 27 11:42:59 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:59.641Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" state=STATUS_PLAYING positionMs= volume=33 May 27 11:42:59 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:59.642Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" id= title="The Sound of Silence" May 27 11:42:59 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:59.643Z level=INFO msg="emitting player state changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" state=STATUS_PLAYING positionMs= volume=33 May 27 11:42:59 primo-plus volumio5-onboarding[1986]: time=2026-05-27T11:42:59.643Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.3.53:53987,00:00:00:00:00:00%01 @ 0x1d0ea50" id= title="The Sound of Silence" May 27 11:42:59 primo-plus volumio[1175]: info: Signalling Playback active due to playback status change May 27 11:42:59 primo-plus volumio[1175]: info: Signalling Playback active due to playback status change May 27 11:42:59 primo-plus volumio[1175]: info: Signalling Playback active due to playback status change May 27 11:42:59 primo-plus volumio[1175]: info: Signalling Playback active due to playback status change May 27 11:42:59 primo-plus volumio[1175]: info: Signalling Playback active due to playback status change May 27 11:42:59 primo-plus volumio[1175]: info: Signalling Playback active due to playback status change May 27 11:42:59 primo-plus volumio[1175]: info: Updating RAAT Signal Path May 27 11:42:59 primo-plus volumio[1175]: info: Updating RAAT Signal Path May 27 11:42:59 primo-plus volumio[1175]: info: Updating RAAT Signal Path May 27 11:42:59 primo-plus volumio[1175]: info: Updating RAAT Signal Path May 27 11:42:59 primo-plus volumio[1175]: info: Updating RAAT Signal Path May 27 11:42:59 primo-plus volumio[1175]: info: Updating RAAT Signal Path May 27 11:43:00 primo-plus volumio[1175]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 11:43:00 primo-plus sudo[2918]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 11:43:00 primo-plus sudo[2918]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 11:43:00 primo-plus volumio[1175]: Error: certificate is not yet valid May 27 11:43:00 primo-plus volumio[1175]: at TLSSocket.onConnectSecure (node:_tls_wrap:1627:34) May 27 11:43:00 primo-plus volumio[1175]: at TLSSocket.emit (node:events:514:28) May 27 11:43:00 primo-plus volumio[1175]: at TLSSocket._finishInit (node:_tls_wrap:1038:8) May 27 11:43:00 primo-plus volumio[1175]: at ssl.onhandshakedone (node:_tls_wrap:824:12) { May 27 11:43:00 primo-plus volumio[1175]: code: 'CERT_NOT_YET_VALID' May 27 11:43:00 primo-plus volumio[1175]: } May 27 11:43:00 primo-plus volumio[1175]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 11:43:00 primo-plus sudo[2918]: pam_unix(sudo:session): session closed for user root May 27 11:43:00 primo-plus sudo[2934]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-27 11:42' May 27 11:43:00 primo-plus sudo[2934]: 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="ceea798be624bcca033d94ae449c2a749a9724f0" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="9938d7179e3b7c4e41f3e2d60c255985cff08fee" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="primoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 27 09:37:15 UTC 2026" VOLUMIO_VERSION="4.164" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Primo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo Plus" VOLUMIO_HASH="c8e7083e83ff605518b1cfc23784b0e7"