-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Sun 2026-03-29 12:42:02 JST. -- Mar 29 12:41:02 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 12:41:02 primo-plus volumio[1078]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 12:41:02 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 12:41:02 primo-plus volumio[1078]: info: Received Get System Version Mar 29 12:41:02 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 12:41:02 primo-plus volumio[1078]: info: Received Get System Info Mar 29 12:41:02 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:02 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:02 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:02 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:02 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:02 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:06 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard Mar 29 12:41:06 primo-plus volumio[1078]: info: CoreCommandRouter::Reload Ui Mar 29 12:41:07 primo-plus sudo[4973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 12:41:07 primo-plus sudo[4973]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:07 primo-plus sudo[4973]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:07 primo-plus sudo[4975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 12:41:07 primo-plus sudo[4975]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:07 primo-plus sudo[4975]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:08 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.200 from 192.168.104.193 UA: Mozilla/5.0 (Linux; Android 16; A30L1 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.164 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 29 12:41:08 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 12:41:08 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:41:08 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Mar 29 12:41:08 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Mar 29 12:41:08 primo-plus volumio[1078]: No protocol specified Mar 29 12:41:08 primo-plus volumio[1078]: xcb_connection_has_error() returned true Mar 29 12:41:08 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Mar 29 12:41:08 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Mar 29 12:41:08 primo-plus volumio[1078]: info: Received Get System Info Mar 29 12:41:08 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:08 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:08 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:08 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:08 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:08 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:08 primo-plus volumio-remote-updater[654]: No test mode Mar 29 12:41:08 primo-plus volumio-remote-updater[654]: No alpha test mode Mar 29 12:41:08 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 12:41:08 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 12:41:08 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:08 primo-plus volumio[1078]: info: Retrieving Cloud Streaming UI Mar 29 12:41:08 primo-plus volumio[1078]: info: Getting Tidal Cloud Configuration Mar 29 12:41:08 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:08 primo-plus volumio[1078]: info: Getting Qobuz Cloud Configuration Mar 29 12:41:08 primo-plus volumio[1078]: info: Asking plugin for UI Config Mar 29 12:41:08 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:08 primo-plus volumio[1078]: info: Getting Spotify Cloud Configuration Mar 29 12:41:08 primo-plus volumio[1078]: info: Asking plugin for UI Config Mar 29 12:41:08 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:08 primo-plus volumio[1078]: info: Saving Spotify Acccount Mar 29 12:41:08 primo-plus volumio[1078]: info: Got it Mar 29 12:41:08 primo-plus volumio[1078]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 29 12:41:08 primo-plus volumio[1078]: info: Got Tidal Cloud Configuration Mar 29 12:41:08 primo-plus volumio[1078]: info: Got it Mar 29 12:41:08 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:08 primo-plus volumio[1078]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 29 12:41:08 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 29 12:41:09 primo-plus volumio[1078]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 9 Mar 29 12:41:09 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 29 12:41:09 primo-plus volumio[1078]: info: Loading i18n strings for locale ja Mar 29 12:41:09 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 29 12:41:09 primo-plus volumio[1078]: info: Setting timezone to Asia/Tokyo Mar 29 12:41:09 primo-plus sudo[4989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 29 12:41:09 primo-plus sudo[4989]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:09 primo-plus sudo[4989]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:09 primo-plus sudo[4993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Tokyo /etc/localtime Mar 29 12:41:09 primo-plus sudo[4993]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:09 primo-plus sudo[4993]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:09 primo-plus sudo[4998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 29 12:41:09 primo-plus sudo[4998]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:09 primo-plus sudo[4998]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:09 primo-plus sudo[5001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Tokyo Mar 29 12:41:09 primo-plus sudo[5001]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:09 primo-plus dbus-daemon[617]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.64' (uid=0 pid=5002 comm="/usr/bin/timedatectl set-timezone Asia/Tokyo ") Mar 29 12:41:09 primo-plus systemd[1]: Starting Time & Date Service... Mar 29 12:41:10 primo-plus dbus-daemon[617]: [system] Successfully activated service 'org.freedesktop.timedate1' Mar 29 12:41:10 primo-plus systemd[1]: Started Time & Date Service. Mar 29 12:41:10 primo-plus sudo[5001]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:10 primo-plus volumio[1078]: info: Fetching Streaming Services browse cache Mar 29 12:41:10 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 12:41:10 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 12:41:10 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:41:10 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Mar 29 12:41:10 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Mar 29 12:41:10 primo-plus volumio[1078]: No protocol specified Mar 29 12:41:10 primo-plus volumio[1078]: xcb_connection_has_error() returned true Mar 29 12:41:10 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Mar 29 12:41:10 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Mar 29 12:41:10 primo-plus volumio[1078]: info: Received Get System Info Mar 29 12:41:10 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:10 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:10 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:10 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:10 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:10 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:10 primo-plus volumio-remote-updater[654]: No test mode Mar 29 12:41:10 primo-plus volumio-remote-updater[654]: No alpha test mode Mar 29 12:41:10 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 12:41:10 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 12:41:10 primo-plus volumio[1078]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 29 12:41:10 primo-plus volumio[1078]: Updating browse sources language Mar 29 12:41:10 primo-plus volumio[1078]: Cannot find translation for source MC-3+ Smart Clock USB 2.0 Mar 29 12:41:10 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:41:10 primo-plus volumio[1078]: Cannot find translation for source MC-3+ Smart Clock USB 2.0 Mar 29 12:41:10 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 12:41:10 primo-plus volumio[1078]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 29 12:41:10 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 29 12:41:11 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:11 primo-plus volumio[1078]: info: Retrieving Cloud Streaming UI Mar 29 12:41:11 primo-plus volumio[1078]: info: Getting Tidal Cloud Configuration Mar 29 12:41:11 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:11 primo-plus volumio[1078]: info: Getting Qobuz Cloud Configuration Mar 29 12:41:11 primo-plus volumio[1078]: info: Asking plugin for UI Config Mar 29 12:41:11 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:11 primo-plus volumio[1078]: info: Getting Spotify Cloud Configuration Mar 29 12:41:11 primo-plus volumio[1078]: info: Asking plugin for UI Config Mar 29 12:41:11 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:11 primo-plus volumio[1078]: info: Saving Spotify Acccount Mar 29 12:41:11 primo-plus volumio[1078]: info: Got it Mar 29 12:41:11 primo-plus volumio[1078]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 29 12:41:11 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 12:41:11 primo-plus volumio[1078]: info: Got Tidal Cloud Configuration Mar 29 12:41:11 primo-plus volumio[1078]: info: Got it Mar 29 12:41:11 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:12 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 29 12:41:12 primo-plus volumio[1078]: info: Loading i18n strings for locale ja Mar 29 12:41:12 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 29 12:41:12 primo-plus volumio[1078]: info: Setting timezone to Asia/Tokyo Mar 29 12:41:12 primo-plus sudo[5023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 29 12:41:12 primo-plus sudo[5023]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:12 primo-plus sudo[5023]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:12 primo-plus sudo[5026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Tokyo /etc/localtime Mar 29 12:41:12 primo-plus sudo[5026]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:12 primo-plus sudo[5026]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:12 primo-plus sudo[5029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 29 12:41:12 primo-plus sudo[5029]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:12 primo-plus sudo[5029]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:12 primo-plus sudo[5032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Tokyo Mar 29 12:41:12 primo-plus sudo[5032]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:12 primo-plus sudo[5032]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:12 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 29 12:41:12 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 29 12:41:12 primo-plus sudo[5035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 29 12:41:12 primo-plus sudo[5035]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:12 primo-plus volumio[1078]: Updating browse sources language Mar 29 12:41:12 primo-plus volumio[1078]: Cannot find translation for source MC-3+ Smart Clock USB 2.0 Mar 29 12:41:12 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:41:12 primo-plus volumio[1078]: Cannot find translation for source MC-3+ Smart Clock USB 2.0 Mar 29 12:41:12 primo-plus volumio[1078]: info: Fetching Streaming Services browse cache Mar 29 12:41:12 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 29 12:41:12 primo-plus volumio[1078]: info: Loading i18n strings for locale en Mar 29 12:41:12 primo-plus volumio[1078]: Updating browse sources language Mar 29 12:41:12 primo-plus volumio[1078]: Cannot find translation for source MC-3+ Smart Clock USB 2.0 Mar 29 12:41:12 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:41:12 primo-plus volumio[1078]: Cannot find translation for source MC-3+ Smart Clock USB 2.0 Mar 29 12:41:12 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 29 12:41:12 primo-plus volumio[1078]: info: Setting timezone to Asia/Tokyo Mar 29 12:41:12 primo-plus sudo[5038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 29 12:41:12 primo-plus sudo[5038]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:12 primo-plus sudo[5038]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:12 primo-plus sudo[5041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Tokyo /etc/localtime Mar 29 12:41:12 primo-plus sudo[5041]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:12 primo-plus sudo[5041]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:13 primo-plus sudo[5045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 29 12:41:13 primo-plus sudo[5045]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:13 primo-plus sudo[5045]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:13 primo-plus sudo[5048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Tokyo Mar 29 12:41:13 primo-plus sudo[5048]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:13 primo-plus sudo[5048]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:13 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 12:41:13 primo-plus volumio[1078]: info: Fetching Streaming Services browse cache Mar 29 12:41:16 primo-plus sudo[5035]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:16 primo-plus volumio[1078]: info: Received Get System Info Mar 29 12:41:16 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:16 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:16 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:16 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:16 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:16 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:16 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:16.591+09:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.104.193:38902,192.168.104.193:43076,00:00:00:00:00:00%08 @ 0x1ef5e90" available=true connected=true macAddress=d8:3a:dd:ea:47:49 ip4Address=192.168.104.201/24 ip6Address= ssid=OPT-AP-14c4-2.4G Mar 29 12:41:16 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:16.592+09:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.104.193:43076 @ 0x2034cf0" available=true connected=true macAddress=d8:3a:dd:ea:47:49 ip4Address=192.168.104.201/24 ip6Address= ssid=OPT-AP-14c4-2.4G Mar 29 12:41:16 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:16 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:17 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:17.405+09:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 29 12:41:17 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:17 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:17 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:17 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:17 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:17 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:17 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.200:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Mar 29 12:41:17 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:17 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:17 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:17 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:17 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:17 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:17 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:17 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:17 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.201:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Mar 29 12:41:17 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:17 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:18 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:18 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:18 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:18 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:18 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:18 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:18 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.200:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Mar 29 12:41:18 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:18 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:18 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:18 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:18 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:18 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:18 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:18 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:18 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.201:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Mar 29 12:41:18 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:18 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:19 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:19 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:19 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:19 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:19 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:19 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:19 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.200:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Mar 29 12:41:19 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:19 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:19 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:19 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:19 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:19 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:19 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:19 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:19 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.201:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Mar 29 12:41:19 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:19 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:20 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:20 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:20 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:20 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:20 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:20 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:20 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.200:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Mar 29 12:41:20 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:20 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:20 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:20 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:20 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:20 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:20 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:20 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:20 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.201:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Mar 29 12:41:20 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:20 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:23 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Mar 29 12:41:23 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Mar 29 12:41:23 primo-plus volumio[1078]: No protocol specified Mar 29 12:41:23 primo-plus volumio[1078]: xcb_connection_has_error() returned true Mar 29 12:41:23 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 12:41:26 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 12:41:26 primo-plus volumio[1078]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 12:41:26 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 12:41:26 primo-plus volumio[1078]: info: Received Get System Version Mar 29 12:41:26 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 12:41:26 primo-plus volumio[1078]: info: Received Get System Info Mar 29 12:41:26 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:26 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:26 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:26 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:26 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:26 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:27 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 12:41:27 primo-plus volumio[1078]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 12:41:27 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 12:41:27 primo-plus volumio[1078]: info: Received Get System Version Mar 29 12:41:27 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 12:41:27 primo-plus volumio[1078]: info: Received Get System Info Mar 29 12:41:27 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:27 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:27 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:27 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:27 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:27 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 29 12:41:29 primo-plus volumio[1078]: info: Preparing to save Alsa Options, stopping services first Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:29 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreCommandRouter::volumioPause Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreStateMachine::pause Mar 29 12:41:29 primo-plus volumio[1078]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"0","label":"Analog Outputs"},"disallowPush":true} Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 12:41:29 primo-plus volumio[1078]: No protocol specified Mar 29 12:41:29 primo-plus volumio[1078]: xcb_connection_has_error() returned true Mar 29 12:41:29 primo-plus volumio[1078]: No protocol specified Mar 29 12:41:29 primo-plus volumio[1078]: xcb_connection_has_error() returned true Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 12:41:29 primo-plus volumio[1078]: No protocol specified Mar 29 12:41:29 primo-plus volumio[1078]: xcb_connection_has_error() returned true Mar 29 12:41:29 primo-plus volumio[1078]: info: Setting default mixerSoftMaster for Softvolume device Mar 29 12:41:29 primo-plus volumio[1078]: info: QobuzConnect: setDeactiveState invoked Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:29 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:29 primo-plus volumio[1078]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Mar 29 12:41:29 primo-plus vtcs[4245]: [2026-03-29 12:41:29.665] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Mar 29 12:41:29 primo-plus vtcs[4245]: [2026-03-29 12:41:29.666] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Mar 29 12:41:29 primo-plus sudo[5115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 29 12:41:29 primo-plus sudo[5115]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:29 primo-plus systemd[1]: Stopping Volumio Tidal Connect Service... Mar 29 12:41:29 primo-plus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 29 12:41:29 primo-plus systemd[1]: vtcs.service: Succeeded. Mar 29 12:41:29 primo-plus systemd[1]: Stopped Volumio Tidal Connect Service. Mar 29 12:41:29 primo-plus volumio[1078]: No protocol specified Mar 29 12:41:29 primo-plus volumio[1078]: xcb_connection_has_error() returned true Mar 29 12:41:29 primo-plus volumio[1078]: info: Applying Volume Override Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 29 12:41:29 primo-plus volumio[1078]: info: Updating Volume Controller Parameters: Device: 0 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 29 12:41:29 primo-plus sudo[5115]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 29 12:41:29 primo-plus volumio[1078]: info: Enabling external Volume Control Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: inputs , updateVolumeSettings Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:29 primo-plus volumio[1078]: info: Enable softmixer device for audio device undefined Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreStateMachine::pushState Mar 29 12:41:29 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreCommandRouter::volumioPushState Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:29 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:29 primo-plus volumio[1078]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:41:29 primo-plus volumio[1078]: info: MRS: Pushing multiroomSync output Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreStateMachine::pushState Mar 29 12:41:29 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreCommandRouter::volumioPushState Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:29 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:29 primo-plus volumio[1078]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:41:29 primo-plus volumio[1078]: info: MRS: Pushing multiroomSync output Mar 29 12:41:29 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:29.850+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.193:38902,192.168.104.193:43076,00:00:00:00:00:00%08 @ 0x1ef5e90" state=STATUS_STOPPED positionMs=254204 volume=73 Mar 29 12:41:29 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:29.851+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.193:43076 @ 0x2034cf0" state=STATUS_STOPPED positionMs=254204 volume=73 Mar 29 12:41:29 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:29.852+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.193:38902,192.168.104.193:43076,00:00:00:00:00:00%08 @ 0x1ef5e90" id=tidal://song/1965091 title=Vox Mar 29 12:41:29 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:29.852+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.193:43076 @ 0x2034cf0" id=tidal://song/1965091 title=Vox Mar 29 12:41:29 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:29.853+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.193:38902,192.168.104.193:43076,00:00:00:00:00:00%08 @ 0x1ef5e90" state=STATUS_STOPPED positionMs=254204 volume=73 Mar 29 12:41:29 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:29.853+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.193:43076 @ 0x2034cf0" state=STATUS_STOPPED positionMs=254204 volume=73 Mar 29 12:41:29 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:29.853+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.193:38902,192.168.104.193:43076,00:00:00:00:00:00%08 @ 0x1ef5e90" id=tidal://song/1965091 title=Vox Mar 29 12:41:29 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:29.855+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.193:43076 @ 0x2034cf0" id=tidal://song/1965091 title=Vox Mar 29 12:41:29 primo-plus sudo[5122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 29 12:41:29 primo-plus sudo[5122]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:29 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:29 primo-plus volumio[1078]: info: Retrieving Cloud Streaming UI Mar 29 12:41:29 primo-plus sudo[5122]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:30 primo-plus volumio[1078]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 29 12:41:30 primo-plus sudo[5128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 29 12:41:30 primo-plus sudo[5128]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:30 primo-plus volumio[1078]: info: Updating RAAT Signal Path Mar 29 12:41:30 primo-plus volumio[1078]: info: Updating RAAT Signal Path Mar 29 12:41:30 primo-plus sudo[5128]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:30 primo-plus volumio[1078]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Mar 29 12:41:30 primo-plus volumio[1078]: xcb_connection_has_error() returned true Mar 29 12:41:30 primo-plus volumio[1078]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Mar 29 12:41:30 primo-plus volumio[1078]: info: Software Volume ALSA configuration written Mar 29 12:41:30 primo-plus volumio[1078]: info: Preparing to generate the ALSA configuration file Mar 29 12:41:30 primo-plus sudo[5131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 29 12:41:30 primo-plus sudo[5131]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:30 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:30 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:30 primo-plus systemd[1]: Stopping Volumio Qobuz Connect Service... Mar 29 12:41:30 primo-plus qobuz-connect[4177]: 20260329 12:41:30.125 [4177.4177] INFO SampleApp: Stopping Local configuration server Mar 29 12:41:30 primo-plus qobuz-connect[4177]: 20260329 12:41:30.135 [4177.4177] INFO SampleApp: shat down connection on UNIX socket Mar 29 12:41:30 primo-plus systemd[1]: qobuz-connect.service: Succeeded. Mar 29 12:41:30 primo-plus systemd[1]: Stopped Volumio Qobuz Connect Service. Mar 29 12:41:30 primo-plus systemd[1]: Started Volumio Qobuz Connect Service. Mar 29 12:41:30 primo-plus sudo[5131]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:30 primo-plus volumio[1078]: No protocol specified Mar 29 12:41:30 primo-plus volumio[1078]: xcb_connection_has_error() returned true Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 12:41:30 primo-plus volumio[1078]: No protocol specified Mar 29 12:41:30 primo-plus volumio[1078]: xcb_connection_has_error() returned true Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Mar 29 12:41:30 primo-plus volumio[1078]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 29 12:41:30 primo-plus volumio[1078]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 29 12:41:30 primo-plus volumio[1078]: info: Reading ALSA contributions from plugins. Mar 29 12:41:30 primo-plus volumio[1078]: info: Getting Tidal Cloud Configuration Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:30 primo-plus volumio[1078]: info: Getting Qobuz Cloud Configuration Mar 29 12:41:30 primo-plus volumio[1078]: info: Asking plugin for UI Config Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:30 primo-plus volumio[1078]: info: Getting Spotify Cloud Configuration Mar 29 12:41:30 primo-plus volumio[1078]: info: Asking plugin for UI Config Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:30 primo-plus volumio[1078]: info: Saving Spotify Acccount Mar 29 12:41:30 primo-plus volumio[1078]: info: Got it Mar 29 12:41:30 primo-plus volumio[1078]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 29 12:41:30 primo-plus volumio[1078]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 29 12:41:30 primo-plus volumio[1078]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:41:30 primo-plus volumio[1078]: info: Not Reporting Auto name since its the default one Mar 29 12:41:30 primo-plus volumio[1078]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 29 12:41:30 primo-plus volumio[1078]: info: QobuzConnect: setDeactiveState invoked Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:30 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:30 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.200:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Mar 29 12:41:30 primo-plus volumio[1078]: info: Executing endpoint qc_getconfig Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 29 12:41:30 primo-plus qobuz-connect[5134]: 20260329 12:41:30.493 [5134.5134] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:30 primo-plus volumio[1078]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 29 12:41:30 primo-plus volumio[1078]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 29 12:41:30 primo-plus qobuz-connect[5134]: 20260329 12:41:30.502 [5134.5134] INFO VolumeManager: [0x2565fc8]: Setting new playback volume: 75 Mar 29 12:41:30 primo-plus qobuz-connect[5134]: 20260329 12:41:30.502 [5134.5134] INFO VolumeManager: [0x2565fc8]: Setting new mute state: 0 Mar 29 12:41:30 primo-plus qobuz-connect[5134]: 20260329 12:41:30.502 [5134.5134] INFO AudioStreamManager: [0x2565d20]: Setting new audio download buffer size: 1048576 Mar 29 12:41:30 primo-plus qobuz-connect[5134]: 20260329 12:41:30.502 [5134.5134] INFO QobuzConnect: [0x2566890]: Client initialized! Mar 29 12:41:30 primo-plus qobuz-connect[5134]: 20260329 12:41:30.502 [5134.5134] INFO SampleApp: Starting Avahi advertising, name: Primo Plus, service name: _qobuz-connect._tcp Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:30 primo-plus qobuz-connect[5134]: 20260329 12:41:30.517 [5134.5134] INFO LocalConfigManager: [0x2565a48]: Starting Local Configuration server Mar 29 12:41:30 primo-plus qobuz-connect[5134]: 20260329 12:41:30.517 [5134.5134] INFO SampleApp: Starting Local configuration server Mar 29 12:41:30 primo-plus qobuz-connect[5134]: 20260329 12:41:30.518 [5134.5134] INFO SampleApp: Connected to UNIX socket client 0x255bf60 Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 29 12:41:30 primo-plus volumio[1078]: No protocol specified Mar 29 12:41:30 primo-plus volumio[1078]: xcb_connection_has_error() returned true Mar 29 12:41:30 primo-plus qobuz-connect[5134]: 20260329 12:41:30.643 [5134.5134] INFO SampleApp: Playback volume changed: 75 Mar 29 12:41:30 primo-plus volumio[1078]: info: Got Tidal Cloud Configuration Mar 29 12:41:30 primo-plus volumio[1078]: info: Got it Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:30 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:30 primo-plus sudo[5150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 29 12:41:30 primo-plus sudo[5150]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:30 primo-plus volumio[1078]: info: Asound.conf file unchanged, so no further update is needed Mar 29 12:41:30 primo-plus volumio[1078]: info: Output device has changed, restarting MPD Mar 29 12:41:30 primo-plus sudo[5150]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:30 primo-plus sudo[5154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 29 12:41:30 primo-plus sudo[5154]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:30 primo-plus sudo[5153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 12:41:30 primo-plus sudo[5153]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:30 primo-plus volumio[1078]: info: Output device has changed, restarting Shairport Sync Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:30 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:41:30 primo-plus sudo[5153]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:30 primo-plus sudo[5157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 12:41:30 primo-plus sudo[5157]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:30 primo-plus systemd[1]: Stopping RAAT DAEMON... Mar 29 12:41:30 primo-plus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 29 12:41:30 primo-plus systemd[1]: raat-daemon.service: Succeeded. Mar 29 12:41:30 primo-plus systemd[1]: Stopped RAAT DAEMON. Mar 29 12:41:30 primo-plus systemd[1]: Started RAAT DAEMON. Mar 29 12:41:30 primo-plus sudo[5154]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:30 primo-plus systemd[1]: Stopping Music Player Daemon... Mar 29 12:41:31 primo-plus systemd[1]: mpd.service: Succeeded. Mar 29 12:41:31 primo-plus systemd[1]: Stopped Music Player Daemon. Mar 29 12:41:31 primo-plus volumio[1078]: No protocol specified Mar 29 12:41:31 primo-plus volumio[1078]: xcb_connection_has_error() returned true Mar 29 12:41:31 primo-plus volumio[1078]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 12:41:31 primo-plus volumio[1078]: No protocol specified Mar 29 12:41:31 primo-plus volumio[1078]: xcb_connection_has_error() returned true Mar 29 12:41:31 primo-plus volumio[1078]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:31 primo-plus systemd[1]: Starting Music Player Daemon... Mar 29 12:41:31 primo-plus volumio[1078]: info: QobuzConnect: setDeactiveState invoked Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:31 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:31 primo-plus volumio[1078]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:31 primo-plus sudo[5172]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 29 12:41:31 primo-plus sudo[5172]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:31 primo-plus sudo[5172]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:31 primo-plus sudo[5186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 29 12:41:31 primo-plus sudo[5186]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:31 primo-plus sudo[5191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 29 12:41:31 primo-plus sudo[5191]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:31 primo-plus sudo[5186]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:31 primo-plus sudo[5191]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:31 primo-plus sudo[5197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 29 12:41:31 primo-plus sudo[5197]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:31 primo-plus sudo[5197]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:31 primo-plus volumio[1078]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 29 12:41:31 primo-plus volumio[1078]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 29 12:41:31 primo-plus volumio[1078]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 29 12:41:31 primo-plus sudo[5201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 29 12:41:31 primo-plus sudo[5201]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:31 primo-plus volumio[1078]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 29 12:41:31 primo-plus volumio[1078]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 29 12:41:31 primo-plus volumio[1078]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 29 12:41:31 primo-plus systemd[1]: Stopping Volumio Qobuz Connect Service... Mar 29 12:41:31 primo-plus qobuz-connect[5134]: 20260329 12:41:31.572 [5134.5134] INFO SampleApp: Stopping Local configuration server Mar 29 12:41:31 primo-plus volumio[1078]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 29 12:41:31 primo-plus volumio[1078]: info: MPD Permissions set Mar 29 12:41:31 primo-plus volumio[1078]: info: Raat Daemon started successfully Mar 29 12:41:31 primo-plus qobuz-connect[5134]: 20260329 12:41:31.582 [5134.5134] INFO SampleApp: shat down connection on UNIX socket Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreStateMachine::pushState Mar 29 12:41:31 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:31 primo-plus systemd[1]: qobuz-connect.service: Succeeded. Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:41:31 primo-plus systemd[1]: Stopped Volumio Qobuz Connect Service. Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::volumioPushState Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:31 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:31 primo-plus volumio[1078]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:41:31 primo-plus volumio[1078]: info: MRS: Pushing multiroomSync output Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreStateMachine::pushState Mar 29 12:41:31 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::volumioPushState Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:31 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:31 primo-plus volumio[1078]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:41:31 primo-plus volumio[1078]: info: MRS: Pushing multiroomSync output Mar 29 12:41:31 primo-plus systemd[1]: Started Volumio Qobuz Connect Service. Mar 29 12:41:31 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:31.629+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.193:38902,192.168.104.193:43076,00:00:00:00:00:00%08 @ 0x1ef5e90" state=STATUS_STOPPED positionMs=256391 volume=73 Mar 29 12:41:31 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:31.633+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.193:43076 @ 0x2034cf0" state=STATUS_STOPPED positionMs=256391 volume=73 Mar 29 12:41:31 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:31.634+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.193:38902,192.168.104.193:43076,00:00:00:00:00:00%08 @ 0x1ef5e90" id=tidal://song/1965091 title=Vox Mar 29 12:41:31 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:31.634+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.193:43076 @ 0x2034cf0" id=tidal://song/1965091 title=Vox Mar 29 12:41:31 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:31.643+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.193:38902,192.168.104.193:43076,00:00:00:00:00:00%08 @ 0x1ef5e90" state=STATUS_STOPPED positionMs=256391 volume=73 Mar 29 12:41:31 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:31.644+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.193:43076 @ 0x2034cf0" state=STATUS_STOPPED positionMs=256391 volume=73 Mar 29 12:41:31 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:31.644+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.193:38902,192.168.104.193:43076,00:00:00:00:00:00%08 @ 0x1ef5e90" id=tidal://song/1965091 title=Vox Mar 29 12:41:31 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:31.645+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.193:43076 @ 0x2034cf0" id=tidal://song/1965091 title=Vox Mar 29 12:41:31 primo-plus sudo[5201]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:31 primo-plus volumio[1078]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 29 12:41:31 primo-plus volumio[1078]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:31 primo-plus volumio[1078]: info: Retrieving Cloud Streaming UI Mar 29 12:41:31 primo-plus volumio[1078]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Mar 29 12:41:31 primo-plus volumio[1078]: info: QobuzConnect: setDeactiveState invoked Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:31 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 12:41:31 primo-plus volumio[1078]: info: Not Reporting Auto name since its the default one Mar 29 12:41:31 primo-plus volumio[1078]: info: Updating RAAT Signal Path Mar 29 12:41:31 primo-plus volumio[1078]: info: Updating RAAT Signal Path Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:31 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:31 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:31 primo-plus volumio[1078]: info: Executing endpoint qc_getconfig Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 29 12:41:31 primo-plus qobuz-connect[5204]: 20260329 12:41:31.951 [5204.5204] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 29 12:41:31 primo-plus volumio[1078]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 29 12:41:31 primo-plus volumio[1078]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 29 12:41:31 primo-plus qobuz-connect[5204]: 20260329 12:41:31.963 [5204.5204] INFO VolumeManager: [0x19e1fc8]: Setting new playback volume: 75 Mar 29 12:41:31 primo-plus qobuz-connect[5204]: 20260329 12:41:31.963 [5204.5204] INFO VolumeManager: [0x19e1fc8]: Setting new mute state: 0 Mar 29 12:41:31 primo-plus qobuz-connect[5204]: 20260329 12:41:31.963 [5204.5204] INFO AudioStreamManager: [0x19e1d20]: Setting new audio download buffer size: 1048576 Mar 29 12:41:31 primo-plus qobuz-connect[5204]: 20260329 12:41:31.964 [5204.5204] INFO QobuzConnect: [0x19e2890]: Client initialized! Mar 29 12:41:31 primo-plus qobuz-connect[5204]: 20260329 12:41:31.964 [5204.5204] INFO SampleApp: Starting Avahi advertising, name: Primo Plus, service name: _qobuz-connect._tcp Mar 29 12:41:31 primo-plus volumio[1078]: info: Getting Tidal Cloud Configuration Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:31 primo-plus volumio[1078]: info: Getting Qobuz Cloud Configuration Mar 29 12:41:31 primo-plus volumio[1078]: info: Asking plugin for UI Config Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:31 primo-plus qobuz-connect[5204]: 20260329 12:41:31.992 [5204.5204] INFO LocalConfigManager: [0x19e1a48]: Starting Local Configuration server Mar 29 12:41:31 primo-plus qobuz-connect[5204]: 20260329 12:41:31.993 [5204.5204] INFO SampleApp: Starting Local configuration server Mar 29 12:41:31 primo-plus qobuz-connect[5204]: 20260329 12:41:31.993 [5204.5204] INFO SampleApp: Connected to UNIX socket client 0x19d7f60 Mar 29 12:41:31 primo-plus volumio[1078]: info: Getting Spotify Cloud Configuration Mar 29 12:41:31 primo-plus volumio[1078]: info: Asking plugin for UI Config Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:31 primo-plus volumio[1078]: info: Saving Spotify Acccount Mar 29 12:41:31 primo-plus volumio[1078]: info: Got it Mar 29 12:41:31 primo-plus volumio[1078]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 29 12:41:31 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 29 12:41:32 primo-plus volumio[1078]: No protocol specified Mar 29 12:41:32 primo-plus volumio[1078]: xcb_connection_has_error() returned true Mar 29 12:41:32 primo-plus qobuz-connect[5204]: 20260329 12:41:32.101 [5204.5204] INFO SampleApp: Playback volume changed: 75 Mar 29 12:41:32 primo-plus volumio[1078]: info: Starting Shairport Sync Mar 29 12:41:32 primo-plus sudo[5220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 29 12:41:32 primo-plus sudo[5220]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:32 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:32 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:32 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.201:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Mar 29 12:41:32 primo-plus volumio[1078]: info: Got Tidal Cloud Configuration Mar 29 12:41:32 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:32 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:32 primo-plus volumio[1078]: info: Got it Mar 29 12:41:32 primo-plus sudo[5220]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:32 primo-plus sudo[5223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 12:41:32 primo-plus volumio[1078]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 12:41:32 primo-plus sudo[5223]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:32 primo-plus sudo[5225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 29 12:41:32 primo-plus sudo[5225]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:32 primo-plus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 29 12:41:32 primo-plus systemd[1]: shairport-sync.service: Succeeded. Mar 29 12:41:32 primo-plus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 29 12:41:32 primo-plus volumio[1078]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 29 12:41:32 primo-plus volumio[1078]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 29 12:41:32 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:32 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:32 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:32 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:32 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:32 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:32 primo-plus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 29 12:41:32 primo-plus sudo[5223]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:32 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.200:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Mar 29 12:41:32 primo-plus systemd[1]: Stopping RAAT DAEMON... Mar 29 12:41:32 primo-plus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 29 12:41:32 primo-plus systemd[1]: raat-daemon.service: Succeeded. Mar 29 12:41:32 primo-plus systemd[1]: Stopped RAAT DAEMON. Mar 29 12:41:32 primo-plus volumio[1078]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 29 12:41:32 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:32 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:32 primo-plus volumio[1078]: info: Shairport-Sync Started Mar 29 12:41:32 primo-plus volumio[1078]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 29 12:41:32 primo-plus systemd[1]: Started RAAT DAEMON. Mar 29 12:41:32 primo-plus sudo[5225]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:32 primo-plus volumio[1078]: info: Raat Daemon started successfully Mar 29 12:41:32 primo-plus volumio[1078]: info: TidalConnect service stoped! Mar 29 12:41:32 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:32 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:32 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:32 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:32 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:32 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:33 primo-plus volumio[1078]: info: TidalConnect service stoped! Mar 29 12:41:33 primo-plus mpd[5189]: Mar 29 12:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 29 12:41:33 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.201:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Mar 29 12:41:33 primo-plus systemd[1]: Started Music Player Daemon. Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:33 primo-plus sudo[5157]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:33 primo-plus volumio[1078]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 29 12:41:33 primo-plus volumio[1078]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 29 12:41:33 primo-plus volumio[1078]: error: updateQueue error: null Mar 29 12:41:33 primo-plus sudo[5247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 29 12:41:33 primo-plus sudo[5247]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:33 primo-plus systemd[1]: Started Volumio Tidal Connect Service. Mar 29 12:41:33 primo-plus sudo[5247]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:33 primo-plus volumio[1078]: info: Executing endpoint tc_getconfig Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 29 12:41:33 primo-plus vtcs[5249]: STARTING TidalConnect services, version: 1.6.1 Mar 29 12:41:33 primo-plus vtcs[5249]: STARTED TidalConnect services. Mar 29 12:41:33 primo-plus volumio[1078]: info: Executing endpoint tc_connect Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 29 12:41:33 primo-plus volumio[1078]: info: Connecting to TidalConnect Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::servicePushState Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreStateMachine::pushState Mar 29 12:41:33 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::volumioPushState Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:33 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:33 primo-plus volumio[1078]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:41:33 primo-plus volumio[1078]: info: MRS: Pushing multiroomSync output Mar 29 12:41:33 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:33 primo-plus volumio[1078]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::servicePushState Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreStateMachine::pushState Mar 29 12:41:33 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::volumioPushState Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:33 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:33 primo-plus volumio[1078]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:41:33 primo-plus volumio[1078]: info: MRS: Pushing multiroomSync output Mar 29 12:41:33 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:33 primo-plus volumio[1078]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Mar 29 12:41:33 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:33.704+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.193:38902,192.168.104.193:43076,00:00:00:00:00:00%08 @ 0x1ef5e90" state=STATUS_STOPPED positionMs=258437 volume=73 Mar 29 12:41:33 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:33.705+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.193:43076 @ 0x2034cf0" state=STATUS_STOPPED positionMs=258437 volume=73 Mar 29 12:41:33 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:33.705+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.193:38902,192.168.104.193:43076,00:00:00:00:00:00%08 @ 0x1ef5e90" id=tidal://song/1965091 title=Vox Mar 29 12:41:33 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:33.705+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.193:43076 @ 0x2034cf0" id=tidal://song/1965091 title=Vox Mar 29 12:41:33 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:33.706+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.193:38902,192.168.104.193:43076,00:00:00:00:00:00%08 @ 0x1ef5e90" state=STATUS_STOPPED positionMs=258437 volume=73 Mar 29 12:41:33 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:33.715+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.193:43076 @ 0x2034cf0" state=STATUS_STOPPED positionMs=258437 volume=73 Mar 29 12:41:33 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:33.716+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.193:38902,192.168.104.193:43076,00:00:00:00:00:00%08 @ 0x1ef5e90" id=tidal://song/1965091 title=Vox Mar 29 12:41:33 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:33.717+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.193:43076 @ 0x2034cf0" id=tidal://song/1965091 title=Vox Mar 29 12:41:33 primo-plus volumio[1078]: info: RAAT: Requesting Headphone Status Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 29 12:41:33 primo-plus volumio[1078]: info: Updating RAAT Signal Path Mar 29 12:41:33 primo-plus volumio[1078]: info: Updating RAAT Signal Path Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreStateMachine::getcurrentVolume Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::volumioRetrievevolume Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreStateMachine::pushState Mar 29 12:41:33 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::volumioPushState Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:33 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:33 primo-plus volumio[1078]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:41:33 primo-plus volumio[1078]: info: MRS: Pushing multiroomSync output Mar 29 12:41:33 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:33.830+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.193:38902,192.168.104.193:43076,00:00:00:00:00:00%08 @ 0x1ef5e90" state=STATUS_STOPPED positionMs=258735 volume=73 Mar 29 12:41:33 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:33.830+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.193:43076 @ 0x2034cf0" state=STATUS_STOPPED positionMs=258735 volume=73 Mar 29 12:41:33 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:33.831+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.193:38902,192.168.104.193:43076,00:00:00:00:00:00%08 @ 0x1ef5e90" id=tidal://song/1965091 title=Vox Mar 29 12:41:33 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:33.831+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.193:43076 @ 0x2034cf0" id=tidal://song/1965091 title=Vox Mar 29 12:41:33 primo-plus volumio[1078]: info: Updating RAAT Signal Path Mar 29 12:41:33 primo-plus volumio[1078]: info: MCU Signalled Headphone Mode Disabled Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 29 12:41:33 primo-plus volumio[1078]: info: Reporting Headphone State: false Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:33 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:33 primo-plus volumio[1078]: info: Updating RAAT Signal Path Mar 29 12:41:34 primo-plus volumio[1078]: info: Executing endpoint restartRAATSocket Mar 29 12:41:34 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 29 12:41:34 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:34 primo-plus volumio[1078]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 29 12:41:34 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:34 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:34 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:34 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:34 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:34 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:34 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:34 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.200:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Mar 29 12:41:34 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:34 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:34 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:34 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:34 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:34 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:34 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:34 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:34 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.201:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Mar 29 12:41:34 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:34 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:34 primo-plus volumio[1078]: info: TidalConnect service stoped! Mar 29 12:41:34 primo-plus volumio[1078]: info: TidalConnect service stoped! Mar 29 12:41:34 primo-plus volumio[1078]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 29 12:41:34 primo-plus volumio[1078]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 29 12:41:34 primo-plus sudo[5284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 29 12:41:34 primo-plus sudo[5284]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 12:41:34 primo-plus sudo[5284]: pam_unix(sudo:session): session closed for user root Mar 29 12:41:34 primo-plus volumio[1078]: [VRCP] RAAT stopped playing Mar 29 12:41:34 primo-plus volumio[1078]: info: CoreCommandRouter::servicePushState Mar 29 12:41:34 primo-plus volumio[1078]: info: CoreStateMachine::pushState Mar 29 12:41:34 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:34 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 12:41:34 primo-plus volumio[1078]: info: CoreCommandRouter::volumioPushState Mar 29 12:41:34 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:34 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:34 primo-plus volumio[1078]: info: MRS: Pushing multiroomSync output update for this device Mar 29 12:41:34 primo-plus volumio[1078]: info: MRS: Pushing multiroomSync output Mar 29 12:41:34 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:34 primo-plus volumio[1078]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received raat Mar 29 12:41:34 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:34.684+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.193:38902,192.168.104.193:43076,00:00:00:00:00:00%08 @ 0x1ef5e90" state=STATUS_STOPPED positionMs=259500 volume=73 Mar 29 12:41:34 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:34.685+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.104.193:43076 @ 0x2034cf0" state=STATUS_STOPPED positionMs=259500 volume=73 Mar 29 12:41:34 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:34.685+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.193:38902,192.168.104.193:43076,00:00:00:00:00:00%08 @ 0x1ef5e90" id=tidal://song/1965091 title=Vox Mar 29 12:41:34 primo-plus volumio5-onboarding[1584]: time=2026-03-29T12:41:34.686+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.104.193:43076 @ 0x2034cf0" id=tidal://song/1965091 title=Vox Mar 29 12:41:34 primo-plus volumio[1078]: info: Updating RAAT Signal Path Mar 29 12:41:35 primo-plus volumio[1078]: info: RAAT: Requesting Headphone Status Mar 29 12:41:35 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 29 12:41:35 primo-plus volumio[1078]: info: MCU Signalled Headphone Mode Disabled Mar 29 12:41:35 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 29 12:41:35 primo-plus volumio[1078]: info: Reporting Headphone State: false Mar 29 12:41:35 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:35 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 12:41:35 primo-plus volumio[1078]: info: Updating RAAT Signal Path Mar 29 12:41:35 primo-plus volumio[1078]: info: CoreCommandRouter::servicePushState Mar 29 12:41:35 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:35 primo-plus volumio[1078]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received raat Mar 29 12:41:35 primo-plus volumio[1078]: info: CoreCommandRouter::servicePushState Mar 29 12:41:35 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:35 primo-plus volumio[1078]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received raat Mar 29 12:41:35 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:35 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:35 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:35 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:35 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:35 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:36 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.200:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Mar 29 12:41:36 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:36 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:36 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:36 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:36 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:36 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:36 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:36 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:36 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.201:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Mar 29 12:41:36 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:36 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:36 primo-plus volumio[1078]: info: TidalConnect service started! Mar 29 12:41:36 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:36 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:36 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:36 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:36 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:36 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:36 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.200:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Mar 29 12:41:36 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:36 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:37 primo-plus volumio[1078]: info: TidalConnect service started! Mar 29 12:41:38 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:38 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:38 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:38 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:38 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:38 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:38 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.201:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Mar 29 12:41:38 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:38 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:39 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:39 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:39 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:39 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:39 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:39 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:39 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.200:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Mar 29 12:41:39 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:39 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:40 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Mar 29 12:41:40 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 12:41:40 primo-plus volumio-remote-updater[654]: No test mode Mar 29 12:41:40 primo-plus volumio-remote-updater[654]: No alpha test mode Mar 29 12:41:40 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 29 12:41:40 primo-plus volumio[1078]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 29 12:41:40 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 29 12:41:42 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Mar 29 12:41:42 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 12:41:42 primo-plus volumio-remote-updater[654]: No test mode Mar 29 12:41:42 primo-plus volumio-remote-updater[654]: No alpha test mode Mar 29 12:41:42 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 29 12:41:43 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Mar 29 12:41:43 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 12:41:43 primo-plus volumio[1078]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 29 12:41:43 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 29 12:41:43 primo-plus systemd[1]: systemd-timedated.service: Succeeded. Mar 29 12:41:43 primo-plus volumio-remote-updater[654]: No test mode Mar 29 12:41:43 primo-plus volumio-remote-updater[654]: No alpha test mode Mar 29 12:41:43 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 29 12:41:43 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:41:43 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:41:43 primo-plus volumio[1078]: info: Discovery: Getting this device information Mar 29 12:41:43 primo-plus volumio[1078]: info: CoreCommandRouter::volumioGetState Mar 29 12:41:43 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:41:43 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:41:43 primo-plus volumio[1078]: verbose: New Socket.io Connection to 192.168.104.201:3000 from 192.168.104.193 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Mar 29 12:41:43 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 12:41:43 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 12:41:43 primo-plus volumio[1078]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 29 12:41:43 primo-plus volumio[1078]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 29 12:42:02 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 0 Mar 29 12:42:02 primo-plus volumio[1078]: info: CorePlayQueue::getTrack 1 Mar 29 12:42:02 primo-plus volumio[1078]: info: Prefetching next song Mar 29 12:42:02 primo-plus volumio[1078]: info: [1774755722040] ControllerTidal::prefetch Mar 29 12:42:02 primo-plus volumio[1078]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 12:42:02 primo-plus volumio[1078]: TypeError: Cannot read property 'highestSoundQuality' of undefined Mar 29 12:42:02 primo-plus volumio[1078]: at ControllerTidal.getProperSoundQuality (/volumio/app/plugins/music_service/tidal/tidal_real:1:19583) Mar 29 12:42:02 primo-plus volumio[1078]: at ControllerTidal.prefetch (/volumio/app/plugins/music_service/tidal/tidal_real:1:20287) Mar 29 12:42:02 primo-plus volumio[1078]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:464:18) Mar 29 12:42:02 primo-plus volumio[1078]: at listOnTimeout (internal/timers.js:554:17) Mar 29 12:42:02 primo-plus volumio[1078]: at processTimers (internal/timers.js:497:7) Mar 29 12:42:02 primo-plus volumio[1078]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 12:42:02 primo-plus sudo[5344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-29 12:41 Mar 29 12:42:02 primo-plus sudo[5344]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="primoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 01:08:28 PM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Primo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo Plus" VOLUMIO_HASH="2aa40c31709b83c0e790f52b483672b5"