-- Logs begin at Fri 2026-02-27 19:38:48 JST, end at Wed 2026-06-03 09:59:34 JST. -- Jun 03 09:58:44 rivo volumio[3099]: info: CALLMETHOD: system_controller network saveDnsSettings [object Object] Jun 03 09:58:44 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: network , saveDnsSettings Jun 03 09:58:44 rivo sudo[6891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/resolv.conf.tail Jun 03 09:58:44 rivo sudo[6891]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:58:44 rivo sudo[6891]: pam_unix(sudo:session): session closed for user root Jun 03 09:58:44 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 03 09:58:44 rivo sudo[6894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jun 03 09:58:44 rivo sudo[6894]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:58:44 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 03 09:58:44 rivo sudo[6897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 Jun 03 09:58:44 rivo sudo[6897]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:58:44 rivo systemd[1]: Starting Wireless Services... Jun 03 09:58:44 rivo dhcpcd[3241]: eth0: pid 6899 deleted IP address 192.168.11.13/24 Jun 03 09:58:44 rivo dhcpcd[3241]: eth0: deleting route to 192.168.11.0/24 Jun 03 09:58:44 rivo avahi-daemon[2829]: Withdrawing address record for 192.168.11.13 on eth0. Jun 03 09:58:44 rivo avahi-daemon[2829]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.11.13. Jun 03 09:58:44 rivo dhcpcd[3307]: eth0: pid 6899 deleted IP address 192.168.11.13/24 Jun 03 09:58:44 rivo dhcpcd[3307]: eth0: deleting route to 192.168.11.0/24 Jun 03 09:58:44 rivo dhcpcd[3241]: eth0: deleting default route via 192.168.11.1 Jun 03 09:58:44 rivo avahi-daemon[2829]: Interface eth0.IPv4 no longer relevant for mDNS. Jun 03 09:58:44 rivo dhcpcd[3307]: eth0: deleting default route via 192.168.11.1 Jun 03 09:58:44 rivo sudo[6897]: pam_unix(sudo:session): session closed for user root Jun 03 09:58:44 rivo volumio[3099]: info: Discovery: A device disappeared from network Jun 03 09:58:44 rivo volumio[3099]: info: Discovery: Device nas disappeared from network Jun 03 09:58:44 rivo volumio[3099]: info: Discovery: Disconnected from remote: 192.168.11.10 Jun 03 09:58:44 rivo dhcpcd[3241]: eth0: soliciting a DHCP lease Jun 03 09:58:44 rivo sudo[6910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down Jun 03 09:58:44 rivo sudo[6910]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:58:44 rivo volumio[3099]: info: Discovery: A device disappeared from network Jun 03 09:58:44 rivo dhcpcd[3307]: eth0: soliciting a DHCP lease Jun 03 09:58:44 rivo sudo[6910]: pam_unix(sudo:session): session closed for user root Jun 03 09:58:44 rivo sudo[6935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up Jun 03 09:58:44 rivo sudo[6935]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:58:44 rivo sudo[6935]: pam_unix(sudo:session): session closed for user root Jun 03 09:58:44 rivo dhcpcd[3241]: eth0: offered 192.168.11.13 from 192.168.11.1 Jun 03 09:58:44 rivo dhcpcd[3241]: eth0: probing address 192.168.11.13/24 Jun 03 09:58:45 rivo volumio[3099]: info: Received Get System Info Jun 03 09:58:45 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 09:58:45 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 09:58:45 rivo volumio[3099]: info: Discovery: Getting this device information Jun 03 09:58:45 rivo volumio[3099]: info: CoreCommandRouter::volumioGetState Jun 03 09:58:45 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 09:58:45 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 03 09:58:45 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 03 09:58:45 rivo volumio5-onboarding[4008]: time=2026-06-03T09:58:45.137+09:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 03 09:58:45 rivo wireless.js[6898]: Cleaning previous... Jun 03 09:58:45 rivo sudo[6946]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 03 09:58:45 rivo sudo[6946]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:58:45 rivo sudo[6946]: pam_unix(sudo:session): session closed for user root Jun 03 09:58:45 rivo sudo[6949]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 03 09:58:45 rivo sudo[6949]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:58:45 rivo sudo[6949]: pam_unix(sudo:session): session closed for user root Jun 03 09:58:45 rivo wireless.js[6898]: Stopped aP Jun 03 09:58:45 rivo wireless.js[6898]: Wireless Networking DISABLED, not starting wireless flow Jun 03 09:58:45 rivo kernel: dhd_ioctl_entry: Interface is down Jun 03 09:58:45 rivo iwconfig[6957]: Error for wireless request "Set Power Management" (8B2C) : Jun 03 09:58:45 rivo iwconfig[6957]: SET failed on device wlan0 ; Invalid argument. Jun 03 09:58:45 rivo systemd[1]: wireless.service: Succeeded. Jun 03 09:58:45 rivo systemd[1]: Started Wireless Services. Jun 03 09:58:45 rivo sudo[6894]: pam_unix(sudo:session): session closed for user root Jun 03 09:58:45 rivo ntpd[3306]: Deleting interface #3 eth0, 192.168.11.13#123, interface stats: received=814, sent=816, dropped=0, active_time=53092 secs Jun 03 09:58:45 rivo ntpd[3306]: 85.121.50.82 local addr 192.168.11.13 -> Jun 03 09:58:45 rivo ntpd[3306]: 167.179.119.205 local addr 192.168.11.13 -> Jun 03 09:58:45 rivo ntpd[3306]: 64.176.59.83 local addr 192.168.11.13 -> Jun 03 09:58:45 rivo ntpd[3306]: 45.77.20.103 local addr 192.168.11.13 -> Jun 03 09:58:45 rivo ntpd[3306]: 172.105.192.74 local addr 192.168.11.13 -> Jun 03 09:58:45 rivo ntpd[3306]: 172.104.124.149 local addr 192.168.11.13 -> Jun 03 09:58:46 rivo nmbd[3050]: [2026/06/03 09:58:46.318022, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jun 03 09:58:46 rivo nmbd[3050]: Packet send failed to 192.168.11.255(138) ERRNO=Network is unreachable Jun 03 09:58:47 rivo volumio[3099]: info: Reporting MCU Network Status: 0 Jun 03 09:58:47 rivo volumio[3099]: info: Volumio Network Manager: Network status updated: 0 Jun 03 09:58:49 rivo dhcpcd[3307]: eth0: offered 192.168.11.13 from 192.168.11.1 Jun 03 09:58:49 rivo dhcpcd[3307]: eth0: probing address 192.168.11.13/24 Jun 03 09:58:50 rivo dhcpcd[3241]: eth0: leased 192.168.11.13 for 172800 seconds Jun 03 09:58:50 rivo dhcpcd[3241]: eth0: adding route to 192.168.11.0/24 Jun 03 09:58:50 rivo dhcpcd[3241]: eth0: adding default route via 192.168.11.1 Jun 03 09:58:50 rivo avahi-daemon[2829]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.11.13. Jun 03 09:58:50 rivo avahi-daemon[2829]: New relevant interface eth0.IPv4 for mDNS. Jun 03 09:58:50 rivo avahi-daemon[2829]: Registering new address record for 192.168.11.13 on eth0.IPv4. Jun 03 09:58:50 rivo volumio[3099]: info: Discovery: adding 8fd51799-8c72-4e8c-b38a-abc40c53a4cc Jun 03 09:58:50 rivo volumio[3099]: info: Discovery: Found device nas Jun 03 09:58:50 rivo ntpd[3306]: ntpd exiting on signal 15 (Terminated) Jun 03 09:58:50 rivo systemd[1]: Stopping Network Time Service... Jun 03 09:58:50 rivo volumio[3099]: info: Discovery: Connecting to remote: 192.168.11.10 Jun 03 09:58:50 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jun 03 09:58:50 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jun 03 09:58:50 rivo systemd[1]: ntp.service: Succeeded. Jun 03 09:58:50 rivo systemd[1]: Stopped Network Time Service. Jun 03 09:58:50 rivo systemd[1]: Starting Network Time Service... Jun 03 09:58:50 rivo sudo[7010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 03 09:58:50 rivo sudo[7010]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:58:50 rivo sudo[7010]: pam_unix(sudo:session): session closed for user root Jun 03 09:58:50 rivo ntpd[7012]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 03 09:58:50 rivo ntpd[7012]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 03 09:58:50 rivo systemd[1]: Started Network Time Service. Jun 03 09:58:50 rivo ntpd[7031]: proto: precision = 1.250 usec (-20) Jun 03 09:58:50 rivo ntpd[7031]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 03 09:58:50 rivo ntpd[7031]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 03 09:58:50 rivo ntpd[7031]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1254 days ago Jun 03 09:58:50 rivo ntpd[7031]: Listen and drop on 0 v6wildcard [::]:123 Jun 03 09:58:50 rivo ntpd[7031]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 03 09:58:50 rivo ntpd[7031]: Listen normally on 2 lo 127.0.0.1:123 Jun 03 09:58:50 rivo ntpd[7031]: Listen normally on 3 eth0 192.168.11.13:123 Jun 03 09:58:50 rivo ntpd[7031]: Listening on routing socket on fd #20 for interface updates Jun 03 09:58:50 rivo ntpd[7031]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jun 03 09:58:50 rivo ntpd[7031]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jun 03 09:58:50 rivo volumio[3099]: info: Discovery: Connected to remote: 192.168.11.10 Jun 03 09:58:50 rivo volumio[3099]: info: Received Get System Info Jun 03 09:58:50 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 03 09:58:50 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 03 09:58:50 rivo volumio[3099]: info: Discovery: Getting this device information Jun 03 09:58:50 rivo volumio[3099]: info: CoreCommandRouter::volumioGetState Jun 03 09:58:50 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 03 09:58:50 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 03 09:58:50 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 03 09:58:51 rivo volumio[3099]: info: Discovery: adding 271a226a-158f-4c49-a4b4-e7b114aedec6 Jun 03 09:58:51 rivo volumio[3099]: info: Discovery: Found device Rivo Jun 03 09:58:51 rivo volumio[3099]: info: CoreCommandRouter::volumioGetState Jun 03 09:58:51 rivo volumio5-onboarding[4008]: time=2026-06-03T09:58:51.825+09:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 03 09:58:53 rivo volumio[3099]: info: CoreCommandRouter::volumioGetState Jun 03 09:58:54 rivo volumio[3099]: info: Discovery: Networking Restart detected, restarting advertisement and browsing Jun 03 09:58:54 rivo volumio[3099]: info: Discovery: Restarting Advertising Jun 03 09:58:54 rivo volumio[3099]: info: Discovery: Stopping existing advertisement Jun 03 09:58:54 rivo volumio[3099]: info: Discovery: Restarting Browsing Jun 03 09:58:54 rivo volumio[3099]: info: Discovery: Networking Restart detected, restarting advertisement and browsing Jun 03 09:58:54 rivo volumio[3099]: info: Discovery: Restarting Advertising Jun 03 09:58:54 rivo volumio[3099]: info: Discovery: Restart already pending, ignoring duplicate call Jun 03 09:58:54 rivo volumio[3099]: info: Discovery: Restarting Browsing Jun 03 09:58:54 rivo volumio[3099]: info: Discovery: Restart already pending, ignoring duplicate call Jun 03 09:58:54 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 03 09:58:54 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 03 09:58:54 rivo volumio[3099]: info: Refreshing Cached IP Addresses Jun 03 09:58:54 rivo sudo[7035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 03 09:58:54 rivo sudo[7035]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:58:54 rivo sudo[7035]: pam_unix(sudo:session): session closed for user root Jun 03 09:58:54 rivo sudo[7039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 03 09:58:54 rivo sudo[7039]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:58:54 rivo sudo[7039]: pam_unix(sudo:session): session closed for user root Jun 03 09:58:55 rivo dhcpcd[3307]: eth0: leased 192.168.11.13 for 172800 seconds Jun 03 09:58:55 rivo dhcpcd[3307]: eth0: adding route to 192.168.11.0/24 Jun 03 09:58:55 rivo dhcpcd[3307]: eth0: adding default route via 192.168.11.1 Jun 03 09:58:55 rivo volumio[3099]: info: Executing endpoint metavolumio Jun 03 09:58:55 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 03 09:58:55 rivo volumio[3099]: info: Executing endpoint metavolumio Jun 03 09:58:55 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 03 09:58:55 rivo volumio[3099]: info: Executing endpoint metavolumio Jun 03 09:58:55 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 03 09:58:55 rivo ntpd[7031]: ntpd exiting on signal 15 (Terminated) Jun 03 09:58:55 rivo systemd[1]: Stopping Network Time Service... Jun 03 09:58:55 rivo systemd[1]: ntp.service: Succeeded. Jun 03 09:58:55 rivo volumio[3099]: info: Discovery: A device disappeared from network Jun 03 09:58:55 rivo systemd[1]: Stopped Network Time Service. Jun 03 09:58:55 rivo systemd[1]: Starting Network Time Service... Jun 03 09:58:55 rivo ntpd[7072]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 03 09:58:55 rivo ntpd[7072]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 03 09:58:55 rivo systemd[1]: Started Network Time Service. Jun 03 09:58:55 rivo ntpd[7088]: proto: precision = 1.250 usec (-20) Jun 03 09:58:55 rivo ntpd[7088]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 03 09:58:55 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 03 09:58:55 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 03 09:58:55 rivo volumio[3099]: info: Refreshing Cached IP Addresses Jun 03 09:58:55 rivo ntpd[7088]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 03 09:58:55 rivo ntpd[7088]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1254 days ago Jun 03 09:58:55 rivo ntpd[7088]: Listen and drop on 0 v6wildcard [::]:123 Jun 03 09:58:55 rivo ntpd[7088]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 03 09:58:55 rivo ntpd[7088]: Listen normally on 2 lo 127.0.0.1:123 Jun 03 09:58:55 rivo ntpd[7088]: Listen normally on 3 eth0 192.168.11.13:123 Jun 03 09:58:55 rivo ntpd[7088]: Listening on routing socket on fd #20 for interface updates Jun 03 09:58:55 rivo ntpd[7088]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jun 03 09:58:55 rivo ntpd[7088]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jun 03 09:58:55 rivo sudo[7092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 03 09:58:55 rivo volumio[3099]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 03 09:58:55 rivo sudo[7092]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:58:55 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 03 09:58:55 rivo sudo[7093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 03 09:58:55 rivo sudo[7093]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:58:55 rivo sudo[7092]: pam_unix(sudo:session): session closed for user root Jun 03 09:58:55 rivo sudo[7093]: pam_unix(sudo:session): session closed for user root Jun 03 09:58:55 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 03 09:58:55 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 03 09:58:55 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Jun 03 09:58:55 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Jun 03 09:58:55 rivo volumio[3099]: info: Received Get System Version Jun 03 09:58:55 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 03 09:58:57 rivo volumio[3099]: info: Reporting MCU Network Status: 1 Jun 03 09:58:57 rivo volumio[3099]: info: Volumio Network Manager: Network status updated: 1 Jun 03 09:58:59 rivo volumio[3099]: info: Discovery: Started advertising with name: Rivo Jun 03 09:58:59 rivo volumio[3099]: info: Discovery: adding 8fd51799-8c72-4e8c-b38a-abc40c53a4cc Jun 03 09:58:59 rivo volumio[3099]: info: Discovery: Found device nas Jun 03 09:59:00 rivo volumio[3099]: info: Discovery: adding 271a226a-158f-4c49-a4b4-e7b114aedec6 Jun 03 09:59:00 rivo volumio[3099]: info: Discovery: Found device Rivo Jun 03 09:59:00 rivo volumio[3099]: info: CoreCommandRouter::volumioGetState Jun 03 09:59:00 rivo volumio[3099]: info: Discovery: this is already registered, 271a226a-158f-4c49-a4b4-e7b114aedec6 Jun 03 09:59:00 rivo volumio[3099]: info: Discovery: Found device Rivo Jun 03 09:59:00 rivo volumio[3099]: info: CoreCommandRouter::volumioGetState Jun 03 09:59:01 rivo ntpd[7088]: Soliciting pool server 138.3.209.153 Jun 03 09:59:01 rivo ntpd[7088]: Soliciting pool server 208.88.66.8 Jun 03 09:59:02 rivo ntpd[7088]: Soliciting pool server 129.250.35.250 Jun 03 09:59:02 rivo ntpd[7088]: Soliciting pool server 129.250.35.251 Jun 03 09:59:02 rivo ntpd[7088]: Soliciting pool server 160.16.113.133 Jun 03 09:59:02 rivo ntpd[7088]: Soliciting pool server 158.101.134.179 Jun 03 09:59:02 rivo ntpd[7088]: Soliciting pool server 142.91.105.55 Jun 03 09:59:02 rivo ntpd[7088]: Soliciting pool server 142.91.108.61 Jun 03 09:59:03 rivo ntpd[7088]: Soliciting pool server 167.179.119.205 Jun 03 09:59:03 rivo ntpd[7088]: Soliciting pool server 85.121.122.62 Jun 03 09:59:03 rivo ntpd[7088]: Soliciting pool server 162.159.200.123 Jun 03 09:59:03 rivo ntpd[7088]: Soliciting pool server 162.159.200.1 Jun 03 09:59:04 rivo ntpd[7088]: Soliciting pool server 172.105.192.74 Jun 03 09:59:04 rivo ntpd[7088]: Soliciting pool server 122.215.240.51 Jun 03 09:59:04 rivo ntpd[7088]: Soliciting pool server 172.233.91.137 Jun 03 09:59:04 rivo ntpd[7088]: Soliciting pool server 109.61.109.140 Jun 03 09:59:05 rivo volumio[3099]: info: CorePlayQueue::getTrack 1 Jun 03 09:59:05 rivo volumio[3099]: info: CorePlayQueue::getTrack 2 Jun 03 09:59:05 rivo volumio[3099]: info: Prefetching next song Jun 03 09:59:05 rivo volumio[3099]: info: [1780448345065] ControllerQobuz::prefetch Jun 03 09:59:05 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/281773077" Jun 03 09:59:05 rivo volumio[3099]: STREAMING PROXY: Handling url /?data=qobuz://song/281773077 Jun 03 09:59:05 rivo volumio[3099]: info: Executing endpoint getStreamUrlqobuz Jun 03 09:59:05 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 03 09:59:05 rivo ntpd[7088]: Soliciting pool server 2606:4700:f1::123 Jun 03 09:59:05 rivo ntpd[7088]: Soliciting pool server 117.102.178.88 Jun 03 09:59:06 rivo volumio[3099]: info: getStreamUrl took 1048 milliseconds Jun 03 09:59:06 rivo volumio[3099]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3338662&eid=281773077&fmt=7&profile=raw&app_id=539451548&cid=3137428&etsp=1780451945&hmac=Acnlts-Rs0hiKU440OIspnVAefc Jun 03 09:59:06 rivo volumio[3099]: STREAMING PROXY: Requesting upstream with range: none Jun 03 09:59:06 rivo volumio[3099]: STREAMING PROXY: Upstream status code: 200 Jun 03 09:59:06 rivo volumio[3099]: STREAMING PROXY: Client closed connection Jun 03 09:59:06 rivo volumio[3099]: STREAMING PROXY: Upstream closed. Received 15544 bytes Jun 03 09:59:06 rivo volumio[3099]: STREAMING PROXY: Incomplete transfer. Expected 115878745, got 15544 Jun 03 09:59:06 rivo volumio[3099]: STREAMING PROXY: Retrying with range: bytes=15544- (attempt 1) Jun 03 09:59:06 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/281773077" Jun 03 09:59:06 rivo volumio[3099]: info: Jun 03 09:59:06 rivo volumio[3099]: ---------------------------- MPD announces system playlist update Jun 03 09:59:06 rivo volumio[3099]: info: Ignoring MPD Status Update Jun 03 09:59:06 rivo volumio[3099]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/281773077" took 3 milliseconds Jun 03 09:59:06 rivo volumio[3099]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 03 09:59:06 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 03 09:59:06 rivo volumio[3099]: info: Jun 03 09:59:06 rivo volumio[3099]: ---------------------------- MPD announces state update: options Jun 03 09:59:06 rivo volumio[3099]: info: ------------------------------ 4ms Jun 03 09:59:06 rivo volumio[3099]: info: sendMpdCommand consume 1 took 2 milliseconds Jun 03 09:59:06 rivo volumio[3099]: info: ControllerMpd::getState Jun 03 09:59:06 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Jun 03 09:59:06 rivo volumio[3099]: info: sendMpdCommand status took 2 milliseconds Jun 03 09:59:06 rivo volumio[3099]: verbose: ControllerMpd::parseState Jun 03 09:59:06 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 09:59:06 rivo volumio[3099]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 03 09:59:06 rivo volumio[3099]: verbose: ControllerMpd::parseTrackInfo Jun 03 09:59:06 rivo volumio[3099]: info: ControllerMpd::pushState Jun 03 09:59:06 rivo volumio[3099]: info: CoreCommandRouter::servicePushState Jun 03 09:59:06 rivo volumio[3099]: info: CorePlayQueue::getTrack 1 Jun 03 09:59:06 rivo volumio[3099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":293117,"duration":297,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3 Kbps","isStreaming":false,"title":"281773076","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/281773076","trackType":"qobuz"} Jun 03 09:59:06 rivo volumio[3099]: verbose: CURRENT POSITION 1 Jun 03 09:59:06 rivo volumio[3099]: info: CoreStateMachine::syncState stateService play Jun 03 09:59:06 rivo volumio[3099]: info: CoreStateMachine::syncState currentStatus play Jun 03 09:59:06 rivo volumio[3099]: info: Received an update from plugin. extracting info from payload Jun 03 09:59:06 rivo volumio[3099]: info: CoreStateMachine::pushState Jun 03 09:59:06 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 09:59:06 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Jun 03 09:59:06 rivo volumio[3099]: info: CoreCommandRouter::volumioGetState Jun 03 09:59:06 rivo volumio[3099]: info: CoreStateMachine::pushState Jun 03 09:59:06 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Jun 03 09:59:06 rivo volumio[3099]: info: CoreCommandRouter::volumioGetState Jun 03 09:59:06 rivo volumio[3099]: info: ------------------------------ 33ms Jun 03 09:59:06 rivo ntpd[7088]: Soliciting pool server 172.104.124.149 Jun 03 09:59:07 rivo volumio[3099]: STREAMING PROXY: Resuming with range: bytes=15544- Jun 03 09:59:07 rivo volumio[3099]: STREAMING PROXY: Resume upstream status code: 206 Jun 03 09:59:07 rivo volumio[3099]: STREAMING PROXY: Handling url /?data=qobuz://song/281773077 Jun 03 09:59:07 rivo volumio[3099]: info: Executing endpoint getStreamUrlqobuz Jun 03 09:59:07 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Jun 03 09:59:09 rivo volumio[3099]: info: getStreamUrl took 1461 milliseconds Jun 03 09:59:09 rivo volumio[3099]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3338662&eid=281773077&fmt=7&profile=raw&app_id=539451548&cid=3137428&etsp=1780451949&hmac=8GHpO_nSc3MpRsIxS3Dfgklt_9E Jun 03 09:59:09 rivo volumio[3099]: STREAMING PROXY: Requesting upstream with range: none Jun 03 09:59:09 rivo volumio[3099]: STREAMING PROXY: Resume ended. Received 115863201 bytes Jun 03 09:59:09 rivo volumio[3099]: STREAMING PROXY: Upstream status code: 200 Jun 03 09:59:09 rivo volumio[3099]: info: CoreStateMachine::startPlaybackTimer Jun 03 09:59:09 rivo volumio[3099]: info: CorePlayQueue::getTrack 2 Jun 03 09:59:10 rivo volumio[3099]: info: Jun 03 09:59:10 rivo volumio[3099]: ---------------------------- MPD announces system playlist update Jun 03 09:59:10 rivo volumio[3099]: info: Ignoring MPD Status Update Jun 03 09:59:10 rivo volumio[3099]: info: Jun 03 09:59:10 rivo volumio[3099]: ---------------------------- MPD announces state update: player Jun 03 09:59:10 rivo volumio[3099]: info: ControllerMpd::getState Jun 03 09:59:10 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand status Jun 03 09:59:10 rivo volumio[3099]: info: ------------------------------ 8ms Jun 03 09:59:10 rivo volumio[3099]: info: sendMpdCommand status took 4 milliseconds Jun 03 09:59:10 rivo volumio[3099]: verbose: ControllerMpd::parseState Jun 03 09:59:10 rivo volumio[3099]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 09:59:10 rivo volumio[3099]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 03 09:59:10 rivo volumio[3099]: verbose: ControllerMpd::parseTrackInfo Jun 03 09:59:10 rivo volumio[3099]: info: ControllerMpd::pushState Jun 03 09:59:10 rivo volumio[3099]: info: CoreCommandRouter::servicePushState Jun 03 09:59:10 rivo volumio[3099]: info: CorePlayQueue::getTrack 2 Jun 03 09:59:10 rivo volumio[3099]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5,"duration":291,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2943 Kbps","isStreaming":false,"title":"281773077","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/281773077","trackType":"qobuz"} Jun 03 09:59:10 rivo volumio[3099]: verbose: CURRENT POSITION 2 Jun 03 09:59:10 rivo volumio[3099]: info: CoreStateMachine::syncState stateService play Jun 03 09:59:10 rivo volumio[3099]: info: CoreStateMachine::syncState currentStatus play Jun 03 09:59:10 rivo volumio[3099]: info: Received an update from plugin. extracting info from payload Jun 03 09:59:10 rivo volumio[3099]: info: CoreStateMachine::pushState Jun 03 09:59:10 rivo volumio[3099]: info: CorePlayQueue::getTrack 2 Jun 03 09:59:10 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 09:59:10 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Jun 03 09:59:10 rivo volumio[3099]: info: CoreCommandRouter::volumioGetState Jun 03 09:59:10 rivo volumio[3099]: info: CorePlayQueue::getTrack 2 Jun 03 09:59:10 rivo volumio[3099]: info: CoreStateMachine::pushState Jun 03 09:59:10 rivo volumio[3099]: info: CorePlayQueue::getTrack 2 Jun 03 09:59:10 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Jun 03 09:59:10 rivo volumio[3099]: info: CoreCommandRouter::volumioGetState Jun 03 09:59:10 rivo volumio[3099]: info: CorePlayQueue::getTrack 2 Jun 03 09:59:10 rivo volumio[3099]: info: ------------------------------ 63ms Jun 03 09:59:10 rivo volumio[3099]: info: CoreStateMachine::pushState Jun 03 09:59:10 rivo volumio[3099]: info: CorePlayQueue::getTrack 2 Jun 03 09:59:10 rivo volumio[3099]: info: CoreCommandRouter::volumioPushState Jun 03 09:59:10 rivo volumio[3099]: info: CoreCommandRouter::volumioGetState Jun 03 09:59:10 rivo volumio[3099]: info: CorePlayQueue::getTrack 2 Jun 03 09:59:13 rivo volumio[3099]: STREAMING PROXY: Upstream closed. Received 115878745 bytes Jun 03 09:59:13 rivo volumio[3099]: STREAMING PROXY: Client closed connection Jun 03 09:59:25 rivo volumio[3099]: info: CoreCommandRouter::volumioGetState Jun 03 09:59:25 rivo volumio[3099]: info: CorePlayQueue::getTrack 2 Jun 03 09:59:27 rivo volumio[3099]: info: Executing endpoint metavolumio Jun 03 09:59:27 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 03 09:59:27 rivo volumio[3099]: info: Executing endpoint metavolumio Jun 03 09:59:27 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 03 09:59:27 rivo volumio[3099]: info: Executing endpoint metavolumio Jun 03 09:59:27 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 03 09:59:27 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 03 09:59:27 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 03 09:59:31 rivo volumio[3099]: info: Enabling plugin fusiondsp Jun 03 09:59:31 rivo volumio[3099]: info: Loading plugin "fusiondsp"... Jun 03 09:59:31 rivo volumio[3099]: info: Applying required configuration parameters for plugin fusiondsp Jun 03 09:59:31 rivo volumio[3099]: info: Preparing to generate the ALSA configuration file Jun 03 09:59:31 rivo volumio[3099]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 03 09:59:31 rivo volumio[3099]: info: Reading ALSA contributions from plugins. Jun 03 09:59:31 rivo volumio[3099]: info: Asound.conf file written Jun 03 09:59:31 rivo sudo[7179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 03 09:59:31 rivo sudo[7179]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:59:31 rivo sudo[7179]: pam_unix(sudo:session): session closed for user root Jun 03 09:59:32 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Jun 03 09:59:32 rivo kernel: Err: can not set rate to 4202967296! Jun 03 09:59:32 rivo kernel: Range[3920000 - 500000000] Jun 03 09:59:32 rivo kernel: out of value, fixed it Jun 03 09:59:32 rivo kernel: id=0 set inskew=0 Jun 03 09:59:32 rivo volumio[3099]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Jun 03 09:59:32 rivo volumio[3099]: Hardware is initialized using a generic method Jun 03 09:59:32 rivo volumio[3099]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Jun 03 09:59:32 rivo volumio[3099]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Jun 03 09:59:32 rivo volumio[3099]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Jun 03 09:59:32 rivo volumio[3099]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Jun 03 09:59:32 rivo volumio[3099]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Qutest Validity:0' : Operation not permitted Jun 03 09:59:32 rivo volumio[3099]: info: Output device has changed, restarting MPD Jun 03 09:59:32 rivo sudo[7184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 03 09:59:32 rivo sudo[7184]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:59:32 rivo volumio[3099]: info: QobuzConnect: setDeactiveState invoked Jun 03 09:59:32 rivo volumio[3099]: info: CoreCommandRouter::volumioGetState Jun 03 09:59:32 rivo volumio[3099]: info: CorePlayQueue::getTrack 2 Jun 03 09:59:32 rivo volumio[3099]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 03 09:59:32 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:32 rivo volumio[3099]: info: PLUGIN START: fusiondsp Jun 03 09:59:32 rivo sudo[7184]: pam_unix(sudo:session): session closed for user root Jun 03 09:59:32 rivo volumio[3099]: info: Loading i18n strings for locale ja Jun 03 09:59:32 rivo sudo[7186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 03 09:59:32 rivo sudo[7186]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:59:32 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 03 09:59:32 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:32 rivo volumio[3099]: info: FusionDsp - mixtype--------------------- None Jun 03 09:59:32 rivo volumio[3099]: info: Preparing to generate the ALSA configuration file Jun 03 09:59:32 rivo systemd[1]: Stopping Music Player Daemon... Jun 03 09:59:32 rivo volumio[3099]: info: Done. Jun 03 09:59:32 rivo volumio[3099]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 03 09:59:32 rivo volumio[3099]: info: Reading ALSA contributions from plugins. Jun 03 09:59:32 rivo volumio[3099]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 03 09:59:32 rivo volumio[3099]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 03 09:59:32 rivo volumio[3099]: info: MPD Permissions set Jun 03 09:59:32 rivo sudo[7193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 03 09:59:32 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:32 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:32 rivo sudo[7193]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:59:32 rivo sudo[7193]: pam_unix(sudo:session): session closed for user root Jun 03 09:59:32 rivo systemd[1]: mpd.service: Succeeded. Jun 03 09:59:32 rivo systemd[1]: Stopped Music Player Daemon. Jun 03 09:59:32 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 09:59:32 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 03 09:59:32 rivo sudo[7196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 03 09:59:32 rivo systemd[1]: Starting Music Player Daemon... Jun 03 09:59:32 rivo sudo[7196]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:59:32 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:32 rivo qobuz-connect[5704]: 20260603 09:59:32.376 [5704.5704] INFO SampleApp: Stopping Local configuration server Jun 03 09:59:32 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 03 09:59:32 rivo qobuz-connect[5704]: 20260603 09:59:32.386 [5704.5704] INFO SampleApp: shat down connection on UNIX socket Jun 03 09:59:32 rivo volumio[3099]: info: FusionDsp - no preset used Jun 03 09:59:32 rivo systemd[1]: qobuz-connect.service: Succeeded. Jun 03 09:59:32 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 03 09:59:32 rivo systemd[1]: Started Volumio Qobuz Connect Service. Jun 03 09:59:32 rivo sudo[7196]: pam_unix(sudo:session): session closed for user root Jun 03 09:59:32 rivo sudo[7200]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 03 09:59:32 rivo sudo[7200]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:59:32 rivo sudo[7200]: pam_unix(sudo:session): session closed for user root Jun 03 09:59:32 rivo volumio[3099]: info: FusionDsp - no preset used Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 09:59:33 rivo volumio[3099]: info: Not Reporting Auto name since its the default one Jun 03 09:59:33 rivo volumio[3099]: info: FusionDsp - kef.json,test.json Jun 03 09:59:33 rivo volumio[3099]: info: FusionDsp - kef.json,test.json Jun 03 09:59:33 rivo volumio[3099]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 03 09:59:33 rivo volumio[3099]: info: QobuzConnect: setDeactiveState invoked Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::volumioGetState Jun 03 09:59:33 rivo volumio[3099]: info: CorePlayQueue::getTrack 2 Jun 03 09:59:33 rivo volumio[3099]: info: Executing endpoint qc_getconfig Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 03 09:59:33 rivo qobuz-connect[7211]: 20260603 09:59:33.188 [7211.7211] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 03 09:59:33 rivo volumio[3099]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 03 09:59:33 rivo volumio[3099]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 03 09:59:33 rivo qobuz-connect[7211]: 20260603 09:59:33.195 [7211.7211] INFO VolumeManager: [0xad106f50]: Setting new playback volume: 75 Jun 03 09:59:33 rivo qobuz-connect[7211]: 20260603 09:59:33.195 [7211.7211] INFO VolumeManager: [0xad106f50]: Setting new mute state: 0 Jun 03 09:59:33 rivo qobuz-connect[7211]: 20260603 09:59:33.195 [7211.7211] INFO AudioStreamManager: [0xad106ca8]: Setting new audio download buffer size: 1048576 Jun 03 09:59:33 rivo qobuz-connect[7211]: 20260603 09:59:33.195 [7211.7211] INFO QobuzConnect: [0xad107818]: Client initialized! Jun 03 09:59:33 rivo qobuz-connect[7211]: 20260603 09:59:33.195 [7211.7211] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Jun 03 09:59:33 rivo volumio[3099]: info: Asound.conf file unchanged, so no further update is needed Jun 03 09:59:33 rivo volumio[3099]: info: Output device has changed, restarting MPD Jun 03 09:59:33 rivo qobuz-connect[7211]: 20260603 09:59:33.214 [7211.7211] INFO LocalConfigManager: [0xad1069d0]: Starting Local Configuration server Jun 03 09:59:33 rivo qobuz-connect[7211]: 20260603 09:59:33.214 [7211.7211] INFO SampleApp: Starting Local configuration server Jun 03 09:59:33 rivo qobuz-connect[7211]: 20260603 09:59:33.215 [7211.7211] INFO SampleApp: Connected to UNIX socket client 0xad0fced8 Jun 03 09:59:33 rivo sudo[7226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 03 09:59:33 rivo sudo[7226]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:59:33 rivo volumio[3099]: info: QobuzConnect: setDeactiveState invoked Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::volumioGetState Jun 03 09:59:33 rivo volumio[3099]: info: CorePlayQueue::getTrack 2 Jun 03 09:59:33 rivo volumio[3099]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:33 rivo sudo[7226]: pam_unix(sudo:session): session closed for user root Jun 03 09:59:33 rivo sudo[7229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 03 09:59:33 rivo sudo[7229]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:59:33 rivo qobuz-connect[7211]: 20260603 09:59:33.338 [7211.7211] INFO SampleApp: Playback volume changed: 75 Jun 03 09:59:33 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 03 09:59:33 rivo systemd[1]: mpd.service: Succeeded. Jun 03 09:59:33 rivo systemd[1]: Stopped Music Player Daemon. Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 03 09:59:33 rivo systemd[1]: Starting Music Player Daemon... Jun 03 09:59:33 rivo sudo[7236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 03 09:59:33 rivo sudo[7236]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:59:33 rivo sudo[7236]: pam_unix(sudo:session): session closed for user root Jun 03 09:59:33 rivo sudo[7241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 03 09:59:33 rivo sudo[7241]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:59:33 rivo qobuz-connect[7211]: 20260603 09:59:33.451 [7211.7211] INFO SampleApp: Stopping Local configuration server Jun 03 09:59:33 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 03 09:59:33 rivo qobuz-connect[7211]: 20260603 09:59:33.462 [7211.7211] INFO SampleApp: shat down connection on UNIX socket Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::volumioGetState Jun 03 09:59:33 rivo volumio[3099]: info: CorePlayQueue::getTrack 2 Jun 03 09:59:33 rivo volumio[3099]: info: MPD Permissions set Jun 03 09:59:33 rivo volumio[3099]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 03 09:59:33 rivo volumio[3099]: info: QobuzConnect: setDeactiveState invoked Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::volumioGetState Jun 03 09:59:33 rivo volumio[3099]: info: CorePlayQueue::getTrack 2 Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:33 rivo systemd[1]: qobuz-connect.service: Succeeded. Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:33 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 03 09:59:33 rivo systemd[1]: Started Volumio Qobuz Connect Service. Jun 03 09:59:33 rivo sudo[7244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 03 09:59:33 rivo sudo[7244]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:59:33 rivo sudo[7241]: pam_unix(sudo:session): session closed for user root Jun 03 09:59:33 rivo sudo[7237]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 03 09:59:33 rivo sudo[7237]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:59:33 rivo sudo[7244]: pam_unix(sudo:session): session closed for user root Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 03 09:59:33 rivo sudo[7237]: pam_unix(sudo:session): session closed for user root Jun 03 09:59:33 rivo sudo[7252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 03 09:59:33 rivo sudo[7252]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:33 rivo volumio[3099]: info: Executing endpoint qc_getconfig Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 03 09:59:33 rivo systemd[1]: Stopping RAAT DAEMON... Jun 03 09:59:33 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 03 09:59:33 rivo systemd[1]: raat-daemon.service: Succeeded. Jun 03 09:59:33 rivo systemd[1]: Stopped RAAT DAEMON. Jun 03 09:59:33 rivo systemd[1]: Started RAAT DAEMON. Jun 03 09:59:33 rivo sudo[7252]: pam_unix(sudo:session): session closed for user root Jun 03 09:59:33 rivo qobuz-connect[7245]: 20260603 09:59:33.647 [7245.7245] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 03 09:59:33 rivo qobuz-connect[7245]: 20260603 09:59:33.653 [7245.7245] INFO VolumeManager: [0xab69af50]: Setting new playback volume: 75 Jun 03 09:59:33 rivo qobuz-connect[7245]: 20260603 09:59:33.653 [7245.7245] INFO VolumeManager: [0xab69af50]: Setting new mute state: 0 Jun 03 09:59:33 rivo qobuz-connect[7245]: 20260603 09:59:33.653 [7245.7245] INFO AudioStreamManager: [0xab69aca8]: Setting new audio download buffer size: 1048576 Jun 03 09:59:33 rivo qobuz-connect[7245]: 20260603 09:59:33.653 [7245.7245] INFO QobuzConnect: [0xab69b818]: Client initialized! Jun 03 09:59:33 rivo qobuz-connect[7245]: 20260603 09:59:33.654 [7245.7245] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Jun 03 09:59:33 rivo qobuz-connect[7245]: 20260603 09:59:33.665 [7245.7245] INFO LocalConfigManager: [0xab69a9d0]: Starting Local Configuration server Jun 03 09:59:33 rivo qobuz-connect[7245]: 20260603 09:59:33.665 [7245.7245] INFO SampleApp: Starting Local configuration server Jun 03 09:59:33 rivo qobuz-connect[7245]: 20260603 09:59:33.666 [7245.7245] INFO SampleApp: Connected to UNIX socket client 0xab690ed8 Jun 03 09:59:33 rivo volumio[3099]: info: Raat Daemon started successfully Jun 03 09:59:33 rivo volumio[3099]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 03 09:59:33 rivo volumio[3099]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:33 rivo qobuz-connect[7245]: 20260603 09:59:33.797 [7245.7245] INFO SampleApp: Playback volume changed: 75 Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 03 09:59:33 rivo volumio[3099]: info: Not Reporting Auto name since its the default one Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::volumioGetState Jun 03 09:59:33 rivo volumio[3099]: info: CorePlayQueue::getTrack 2 Jun 03 09:59:33 rivo volumio[3099]: info: Executing endpoint restartRAATSocket Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 03 09:59:33 rivo volumio[3099]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 03 09:59:33 rivo volumio[3099]: info: CorePlayQueue::getTrack 2 Jun 03 09:59:33 rivo volumio[3099]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 09:59:33 rivo sudo[7280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 03 09:59:33 rivo volumio[3099]: TypeError: Cannot read property 'off' of undefined Jun 03 09:59:33 rivo volumio[3099]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Jun 03 09:59:33 rivo volumio[3099]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jun 03 09:59:33 rivo volumio[3099]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Jun 03 09:59:33 rivo volumio[3099]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Jun 03 09:59:33 rivo volumio[3099]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1412:47) Jun 03 09:59:33 rivo volumio[3099]: at Socket.emit (events.js:400:28) Jun 03 09:59:33 rivo volumio[3099]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 03 09:59:33 rivo volumio[3099]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jun 03 09:59:33 rivo volumio[3099]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 09:59:33 rivo sudo[7280]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:59:33 rivo sudo[7280]: pam_unix(sudo:session): session closed for user root Jun 03 09:59:34 rivo sudo[7283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 03 09:59:34 rivo sudo[7283]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 09:59:34 rivo systemd[1]: Stopping RAAT DAEMON... Jun 03 09:59:34 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 03 09:59:34 rivo systemd[1]: raat-daemon.service: Succeeded. Jun 03 09:59:34 rivo systemd[1]: Stopped RAAT DAEMON. Jun 03 09:59:34 rivo systemd[1]: Started RAAT DAEMON. Jun 03 09:59:34 rivo sudo[7283]: pam_unix(sudo:session): session closed for user root Jun 03 09:59:34 rivo sudo[7303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-06-03 09:58 Jun 03 09:59:34 rivo sudo[7303]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 11:38:48 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="8e381701610c2a79deb52e712150c089"