-- Logs begin at Thu 2025-05-01 13:43:38 CEST, end at Fri 2025-05-23 17:13:57 CEST. -- May 23 17:12:16 rivoplus ntpd[14777]: error resolving pool 3.debian.pool.ntp.org: System error (-11) May 23 17:12:16 rivoplus ntpd[14777]: error resolving pool 2.debian.pool.ntp.org: System error (-11) May 23 17:12:20 rivoplus ntpd[14777]: error resolving pool 1.debian.pool.ntp.org: System error (-11) May 23 17:12:23 rivoplus ntpd[14777]: error resolving pool 0.debian.pool.ntp.org: System error (-11) May 23 17:12:38 rivoplus volumio[26908]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] May 23 17:12:38 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet May 23 17:12:38 rivoplus sudo[27855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces May 23 17:12:38 rivoplus sudo[27855]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:12:38 rivoplus sudo[27855]: pam_unix(sudo:session): session closed for user root May 23 17:12:38 rivoplus sudo[27857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf May 23 17:12:38 rivoplus sudo[27857]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:12:38 rivoplus sudo[27857]: pam_unix(sudo:session): session closed for user root May 23 17:12:38 rivoplus volumio[26908]: info: Permissions for /etc/network/interfaces set May 23 17:12:38 rivoplus volumio[26908]: info: Restarting Wired Network May 23 17:12:38 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 23 17:12:38 rivoplus volumio[26908]: info: Discovery: Restarting Advertising due to device name change May 23 17:12:38 rivoplus sudo[27860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 May 23 17:12:38 rivoplus sudo[27860]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:12:38 rivoplus avahi-daemon[2842]: Withdrawing address record for 192.168.1.131 on eth0. May 23 17:12:38 rivoplus avahi-daemon[2842]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.131. May 23 17:12:38 rivoplus dhcpcd[3421]: eth0: pid 27861 deleted IP address 192.168.1.131/24 May 23 17:12:38 rivoplus dhcpcd[3338]: eth0: pid 27861 deleted IP address 192.168.1.131/24 May 23 17:12:38 rivoplus volumio[26908]: info: Discovery: A device disappeared from network May 23 17:12:38 rivoplus volumio[26908]: info: Discovery: Device rivoplus disappeared from network May 23 17:12:38 rivoplus dhcpcd[3338]: eth0: deleting route to 192.168.1.0/24 May 23 17:12:38 rivoplus dhcpcd[3421]: eth0: deleting route to 192.168.1.0/24 May 23 17:12:38 rivoplus dhcpcd[3338]: eth0: deleting default route via 192.168.1.254 May 23 17:12:38 rivoplus avahi-daemon[2842]: Interface eth0.IPv4 no longer relevant for mDNS. May 23 17:12:38 rivoplus dhcpcd[3421]: eth0: deleting default route via 192.168.1.254 May 23 17:12:38 rivoplus sudo[27860]: pam_unix(sudo:session): session closed for user root May 23 17:12:38 rivoplus sudo[27865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down May 23 17:12:38 rivoplus sudo[27865]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:12:38 rivoplus sudo[27865]: pam_unix(sudo:session): session closed for user root May 23 17:12:38 rivoplus dhcpcd[3338]: eth0: rebinding lease of 192.168.1.131 May 23 17:12:38 rivoplus dhcpcd[3421]: eth0: rebinding lease of 192.168.1.131 May 23 17:12:38 rivoplus dhcpcd[3421]: eth0: probing address 192.168.1.131/24 May 23 17:12:38 rivoplus dhcpcd[3338]: eth0: probing address 192.168.1.131/24 May 23 17:12:39 rivoplus sudo[27884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up May 23 17:12:39 rivoplus sudo[27884]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:12:39 rivoplus sudo[27884]: pam_unix(sudo:session): session closed for user root May 23 17:12:40 rivoplus ntpd[14777]: Deleting interface #3 eth0, 192.168.1.131#123, interface stats: received=0, sent=0, dropped=0, active_time=4612 secs May 23 17:12:43 rivoplus dhcpcd[3338]: eth0: leased 192.168.1.131 for 14400 seconds May 23 17:12:43 rivoplus dhcpcd[3338]: eth0: adding route to 192.168.1.0/24 May 23 17:12:43 rivoplus dhcpcd[3338]: eth0: adding default route via 192.168.1.254 May 23 17:12:43 rivoplus avahi-daemon[2842]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.131. May 23 17:12:43 rivoplus avahi-daemon[2842]: New relevant interface eth0.IPv4 for mDNS. May 23 17:12:43 rivoplus avahi-daemon[2842]: Registering new address record for 192.168.1.131 on eth0.IPv4. May 23 17:12:43 rivoplus volumio[26908]: info: Discovery: Started advertising with name: Rivoplus May 23 17:12:43 rivoplus ntpd[14777]: ntpd exiting on signal 15 (Terminated) May 23 17:12:43 rivoplus systemd[1]: Stopping Network Time Service... May 23 17:12:43 rivoplus systemd[1]: ntp.service: Succeeded. May 23 17:12:43 rivoplus systemd[1]: Stopped Network Time Service. May 23 17:12:43 rivoplus systemd[1]: Starting Network Time Service... May 23 17:12:44 rivoplus ntpd[27924]: ntpd 4.2.8p12@1.3728-o (1): Starting May 23 17:12:44 rivoplus ntpd[27924]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 23 17:12:44 rivoplus systemd[1]: Started Network Time Service. May 23 17:12:44 rivoplus ntpd[27937]: proto: precision = 1.250 usec (-20) May 23 17:12:44 rivoplus ntpd[27937]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 23 17:12:44 rivoplus ntpd[27937]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 23 17:12:44 rivoplus ntpd[27937]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 878 days ago May 23 17:12:44 rivoplus ntpd[27937]: Listen and drop on 0 v6wildcard [::]:123 May 23 17:12:44 rivoplus ntpd[27937]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 23 17:12:44 rivoplus ntpd[27937]: Listen normally on 2 lo 127.0.0.1:123 May 23 17:12:44 rivoplus ntpd[27937]: Listen normally on 3 eth0 192.168.1.131:123 May 23 17:12:44 rivoplus ntpd[27937]: Listening on routing socket on fd #20 for interface updates May 23 17:12:44 rivoplus ntpd[27937]: kernel reports TIME_ERROR: 0x6041: Clock Unsynchronized May 23 17:12:44 rivoplus ntpd[27937]: kernel reports TIME_ERROR: 0x6041: Clock Unsynchronized May 23 17:12:44 rivoplus dhcpcd[3421]: eth0: leased 192.168.1.131 for 14400 seconds May 23 17:12:44 rivoplus dhcpcd[3421]: eth0: adding route to 192.168.1.0/24 May 23 17:12:44 rivoplus dhcpcd[3421]: eth0: adding default route via 192.168.1.254 May 23 17:12:44 rivoplus ntpd[27937]: ntpd exiting on signal 15 (Terminated) May 23 17:12:44 rivoplus systemd[1]: Stopping Network Time Service... May 23 17:12:44 rivoplus systemd[1]: ntp.service: Succeeded. May 23 17:12:44 rivoplus systemd[1]: Stopped Network Time Service. May 23 17:12:44 rivoplus systemd[1]: Starting Network Time Service... May 23 17:12:44 rivoplus ntpd[27970]: ntpd 4.2.8p12@1.3728-o (1): Starting May 23 17:12:44 rivoplus ntpd[27970]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 23 17:12:44 rivoplus systemd[1]: Started Network Time Service. May 23 17:12:44 rivoplus ntpd[27976]: proto: precision = 1.208 usec (-20) May 23 17:12:44 rivoplus ntpd[27976]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 23 17:12:44 rivoplus ntpd[27976]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 23 17:12:44 rivoplus ntpd[27976]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 878 days ago May 23 17:12:44 rivoplus ntpd[27976]: Listen and drop on 0 v6wildcard [::]:123 May 23 17:12:44 rivoplus ntpd[27976]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 23 17:12:44 rivoplus ntpd[27976]: Listen normally on 2 lo 127.0.0.1:123 May 23 17:12:44 rivoplus ntpd[27976]: Listen normally on 3 eth0 192.168.1.131:123 May 23 17:12:44 rivoplus ntpd[27976]: Listening on routing socket on fd #20 for interface updates May 23 17:12:44 rivoplus ntpd[27976]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized May 23 17:12:44 rivoplus ntpd[27976]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 23 17:12:44 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 23 17:12:45 rivoplus ntpd[27976]: Soliciting pool server 213.157.100.71 May 23 17:12:46 rivoplus volumio[26908]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 23 17:12:46 rivoplus volumio[26908]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 23 17:12:46 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:12:46 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:12:46 rivoplus volumio[26908]: info: Starting MyVolumio Remote Streaming Endpoints May 23 17:12:46 rivoplus volumio[26908]: info: MyVolumio login type: Token May 23 17:12:46 rivoplus volumio[26908]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 23 17:12:46 rivoplus volumio[26908]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' May 23 17:12:46 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 17:12:46 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 17:12:46 rivoplus volumio[26908]: info: Discovery: Getting this device information May 23 17:12:46 rivoplus volumio[26908]: info: CoreCommandRouter::volumioGetState May 23 17:12:46 rivoplus volumio[26908]: info: CorePlayQueue::getTrack 0 May 23 17:12:46 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 17:12:46 rivoplus volumio[26908]: info: Discovery: adding e44cdfc2-fcf3-46ad-820c-2f6d8fc2c4ef May 23 17:12:46 rivoplus volumio[26908]: info: Discovery: Found device Rivoplus May 23 17:12:46 rivoplus volumio[26908]: info: CoreCommandRouter::volumioGetState May 23 17:12:46 rivoplus volumio[26908]: info: CorePlayQueue::getTrack 0 May 23 17:12:46 rivoplus volumio[26908]: verbose: New Socket.io Connection to 192.168.1.131:3000 from 192.168.1.126 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 23 17:12:46 rivoplus volumio[26908]: info: CoreCommandRouter::volumioGetState May 23 17:12:46 rivoplus volumio[26908]: info: CorePlayQueue::getTrack 0 May 23 17:12:46 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 23 17:12:46 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 23 17:12:46 rivoplus volumio[26908]: error: MyVolumio Custom Token format not valid, refreshing it May 23 17:12:46 rivoplus ntpd[27976]: Soliciting pool server 188.156.114.7 May 23 17:12:46 rivoplus ntpd[27976]: Soliciting pool server 193.227.197.2 May 23 17:12:47 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 17:12:47 rivoplus volumio[26908]: info: Received Get System Info May 23 17:12:47 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 17:12:47 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 17:12:47 rivoplus volumio[26908]: info: Discovery: Getting this device information May 23 17:12:47 rivoplus volumio[26908]: info: CoreCommandRouter::volumioGetState May 23 17:12:47 rivoplus volumio[26908]: info: CorePlayQueue::getTrack 0 May 23 17:12:47 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 17:12:47 rivoplus volumio[26908]: info: MyVolumio login type: Token May 23 17:12:47 rivoplus ntpd[27976]: Soliciting pool server 80.249.164.126 May 23 17:12:47 rivoplus ntpd[27976]: Soliciting pool server 80.249.164.129 May 23 17:12:47 rivoplus ntpd[27976]: Soliciting pool server 193.6.222.47 May 23 17:12:47 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 23 17:12:47 rivoplus volumio[26908]: info: MyVolumio login type: Token May 23 17:12:48 rivoplus volumio[26908]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 23 17:12:48 rivoplus ntpd[27976]: Soliciting pool server 62.112.195.26 May 23 17:12:48 rivoplus ntpd[27976]: Soliciting pool server 62.112.193.9 May 23 17:12:48 rivoplus ntpd[27976]: Soliciting pool server 92.249.148.253 May 23 17:12:48 rivoplus ntpd[27976]: Soliciting pool server 193.6.222.20 May 23 17:12:48 rivoplus volumio[26908]: info: MyVolumio token set successfully May 23 17:12:48 rivoplus volumio[26908]: info: MYVOLUMIO: Adding device May 23 17:12:48 rivoplus volumio[26908]: info: MYVOLUMIO: Evaluating Server May 23 17:12:49 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart May 23 17:12:49 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart May 23 17:12:49 rivoplus volumio[26908]: info: Refreshing Cached IP Addresses May 23 17:12:49 rivoplus sudo[28018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 23 17:12:49 rivoplus sudo[28018]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:12:49 rivoplus volumio[26908]: info: MyVolumio status changed May 23 17:12:49 rivoplus volumio[26908]: info: Streaming services startup May 23 17:12:49 rivoplus volumio[26908]: info: Starting Streaming Daemon May 23 17:12:49 rivoplus sudo[28020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 23 17:12:49 rivoplus sudo[28020]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:12:49 rivoplus sudo[28018]: pam_unix(sudo:session): session closed for user root May 23 17:12:49 rivoplus sudo[28020]: pam_unix(sudo:session): session closed for user root May 23 17:12:49 rivoplus volumio[26908]: info: Removing browser output: myVolumio user plan is not superstar May 23 17:12:49 rivoplus volumio[26908]: info: Removing audio output: May 23 17:12:49 rivoplus volumio[26908]: info: Stoppping Tunnel 1 May 23 17:12:49 rivoplus sudo[28026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 23 17:12:49 rivoplus sudo[28026]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:12:49 rivoplus sudo[28026]: pam_unix(sudo:session): session closed for user root May 23 17:12:49 rivoplus sudo[28029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 23 17:12:49 rivoplus sudo[28029]: pam_unix(sudo:session): session opened for user root by (uid=0) May 23 17:12:49 rivoplus volumio[26908]: error: Cannot start Volumio Streaming Daemon May 23 17:12:49 rivoplus sudo[28029]: pam_unix(sudo:session): session closed for user root May 23 17:12:49 rivoplus volumio[26908]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 23 17:12:49 rivoplus volumio[26908]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 23 17:12:49 rivoplus volumio[26908]: info: Remote SSH Stopped May 23 17:12:49 rivoplus ntpd[27976]: Soliciting pool server 195.111.92.55 May 23 17:12:49 rivoplus ntpd[27976]: Soliciting pool server 81.0.124.200 May 23 17:12:49 rivoplus ntpd[27976]: Soliciting pool server 62.112.195.56 May 23 17:12:49 rivoplus volumio[26908]: info: Setting Geolocation for MyVolumio to eu4 May 23 17:12:49 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:12:49 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:12:49 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:12:50 rivoplus ntpd[27976]: Soliciting pool server 213.157.100.66 May 23 17:12:50 rivoplus ntpd[27976]: Soliciting pool server 2001:ac8:26:62:0:1:0:14 May 23 17:12:50 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 17:12:50 rivoplus volumio[26908]: info: Received Get System Info May 23 17:12:50 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 17:12:50 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 17:12:50 rivoplus volumio[26908]: info: Discovery: Getting this device information May 23 17:12:50 rivoplus volumio[26908]: info: CoreCommandRouter::volumioGetState May 23 17:12:50 rivoplus volumio[26908]: info: CorePlayQueue::getTrack 0 May 23 17:12:50 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 17:12:50 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 17:12:50 rivoplus volumio[26908]: info: Received Get System Info May 23 17:12:50 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 17:12:50 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 17:12:50 rivoplus volumio[26908]: info: Discovery: Getting this device information May 23 17:12:50 rivoplus volumio[26908]: info: CoreCommandRouter::volumioGetState May 23 17:12:50 rivoplus volumio[26908]: info: CorePlayQueue::getTrack 0 May 23 17:12:50 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 17:12:50 rivoplus volumio[26908]: info: Updating MyVolumio device info May 23 17:12:50 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:12:50 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:12:50 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:12:50 rivoplus volumio[26908]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 23 17:12:51 rivoplus volumio[26908]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 23 17:12:51 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 23 17:12:51 rivoplus volumio[26908]: info: Received Get System Info May 23 17:12:51 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 17:12:51 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 17:12:51 rivoplus volumio[26908]: info: Discovery: Getting this device information May 23 17:12:51 rivoplus volumio[26908]: info: CoreCommandRouter::volumioGetState May 23 17:12:51 rivoplus volumio[26908]: info: CorePlayQueue::getTrack 0 May 23 17:12:51 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 17:12:51 rivoplus ntpd[27976]: Soliciting pool server 82.141.152.3 May 23 17:12:54 rivoplus volumio[26908]: info: MYVOLUMIO: Adding device May 23 17:12:54 rivoplus volumio[26908]: info: MYVOLUMIO: Evaluating Server May 23 17:12:55 rivoplus volumio[26908]: info: Setting Geolocation for MyVolumio to eu4 May 23 17:12:55 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:12:55 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:12:55 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:12:56 rivoplus volumio[26908]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 23 17:12:56 rivoplus volumio[26908]: info: Updating MyVolumio device info May 23 17:12:56 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:12:56 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:12:56 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 23 17:12:57 rivoplus volumio[26908]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 23 17:13:01 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 23 17:13:01 rivoplus volumio[26908]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 23 17:13:01 rivoplus volumio[26908]: info: CoreCommandRouter::volumioGetState May 23 17:13:01 rivoplus volumio[26908]: info: CorePlayQueue::getTrack 0 May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::volumioGetState May 23 17:13:04 rivoplus volumio[26908]: info: CorePlayQueue::getTrack 0 May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::volumioGetState May 23 17:13:04 rivoplus volumio[26908]: info: CorePlayQueue::getTrack 0 May 23 17:13:04 rivoplus volumio[26908]: info: Currently active: tidal May 23 17:13:04 rivoplus volumio[26908]: info: Stopping currently active service May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::volumioStop May 23 17:13:04 rivoplus volumio[26908]: info: CoreStateMachine::stop May 23 17:13:04 rivoplus volumio[26908]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 17:13:04 rivoplus volumio[26908]: info: TidalConnect Active May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::volumioGetState May 23 17:13:04 rivoplus volumio[26908]: info: CorePlayQueue::getTrack 0 May 23 17:13:04 rivoplus volumio[26908]: info: Currently active: tidal May 23 17:13:04 rivoplus volumio[26908]: info: Setting Volatile state to tidalconnect May 23 17:13:04 rivoplus volumio[26908]: info: CoreStateMachine::setConsumeUpdateService undefined May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:04 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:04 rivoplus volumio[26908]: info: CorePlayQueue::getTrack 0 May 23 17:13:04 rivoplus volumio[26908]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:04 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:04 rivoplus volumio[26908]: info: CorePlayQueue::getTrack 0 May 23 17:13:04 rivoplus volumio[26908]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 23 17:13:04 rivoplus volumio[26908]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 23 17:13:04 rivoplus volumio[26908]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 23 17:13:04 rivoplus vtcs[27236]: [2025-05-23 17:13:04.678] [tisoc] [warning] [PlaybackControllerImpl.cpp:510] Illegal state:1 May 23 17:13:04 rivoplus vtcs[27236]: [2025-05-23 17:13:04.679] [tisoc] [warning] [PlaybackControllerImpl.cpp:214] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 May 23 17:13:04 rivoplus volumio[26908]: info: Pushing metadata May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:04 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:04 rivoplus volumio[26908]: info: CorePlayQueue::getTrack 0 May 23 17:13:04 rivoplus volumio[26908]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:04 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:04 rivoplus volumio[26908]: info: CorePlayQueue::getTrack 0 May 23 17:13:04 rivoplus volumio[26908]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:04 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:04 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:04 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:04 rivoplus volumio[26908]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 23 17:13:04 rivoplus volumio[26908]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined May 23 17:13:04 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:04 rivoplus volumio[26908]: info: MCU Signalled Playback Active May 23 17:13:05 rivoplus volumio[26908]: info: Pushing metadata May 23 17:13:05 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:05 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:05 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:05 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:05 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:05 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:05 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:05 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:05 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:05 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:05 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:05 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:05 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:05 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:05 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:05 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:05 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:05 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:05 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:05 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:05 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:05 rivoplus volumio[26908]: info: MCU Signalled Playback Inactive May 23 17:13:05 rivoplus volumio[26908]: info: MCU Signalled Playback Active May 23 17:13:05 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:05 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:05 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:05 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:05 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:05 rivoplus vtcs[27236]: [2025-05-23 17:13:05.936] [tisoc] [warning] [flac_decoder.cpp:282] FLACDecoder::error_callback FLAC__STREAM_DECODER_ERROR_STATUS_LOST_SYNC May 23 17:13:05 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:05 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:05 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:05 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:05 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:06 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:06 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:06 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:06 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:06 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:13:06 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 23 17:13:06 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 23 17:13:06 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 23 17:13:06 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 23 17:13:06 rivoplus volumio[26908]: info: CoreCommandRouter::volumioGetBrowseSources May 23 17:13:06 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 23 17:13:06 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:12 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:12 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume11 May 23 17:13:12 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:12 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:12 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:12 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:12 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:12 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume12 May 23 17:13:12 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:12 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:12 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:12 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:12 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume13 May 23 17:13:12 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:12 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:12 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:12 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:12 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:12 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:12 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume14 May 23 17:13:13 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:13 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:13 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:13 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:13 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume15 May 23 17:13:13 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:13 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:13 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:13 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:13 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:13 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:13 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume16 May 23 17:13:13 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:13 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume17 May 23 17:13:13 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:13 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:13 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:13 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:13 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:13 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:13 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume18 May 23 17:13:13 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:13 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume19 May 23 17:13:13 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:13 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:13 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:13 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:13 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:13 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:13 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:13 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:13 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:13 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:13 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume20 May 23 17:13:13 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:13 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume21 May 23 17:13:13 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:13 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:13 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:13 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:13 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:13 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:13 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:14 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:14 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume22 May 23 17:13:14 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:14 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:14 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:14 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume23 May 23 17:13:14 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:14 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:14 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume24 May 23 17:13:14 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:14 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:14 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:14 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:14 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume25 May 23 17:13:14 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:14 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:14 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume26 May 23 17:13:14 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:14 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:14 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:14 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:14 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume27 May 23 17:13:14 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:14 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume28 May 23 17:13:14 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:14 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:14 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:14 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume29 May 23 17:13:14 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:14 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume30 May 23 17:13:14 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:14 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:14 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:14 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:14 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:14 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:14 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:14 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume31 May 23 17:13:14 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:14 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume32 May 23 17:13:14 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:14 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:14 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:14 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume33 May 23 17:13:14 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:14 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:14 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:14 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:14 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:14 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:14 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume34 May 23 17:13:14 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:14 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume35 May 23 17:13:15 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:15 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:15 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:15 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:15 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:15 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:15 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:16 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:16 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume36 May 23 17:13:16 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:16 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:16 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:16 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:16 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:16 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume37 May 23 17:13:16 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:16 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:16 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:16 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:16 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume38 May 23 17:13:16 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:16 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:16 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:16 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:17 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:17 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:17 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume39 May 23 17:13:17 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:17 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:17 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:17 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:17 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume40 May 23 17:13:17 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:17 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:17 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:17 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:17 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:17 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:17 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume41 May 23 17:13:17 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:17 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume42 May 23 17:13:17 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:17 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:17 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:17 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:17 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:17 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:17 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume43 May 23 17:13:17 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:17 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:17 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:17 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:17 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:17 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:17 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:17 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume44 May 23 17:13:17 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:17 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume45 May 23 17:13:17 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:17 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume46 May 23 17:13:17 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:17 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:17 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:17 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:17 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:17 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:17 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:17 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:17 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume47 May 23 17:13:17 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:17 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume48 May 23 17:13:17 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:17 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:17 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:17 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:17 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:17 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:17 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:17 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:17 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:17 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:18 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:18 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume49 May 23 17:13:18 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:18 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:18 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:18 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:18 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:18 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume50 May 23 17:13:18 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:18 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:18 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:18 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:18 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume51 May 23 17:13:18 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:18 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:18 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:18 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:18 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:18 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:18 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume52 May 23 17:13:18 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:18 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:18 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:18 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:18 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume53 May 23 17:13:18 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:18 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume54 May 23 17:13:18 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:18 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:18 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:18 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:18 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:18 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:18 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:18 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:18 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:18 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume55 May 23 17:13:18 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:18 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume56 May 23 17:13:19 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:19 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:19 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:19 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:19 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:19 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:19 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume57 May 23 17:13:19 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:19 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume58 May 23 17:13:19 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:19 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:19 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:19 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:19 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:19 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:19 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:19 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:19 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:19 rivoplus volumio[26908]: info: Volume: {"mute":false} May 23 17:13:19 rivoplus volumio[26908]: info: VolumeController::SetAlsaVolume59 May 23 17:13:19 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:19 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:19 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:19 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:20 rivoplus volumio[26908]: info: Pushing metadata May 23 17:13:20 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:20 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:20 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:20 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:20 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:20 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:20 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:20 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:20 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:20 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:20 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:20 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:20 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:20 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:20 rivoplus volumio[26908]: info: Pushing metadata May 23 17:13:20 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:20 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:20 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:20 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:20 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:20 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:21 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:21 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:21 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:21 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:21 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:21 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:21 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:21 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:21 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:21 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:21 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:21 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:21 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:21 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:21 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:32 rivoplus volumio[26908]: info: Pushing metadata May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:32 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:32 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:32 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:32 rivoplus volumio[26908]: info: Pushing metadata May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:32 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:32 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:32 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:32 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:32 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:32 rivoplus volumio[26908]: info: MCU Signalled Playback Inactive May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:32 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:32 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:32 rivoplus volumio[26908]: info: MCU Signalled Playback Active May 23 17:13:33 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:33 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:33 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:33 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:33 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:35 rivoplus volumio[26908]: info: Pushing metadata May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:35 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:35 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:35 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:35 rivoplus volumio[26908]: info: Pushing metadata May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:35 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:35 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:35 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:35 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:35 rivoplus volumio[26908]: info: MCU Signalled Playback Inactive May 23 17:13:35 rivoplus volumio[26908]: info: MCU Signalled Playback Active May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:35 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:35 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:36 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:36 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:36 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:36 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:36 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:36 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:36 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:36 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:36 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:48 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:48 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:48 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:48 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:48 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:49 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:49 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:49 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:49 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:49 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:49 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:49 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:49 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:50 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:50 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:50 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:50 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:50 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:51 rivoplus vtcs[27236]: [2025-05-23 17:13:51.584] [tisoc] [warning] [PlaybackControllerImpl.cpp:214] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:51 rivoplus volumio[26908]: info: Pushing metadata May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:51 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:51 rivoplus volumio[26908]: info: Pushing metadata May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:51 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:51 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:51 rivoplus volumio[26908]: info: MCU Signalled Playback Inactive May 23 17:13:51 rivoplus volumio[26908]: info: MCU Signalled Playback Active May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:51 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:52 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:52 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:52 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:52 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:52 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:52 rivoplus volumio[26908]: info: CoreCommandRouter::servicePushState May 23 17:13:52 rivoplus volumio[26908]: info: CoreStateMachine::pushState May 23 17:13:52 rivoplus volumio[26908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 17:13:52 rivoplus volumio[26908]: info: CoreCommandRouter::volumioPushState May 23 17:13:52 rivoplus volumio[26908]: info: Signalling Playback active due to playback status change May 23 17:13:56 rivoplus volumio[26908]: info: CorePlayQueue::getTrack 0 May 23 17:13:56 rivoplus volumio[26908]: info: CorePlayQueue::getTrack 1 May 23 17:13:56 rivoplus volumio[26908]: info: Prefetching next song May 23 17:13:56 rivoplus volumio[26908]: info: [1748013236468] ControllerTidal::prefetch May 23 17:13:56 rivoplus volumio[26908]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 17:13:56 rivoplus volumio[26908]: TypeError: Cannot read property 'highestSoundQuality' of undefined May 23 17:13:56 rivoplus volumio[26908]: at ControllerTidal.getProperSoundQuality (/volumio/app/plugins/music_service/tidal/tidal_real:1:19037) May 23 17:13:56 rivoplus volumio[26908]: at ControllerTidal.prefetch (/volumio/app/plugins/music_service/tidal/tidal_real:1:19741) May 23 17:13:56 rivoplus volumio[26908]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:460:18) May 23 17:13:56 rivoplus volumio[26908]: at listOnTimeout (internal/timers.js:557:17) May 23 17:13:56 rivoplus volumio[26908]: at processTimers (internal/timers.js:500:7) May 23 17:13:56 rivoplus volumio[26908]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 17:13:57 rivoplus sudo[28303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-23 17:12 May 23 17:13:57 rivoplus sudo[28303]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:06:47 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="f92ac0ecb6de863dba28fedfed23f19e"