-- Logs begin at Tue 2025-04-22 12:41:20 CST, end at Tue 2025-04-22 12:44:49 CST. -- Apr 22 12:43:00 rivo volumio[3421]: info: Successfully Updated MyVolumio device Apr 22 12:43:00 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 61 Apr 22 12:43:00 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:00 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:01 rivo volumio[3421]: info: Successfully Updated MyVolumio device Apr 22 12:43:01 rivo sudo[4411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 22 12:43:01 rivo sudo[4411]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 12:43:01 rivo systemd[1]: Started MyVolumio SSH Tunnel. Apr 22 12:43:01 rivo sudo[4411]: pam_unix(sudo:session): session closed for user root Apr 22 12:43:01 rivo volumio[3421]: info: Remote SSH Started Apr 22 12:43:01 rivo autossh[4422]: port set to 0, monitoring disabled Apr 22 12:43:01 rivo autossh[4422]: starting ssh (count 1) Apr 22 12:43:01 rivo autossh[4422]: ssh child pid is 4427 Apr 22 12:43:01 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 43 Apr 22 12:43:02 rivo volumiossh-tunnel[4413]: Warning: Permanently added '[as1.myvolumio.org]:2222,[128.199.166.162]:2222' (RSA) to the list of known hosts. Apr 22 12:43:02 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:02 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:02 rivo volumio[3421]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.PtlvY5Ib0ORipAf5wU4wXwp68EW2.d7219eebd2e3f4026ec045151d19954a.state.mute' Apr 22 12:43:02 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 38 Apr 22 12:43:02 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:02 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:03 rivo volumio[3421]: error: MyVolumio Plugin failed to start in a timely fashion Apr 22 12:43:03 rivo volumio[3421]: [Metrics] CommandRouter: 79s 717.54ms Apr 22 12:43:03 rivo volumio[3421]: info: CoreCommandRouter::volumiosetStartupVolume Apr 22 12:43:03 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 12:43:03 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 12:43:03 rivo volumio[3421]: info: CoreCommandRouter::Close All Modals sent Apr 22 12:43:03 rivo volumio[3421]: info: CoreCommandRouter::Close All Modals sent Apr 22 12:43:03 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Apr 22 12:43:03 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:03 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:04 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Apr 22 12:43:04 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:04 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:04 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 22 12:43:04 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 22 12:43:04 rivo volumio[3421]: No protocol specified Apr 22 12:43:04 rivo volumio[3421]: xcb_connection_has_error() returned true Apr 22 12:43:04 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 22 12:43:05 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 22 12:43:05 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:05 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:05 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 22 12:43:06 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:06 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:07 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 22 12:43:07 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:07 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:08 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 22 12:43:08 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:08 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:08 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 22 12:43:08 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 22 12:43:08 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:08 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:09 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 22 12:43:09 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:09 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:10 rivo volumio[3421]: info: BOOT COMPLETED Apr 22 12:43:10 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 22 12:43:10 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:10 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:11 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 22 12:43:11 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:11 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:12 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 12:43:12 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 12:43:12 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 12:43:12 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 12:43:12 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 12:43:12 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 12:43:12 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 12:43:12 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 12:43:12 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 12:43:12 rivo volumio[3421]: info: Not Reporting Auto name since its the default one Apr 22 12:43:12 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 22 12:43:12 rivo volumio[3421]: No protocol specified Apr 22 12:43:12 rivo volumio[3421]: xcb_connection_has_error() returned true Apr 22 12:43:12 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 22 12:43:12 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:12 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:12 rivo sudo[4456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 22 12:43:12 rivo sudo[4456]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 12:43:12 rivo sudo[4456]: pam_unix(sudo:session): session closed for user root Apr 22 12:43:12 rivo sudo[4458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 22 12:43:12 rivo sudo[4458]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 12:43:12 rivo systemd[1]: Started RAAT DAEMON. Apr 22 12:43:12 rivo sudo[4458]: pam_unix(sudo:session): session closed for user root Apr 22 12:43:12 rivo volumio[3421]: info: Raat Daemon started successfully Apr 22 12:43:12 rivo systemd[1]: Started Music Player Daemon. Apr 22 12:43:12 rivo volumio[3421]: Upnp client error: Error: This socket has been ended by the other party Apr 22 12:43:12 rivo volumio[3421]: Upnp client error: Error: This socket has been ended by the other party Apr 22 12:43:12 rivo mpd_monitor.sh[2853]: MPD Monitor Service: MPD restarted due to no mpc output. Apr 22 12:43:12 rivo sudo[3984]: pam_unix(sudo:session): session closed for user root Apr 22 12:43:12 rivo sudo[3973]: pam_unix(sudo:session): session closed for user root Apr 22 12:43:12 rivo sudo[4329]: pam_unix(sudo:session): session closed for user root Apr 22 12:43:12 rivo volumio[3421]: error: MPD error: The expression evaluated to a falsy value: Apr 22 12:43:12 rivo volumio[3421]: assert.ok(self.idling) Apr 22 12:43:12 rivo volumio[3421]: error: The expression evaluated to a falsy value: Apr 22 12:43:12 rivo volumio[3421]: assert.ok(self.idling) Apr 22 12:43:12 rivo volumio[3421]: info: Executing endpoint restartRAATSocket Apr 22 12:43:12 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 22 12:43:12 rivo volumio[3421]: error: MPD error: The expression evaluated to a falsy value: Apr 22 12:43:12 rivo volumio[3421]: assert.ok(self.idling) Apr 22 12:43:12 rivo volumio[3421]: error: The expression evaluated to a falsy value: Apr 22 12:43:12 rivo volumio[3421]: assert.ok(self.idling) Apr 22 12:43:12 rivo volumio[3421]: info: MPD running with PID4346 Apr 22 12:43:12 rivo volumio[3421]: ,establishing connection Apr 22 12:43:12 rivo volumio[3421]: error: updateQueue error: null Apr 22 12:43:12 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:12 rivo volumio[3421]: error: updateQueue error: null Apr 22 12:43:12 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 22 12:43:13 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:13 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:13 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:13 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 12:43:13 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 12:43:13 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 22 12:43:13 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 22 12:43:13 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 22 12:43:13 rivo volumio[3421]: info: CoreCommandRouter::volumioGetBrowseSources Apr 22 12:43:13 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 22 12:43:14 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:14 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:14 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:15 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:15 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:15 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:15 rivo volumio[3421]: info: RAAT: Requesting Headphone Status Apr 22 12:43:15 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Apr 22 12:43:15 rivo volumio[3421]: info: MCU Signalled Headphone Mode Disabled Apr 22 12:43:15 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 22 12:43:15 rivo volumio[3421]: info: Reporting Headphone State: false Apr 22 12:43:15 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 12:43:15 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 12:43:15 rivo volumio[3421]: info: Updating RAAT Signal Path Apr 22 12:43:16 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:16 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:16 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:18 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:18 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:18 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:19 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:19 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:19 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:19 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:19 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:19 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:20 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 22 12:43:20 rivo volumio[3421]: info: CURURI: music-library/NAS Apr 22 12:43:21 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:21 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:21 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:22 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:22 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:22 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:23 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:23 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:23 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:24 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 22 12:43:24 rivo volumio[3421]: info: CURURI: music-library Apr 22 12:43:24 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:24 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:24 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:25 rivo volumio[3421]: error: MPD error: Error: read ECONNRESET Apr 22 12:43:26 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:26 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:26 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:26 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:26 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:26 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:27 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:27 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:27 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:28 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:28 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:28 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:28 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 22 12:43:28 rivo volumio[3421]: info: CURURI: music-library Apr 22 12:43:28 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:28 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:28 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:30 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:30 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:30 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:31 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:31 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:31 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:32 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:32 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:32 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:33 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:33 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:33 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:33 rivo volumio[3421]: Upnp client error: Error: This socket has been ended by the other party Apr 22 12:43:33 rivo volumio[3421]: error: updateQueue error: null Apr 22 12:43:33 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:33 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:33 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:34 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:34 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:34 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:35 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:35 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:35 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:36 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:36 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:36 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:37 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:37 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:37 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:38 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:38 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:38 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:38 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:38 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:38 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:39 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:39 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:39 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:40 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:40 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:40 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:41 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:41 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:41 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:42 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 22 12:43:42 rivo volumio[3421]: info: CURURI: music-library Apr 22 12:43:42 rivo volumio[3421]: info: Preload queue cleared Apr 22 12:43:42 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:42 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:42 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:43 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 22 12:43:43 rivo volumio[3421]: info: CURURI: music-library/NAS Apr 22 12:43:43 rivo volumio[3421]: info: Preload queue cleared Apr 22 12:43:43 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:43 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:43 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:44 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:44 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:44 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:45 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 22 12:43:45 rivo volumio[3421]: info: CURURI: music-library/NAS/U Apr 22 12:43:45 rivo volumio[3421]: info: Preload queue cleared Apr 22 12:43:45 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:45 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:45 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:46 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:46 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:46 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:46 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 22 12:43:46 rivo volumio[3421]: info: CURURI: music-library/NAS/U/2025 Apr 22 12:43:46 rivo volumio[3421]: info: Preload queue cleared Apr 22 12:43:47 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:47 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:47 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:48 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:48 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:48 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: info: Preload queue cleared Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::ClearQueue Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::stop Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::clearPlayQueue Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::saveQueue Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::volumioPushQueue Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::addQueueItems Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::addQueueItems Apr 22 12:43:49 rivo volumio[3421]: info: Preload queue cleared Apr 22 12:43:49 rivo volumio[3421]: info: Adding Item to queue: music-library/NAS/U/2025/76 Trombones Apr 22 12:43:49 rivo volumio[3421]: info: Exploding uri music-library/NAS/U/2025/76 Trombones in service mpd Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=Philip%20C.%20Chevallard/76%20Trombones%3A%20Works%20for%20Trombone/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F01.%20The%20Music%20Man%2076%20Trombones%20(arr.%20R.%20Hays%20for%20wind%20ensemble).flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/01. The Music Man 76 Trombones (arr. R. Hays for wind ensemble).flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=Kelly%20Bledsoe/76%20Trombones%3A%20Works%20for%20Trombone/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F02.%20Miss%20Trombone%20-%20A%20Slippery%20Rag.flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/02. Miss Trombone - A Slippery Rag.flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=H.%20Bruce%20Gilkes/76%20Trombones%3A%20Works%20for%20Trombone/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F03.%20Trombones%20Triumphant.flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/03. Trombones Triumphant.flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=Larry%20H.%20Lang/76%20Trombones%3A%20Works%20for%20Trombone/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F04.%20II.%20Andante%20con%20moto.flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/04. II. Andante con moto.flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=Dukes%20Of%20Dixieland/The%20Dukes%20At%20Carnegie%20Hall%20-%20Volume%2010/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F05.%2076%20Trombones.flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/05. 76 Trombones.flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=Larry%20H.%20Lang/76%20Trombones%3A%20Works%20for%20Trombone/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F05.%20III.%20Allegro.flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/05. III. Allegro.flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=Larry%20H.%20Lang/76%20Trombones%3A%20Works%20for%20Trombone/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F06.%20Trombone%20Detritus.flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/06. Trombone Detritus.flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=Auburn%20University%20Marching%20Band/The%20Auburn%20University%20Marching%20Band%202003/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F07.%2076%20Trombones.flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/07. 76 Trombones.flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=Larry%20H.%20Lang/76%20Trombones%3A%20Works%20for%20Trombone/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F07.%20Lassus%20Trombone%20(arr.%20T.%20Allums%20for%20wind%20ensemble).flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/07. Lassus Trombone (arr. T. Allums for wind ensemble).flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=Philip%20C.%20Chevallard/76%20Trombones%3A%20Works%20for%20Trombone/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F08.%20Trombone%20Rag%20(arr.%20T.%20Allums%20for%20wind%20ensemble).flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/08. Trombone Rag (arr. T. Allums for wind ensemble).flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=Lowell%20Graham/76%20Trombones%3A%20Works%20for%20Trombone/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F09.%20Bull%20Trombone.flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/09. Bull Trombone.flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=Adam%20Frey%2C%20Scott%20Hartman%20and%20Metropolitan%20Wind%20Symphony/Little%20Buckaroos/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F10.%2076%20Trombones%20(Willson%20arr.%20Amis).flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/10. 76 Trombones (Willson arr. Amis).flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=The%20London%20Trombone%20Sound/The%20London%20Trombone%20Sound/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F10.%2076%20Trombones.flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/10. 76 Trombones.flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=Lowell%20Graham/76%20Trombones%3A%20Works%20for%20Trombone/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F10.%20Tribute%20to%20the%20Trombones.flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/10. Tribute to the Trombones.flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=Solano%20Winds%20Community%20Concert%20Band/Solano%20Winds%20Community%20Concert%20Band%20-%20Stage%20%26%20Screen/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F11.%2076%20Trombones.flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/11. 76 Trombones.flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=Lowell%20Graham/76%20Trombones%3A%20Works%20for%20Trombone/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F11.%20Hot%20Trombone.flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/11. Hot Trombone.flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=Henry%20Hamilton/76%20Trombones%3A%20Works%20for%20Trombone/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F12.%20The%20Trombone%20King%20(arr.%20J.%20Paynter%20for%20wind%20ensemble).flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/12. The Trombone King (arr. J. Paynter for wind ensemble).flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=Thomas%20Rotondi%2C%20Jr./76%20Trombones%3A%20Works%20for%20Trombone/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F13.%20Trombones%2C%20Tricked%20Out%20(arr.%20P.%20Murtha%20for%20wind%20ensemble).flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/13. Trombones, Tricked Out (arr. P. Murtha for wind ensemble).flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=Canadian%20Brass/Brass%20On%20Broadway/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F14.%2076%20Trombones.flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/14. 76 Trombones.flac Apr 22 12:43:49 rivo volumio[3421]: info: ALBUMART /albumart?cacheid=584&web=University%20of%20Washington%20Husky%20Marching%20Band/University%20of%20Washington%20Husky%20Marching%20Band%20-%20Let's%20Groove/extralarge&path=%2Fmnt%2FNAS%2FU%2F2025%2F76%20Trombones%2F19.%2076%20Trombones.flac&metadata=false Apr 22 12:43:49 rivo volumio[3421]: info: URI /mnt/NAS/U/2025/76 Trombones/19. 76 Trombones.flac Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::volumioPushQueue Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::saveQueue Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::updateTrackBlock Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrackBlock Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::volumioPlay Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::play index 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::stop Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::play index undefined Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::startPlaybackTimer Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::clearAddPlayTracks NAS/U/2025/76 Trombones/01. The Music Man 76 Trombones (arr. R. Hays for wind ensemble).flac Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand stop Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand stop took 12 milliseconds Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand clear Apr 22 12:43:49 rivo volumio[3421]: info: Apr 22 12:43:49 rivo volumio[3421]: ---------------------------- MPD announces system playlist update Apr 22 12:43:49 rivo volumio[3421]: info: Ignoring MPD Status Update Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand clear took 7 milliseconds Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand add "NAS/U/2025/76 Trombones/01. The Music Man 76 Trombones (arr. R. Hays for wind ensemble).flac" Apr 22 12:43:49 rivo volumio[3421]: info: Apr 22 12:43:49 rivo volumio[3421]: ---------------------------- MPD announces system playlist update Apr 22 12:43:49 rivo volumio[3421]: info: Ignoring MPD Status Update Apr 22 12:43:49 rivo volumio[3421]: info: Apr 22 12:43:49 rivo volumio[3421]: ---------------------------- MPD announces system playlist update Apr 22 12:43:49 rivo volumio[3421]: info: Ignoring MPD Status Update Apr 22 12:43:49 rivo volumio[3421]: info: Apr 22 12:43:49 rivo volumio[3421]: ---------------------------- MPD announces system playlist update Apr 22 12:43:49 rivo volumio[3421]: info: Ignoring MPD Status Update Apr 22 12:43:49 rivo volumio[3421]: error: updateQueue error: null Apr 22 12:43:49 rivo volumio[3421]: info: Apr 22 12:43:49 rivo volumio[3421]: ---------------------------- MPD announces system playlist update Apr 22 12:43:49 rivo volumio[3421]: info: Ignoring MPD Status Update Apr 22 12:43:49 rivo volumio[3421]: info: ------------------------------ 16ms Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand add "NAS/U/2025/76 Trombones/01. The Music Man 76 Trombones (arr. R. Hays for wind ensemble).flac" took 14 milliseconds Apr 22 12:43:49 rivo volumio[3421]: info: ------------------------------ 13ms Apr 22 12:43:49 rivo volumio[3421]: info: ------------------------------ 12ms Apr 22 12:43:49 rivo volumio[3421]: info: ------------------------------ 11ms Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand play Apr 22 12:43:49 rivo volumio[3421]: info: Apr 22 12:43:49 rivo volumio[3421]: ---------------------------- MPD announces system playlist update Apr 22 12:43:49 rivo volumio[3421]: info: Ignoring MPD Status Update Apr 22 12:43:49 rivo volumio[3421]: info: Apr 22 12:43:49 rivo volumio[3421]: ---------------------------- MPD announces system playlist update Apr 22 12:43:49 rivo volumio[3421]: info: Ignoring MPD Status Update Apr 22 12:43:49 rivo volumio[3421]: info: Apr 22 12:43:49 rivo volumio[3421]: ---------------------------- MPD announces system playlist update Apr 22 12:43:49 rivo volumio[3421]: info: Ignoring MPD Status Update Apr 22 12:43:49 rivo volumio[3421]: info: ------------------------------ 17ms Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand play took 11 milliseconds Apr 22 12:43:49 rivo volumio[3421]: info: ------------------------------ 11ms Apr 22 12:43:49 rivo volumio[3421]: info: ------------------------------ 9ms Apr 22 12:43:49 rivo volumio[3421]: info: ------------------------------ 8ms Apr 22 12:43:49 rivo volumio[3421]: info: Apr 22 12:43:49 rivo volumio[3421]: ---------------------------- MPD announces state update: player Apr 22 12:43:49 rivo volumio[3421]: info: ControllerMpd::getState Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand status Apr 22 12:43:49 rivo volumio[3421]: info: Apr 22 12:43:49 rivo volumio[3421]: ---------------------------- MPD announces state update: player Apr 22 12:43:49 rivo volumio[3421]: info: ControllerMpd::getState Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand status Apr 22 12:43:49 rivo volumio[3421]: info: Apr 22 12:43:49 rivo volumio[3421]: ---------------------------- MPD announces state update: player Apr 22 12:43:49 rivo volumio[3421]: info: ControllerMpd::getState Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand status Apr 22 12:43:49 rivo volumio[3421]: info: Apr 22 12:43:49 rivo volumio[3421]: ---------------------------- MPD announces state update: player Apr 22 12:43:49 rivo volumio[3421]: info: ControllerMpd::getState Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand status Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand status took 50 milliseconds Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::parseState Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 12:43:49 rivo volumio[3421]: info: Apr 22 12:43:49 rivo volumio[3421]: ---------------------------- MPD announces state update: player Apr 22 12:43:49 rivo volumio[3421]: info: ControllerMpd::getState Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand status Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand status took 56 milliseconds Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand status took 55 milliseconds Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand status took 52 milliseconds Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::parseState Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::parseState Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::parseState Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 12:43:49 rivo volumio[3421]: info: Apr 22 12:43:49 rivo volumio[3421]: ---------------------------- MPD announces state update: player Apr 22 12:43:49 rivo volumio[3421]: info: ControllerMpd::getState Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand status Apr 22 12:43:49 rivo volumio[3421]: info: Apr 22 12:43:49 rivo volumio[3421]: ---------------------------- MPD announces state update: player Apr 22 12:43:49 rivo volumio[3421]: info: ControllerMpd::getState Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand status Apr 22 12:43:49 rivo volumio[3421]: info: Apr 22 12:43:49 rivo volumio[3421]: ---------------------------- MPD announces state update: player Apr 22 12:43:49 rivo volumio[3421]: info: ControllerMpd::getState Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand status Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand playlistinfo took 22 milliseconds Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand status took 19 milliseconds Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand playlistinfo took 14 milliseconds Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand status took 12 milliseconds Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand status took 9 milliseconds Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand status took 7 milliseconds Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::parseTrackInfo Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::parseState Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::parseTrackInfo Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::parseTrackInfo Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::parseTrackInfo Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::parseState Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::parseState Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::parseState Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 12:43:49 rivo volumio[3421]: info: ControllerMpd::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::servicePushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Music Man (arr. R. Hays for wind ensemble): The Music Man: 76 Trombones (arr. R. Hays for wind ensemble)","artist":"Philip C. Chevallard","album":"76 Trombones: Works for Trombone","uri":"NAS/U/2025/76 Trombones/01. The Music Man 76 Trombones (arr. R. Hays for wind ensemble).flac","trackType":"flac"} Apr 22 12:43:49 rivo volumio[3421]: verbose: CURRENT POSITION 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::syncState stateService play Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::syncState currentStatus stop Apr 22 12:43:49 rivo volumio[3421]: info: ControllerMpd::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::servicePushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Music Man (arr. R. Hays for wind ensemble): The Music Man: 76 Trombones (arr. R. Hays for wind ensemble)","artist":"Philip C. Chevallard","album":"76 Trombones: Works for Trombone","uri":"NAS/U/2025/76 Trombones/01. The Music Man 76 Trombones (arr. R. Hays for wind ensemble).flac","trackType":"flac"} Apr 22 12:43:49 rivo volumio[3421]: verbose: CURRENT POSITION 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::syncState stateService play Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::syncState currentStatus play Apr 22 12:43:49 rivo volumio[3421]: info: Received an update from plugin. extracting info from payload Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::volumioPushState Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output update for this device Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::volumioPushState Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output update for this device Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output Apr 22 12:43:49 rivo volumio[3421]: info: ControllerMpd::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::servicePushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: verbose: STATE SERVICE {"status":"play","position":0,"seek":183,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"208 Kbps","isStreaming":false,"title":"The Music Man (arr. R. Hays for wind ensemble): The Music Man: 76 Trombones (arr. R. Hays for wind ensemble)","artist":"Philip C. Chevallard","album":"76 Trombones: Works for Trombone","uri":"NAS/U/2025/76 Trombones/01. The Music Man 76 Trombones (arr. R. Hays for wind ensemble).flac","trackType":"flac"} Apr 22 12:43:49 rivo volumio[3421]: verbose: CURRENT POSITION 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::syncState stateService play Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::syncState currentStatus play Apr 22 12:43:49 rivo volumio[3421]: info: Received an update from plugin. extracting info from payload Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::volumioPushState Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output update for this device Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::volumioPushState Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output update for this device Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output Apr 22 12:43:49 rivo volumio[3421]: info: ControllerMpd::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::servicePushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: verbose: STATE SERVICE {"status":"play","position":0,"seek":205,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"223 Kbps","isStreaming":false,"title":"The Music Man (arr. R. Hays for wind ensemble): The Music Man: 76 Trombones (arr. R. Hays for wind ensemble)","artist":"Philip C. Chevallard","album":"76 Trombones: Works for Trombone","uri":"NAS/U/2025/76 Trombones/01. The Music Man 76 Trombones (arr. R. Hays for wind ensemble).flac","trackType":"flac"} Apr 22 12:43:49 rivo volumio[3421]: verbose: CURRENT POSITION 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::syncState stateService play Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::syncState currentStatus play Apr 22 12:43:49 rivo volumio[3421]: info: Received an update from plugin. extracting info from payload Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::volumioPushState Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output update for this device Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::volumioPushState Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output update for this device Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output Apr 22 12:43:49 rivo volumio[3421]: info: ------------------------------ 179ms Apr 22 12:43:49 rivo volumio[3421]: info: ------------------------------ 273ms Apr 22 12:43:49 rivo volumio[3421]: info: ------------------------------ 273ms Apr 22 12:43:49 rivo volumio[3421]: info: ------------------------------ 272ms Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand playlistinfo took 199 milliseconds Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand playlistinfo took 198 milliseconds Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand playlistinfo took 202 milliseconds Apr 22 12:43:49 rivo volumio[3421]: info: sendMpdCommand playlistinfo took 214 milliseconds Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::parseTrackInfo Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::parseTrackInfo Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::parseTrackInfo Apr 22 12:43:49 rivo volumio[3421]: verbose: ControllerMpd::parseTrackInfo Apr 22 12:43:49 rivo volumio[3421]: info: ControllerMpd::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::servicePushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"772 Kbps","isStreaming":false,"title":"The Music Man (arr. R. Hays for wind ensemble): The Music Man: 76 Trombones (arr. R. Hays for wind ensemble)","artist":"Philip C. Chevallard","album":"76 Trombones: Works for Trombone","uri":"NAS/U/2025/76 Trombones/01. The Music Man 76 Trombones (arr. R. Hays for wind ensemble).flac","trackType":"flac"} Apr 22 12:43:49 rivo volumio[3421]: verbose: CURRENT POSITION 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::syncState stateService play Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::syncState currentStatus play Apr 22 12:43:49 rivo volumio[3421]: info: Received an update from plugin. extracting info from payload Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::volumioPushState Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output update for this device Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::volumioPushState Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output update for this device Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output Apr 22 12:43:49 rivo volumio[3421]: info: ControllerMpd::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::servicePushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"772 Kbps","isStreaming":false,"title":"The Music Man (arr. R. Hays for wind ensemble): The Music Man: 76 Trombones (arr. R. Hays for wind ensemble)","artist":"Philip C. Chevallard","album":"76 Trombones: Works for Trombone","uri":"NAS/U/2025/76 Trombones/01. The Music Man 76 Trombones (arr. R. Hays for wind ensemble).flac","trackType":"flac"} Apr 22 12:43:49 rivo volumio[3421]: verbose: CURRENT POSITION 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::syncState stateService play Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::syncState currentStatus play Apr 22 12:43:49 rivo volumio[3421]: info: Received an update from plugin. extracting info from payload Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::volumioPushState Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output update for this device Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::volumioPushState Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output update for this device Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output Apr 22 12:43:49 rivo volumio[3421]: info: ControllerMpd::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::servicePushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"772 Kbps","isStreaming":false,"title":"The Music Man (arr. R. Hays for wind ensemble): The Music Man: 76 Trombones (arr. R. Hays for wind ensemble)","artist":"Philip C. Chevallard","album":"76 Trombones: Works for Trombone","uri":"NAS/U/2025/76 Trombones/01. The Music Man 76 Trombones (arr. R. Hays for wind ensemble).flac","trackType":"flac"} Apr 22 12:43:49 rivo volumio[3421]: verbose: CURRENT POSITION 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::syncState stateService play Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::syncState currentStatus play Apr 22 12:43:49 rivo volumio[3421]: info: Received an update from plugin. extracting info from payload Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::volumioPushState Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output update for this device Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::volumioPushState Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output update for this device Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output Apr 22 12:43:49 rivo volumio[3421]: info: ControllerMpd::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::servicePushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"772 Kbps","isStreaming":false,"title":"The Music Man (arr. R. Hays for wind ensemble): The Music Man: 76 Trombones (arr. R. Hays for wind ensemble)","artist":"Philip C. Chevallard","album":"76 Trombones: Works for Trombone","uri":"NAS/U/2025/76 Trombones/01. The Music Man 76 Trombones (arr. R. Hays for wind ensemble).flac","trackType":"flac"} Apr 22 12:43:49 rivo volumio[3421]: verbose: CURRENT POSITION 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::syncState stateService play Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::syncState currentStatus play Apr 22 12:43:49 rivo volumio[3421]: info: Received an update from plugin. extracting info from payload Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::volumioPushState Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output update for this device Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output Apr 22 12:43:49 rivo volumio[3421]: info: CoreStateMachine::pushState Apr 22 12:43:49 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:49 rivo volumio[3421]: info: CoreCommandRouter::volumioPushState Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output update for this device Apr 22 12:43:49 rivo volumio[3421]: info: MRS: Pushing multiroomSync output Apr 22 12:43:50 rivo volumio[3421]: info: ------------------------------ 521ms Apr 22 12:43:50 rivo volumio[3421]: info: ------------------------------ 512ms Apr 22 12:43:50 rivo volumio[3421]: info: ------------------------------ 511ms Apr 22 12:43:50 rivo volumio[3421]: info: ------------------------------ 510ms Apr 22 12:43:50 rivo volumio[3421]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.PtlvY5Ib0ORipAf5wU4wXwp68EW2.d7219eebd2e3f4026ec045151d19954a.state.mute' Apr 22 12:43:50 rivo volumio[3421]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.PtlvY5Ib0ORipAf5wU4wXwp68EW2.d7219eebd2e3f4026ec045151d19954a.state.mute' Apr 22 12:43:50 rivo volumio[3421]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.PtlvY5Ib0ORipAf5wU4wXwp68EW2.d7219eebd2e3f4026ec045151d19954a.state.mute' Apr 22 12:43:50 rivo volumio[3421]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.PtlvY5Ib0ORipAf5wU4wXwp68EW2.d7219eebd2e3f4026ec045151d19954a.state.mute' Apr 22 12:43:50 rivo volumio[3421]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.PtlvY5Ib0ORipAf5wU4wXwp68EW2.d7219eebd2e3f4026ec045151d19954a.state.mute' Apr 22 12:43:50 rivo volumio[3421]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.PtlvY5Ib0ORipAf5wU4wXwp68EW2.d7219eebd2e3f4026ec045151d19954a.state.mute' Apr 22 12:43:50 rivo volumio[3421]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.PtlvY5Ib0ORipAf5wU4wXwp68EW2.d7219eebd2e3f4026ec045151d19954a.state.mute' Apr 22 12:43:50 rivo volumio[3421]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.PtlvY5Ib0ORipAf5wU4wXwp68EW2.d7219eebd2e3f4026ec045151d19954a.state.mute' Apr 22 12:43:50 rivo volumio[3421]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.PtlvY5Ib0ORipAf5wU4wXwp68EW2.d7219eebd2e3f4026ec045151d19954a.state.mute' Apr 22 12:43:50 rivo volumio[3421]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.PtlvY5Ib0ORipAf5wU4wXwp68EW2.d7219eebd2e3f4026ec045151d19954a.state.mute' Apr 22 12:43:50 rivo volumio[3421]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.PtlvY5Ib0ORipAf5wU4wXwp68EW2.d7219eebd2e3f4026ec045151d19954a.state.mute' Apr 22 12:43:50 rivo volumio[3421]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.PtlvY5Ib0ORipAf5wU4wXwp68EW2.d7219eebd2e3f4026ec045151d19954a.state.mute' Apr 22 12:43:50 rivo volumio[3421]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.PtlvY5Ib0ORipAf5wU4wXwp68EW2.d7219eebd2e3f4026ec045151d19954a.state.mute' Apr 22 12:43:50 rivo volumio[3421]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.PtlvY5Ib0ORipAf5wU4wXwp68EW2.d7219eebd2e3f4026ec045151d19954a.state.mute' Apr 22 12:43:50 rivo volumio[3421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Apr 22 12:43:50 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:43:50 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:43:50 rivo volumio[3421]: info: Signalling Playback active due to playback status change Apr 22 12:43:50 rivo volumio[3421]: info: MCU Signalled Playback Active Apr 22 12:43:51 rivo volumio[3421]: info: Executing endpoint metavolumio Apr 22 12:43:51 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 22 12:43:51 rivo volumio[3421]: info: Executing endpoint metavolumio Apr 22 12:43:51 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 22 12:43:51 rivo volumio[3421]: info: Executing endpoint metavolumio Apr 22 12:43:51 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 22 12:43:52 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 22 12:43:52 rivo volumio[3421]: info: CURURI: music-library Apr 22 12:43:52 rivo volumio[3421]: info: Preload queue cleared Apr 22 12:43:54 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 22 12:43:54 rivo volumio[3421]: info: CURURI: music-library/NAS Apr 22 12:43:54 rivo volumio[3421]: info: Preload queue cleared Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 12:44:04 rivo volumio[3421]: info: Retrieving Cloud Streaming UI Apr 22 12:44:04 rivo volumio[3421]: info: Getting Tidal Cloud Configuration Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 12:44:04 rivo volumio[3421]: info: Getting Qobuz Cloud Configuration Apr 22 12:44:04 rivo volumio[3421]: info: Asking plugin for UI Config Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 12:44:04 rivo volumio[3421]: info: Getting Spotify Cloud Configuration Apr 22 12:44:04 rivo volumio[3421]: info: Asking plugin for UI Config Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 12:44:04 rivo volumio[3421]: info: Saving Spotify Acccount Apr 22 12:44:04 rivo volumio[3421]: info: Got it Apr 22 12:44:04 rivo volumio[3421]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 22 12:44:04 rivo volumio[3421]: info: Got Tidal Cloud Configuration Apr 22 12:44:04 rivo volumio[3421]: info: Got it Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::volumioGetBrowseSources Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::volumioGetBrowseSources Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::volumioGetBrowseSources Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 12:44:04 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 22 12:44:08 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 12:44:12 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 12:44:13 rivo volumio[3421]: info: CALLMETHOD: miscellanea my_music updateMusicLibraryBrowseSourcesVisibility [object Object] Apr 22 12:44:13 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibraryBrowseSourcesVisibility Apr 22 12:44:13 rivo volumio[3421]: info: CoreCommandRouter::volumioUpdateToBrowseSources Apr 22 12:44:13 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 22 12:44:13 rivo volumio[3421]: Cannot find translation for source QOBUZ Apr 22 12:44:13 rivo volumio[3421]: Cannot find translation for source TIDAL Apr 22 12:44:16 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 12:44:19 rivo volumio[3421]: info: CALLMETHOD: miscellanea my_music updateMusicLibrarySupersearchSources [object Object] Apr 22 12:44:19 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibrarySupersearchSources Apr 22 12:44:20 rivo volumio[3421]: info: CoreCommandRouter::volumioGetBrowseSources Apr 22 12:44:20 rivo volumio[3421]: info: CoreCommandRouter::volumioGetBrowseSources Apr 22 12:44:20 rivo volumio[3421]: info: CoreCommandRouter::updateGlobalSearchableBrowseSources Apr 22 12:44:20 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 12:44:23 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 22 12:44:23 rivo volumio[3421]: info: CURURI: music-library Apr 22 12:44:26 rivo volumio[3421]: info: Preload queue cleared Apr 22 12:44:27 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 22 12:44:27 rivo volumio[3421]: info: CURURI: music-library/NAS Apr 22 12:44:27 rivo volumio[3421]: info: Preload queue cleared Apr 22 12:44:29 rivo volumio[3959]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 22 12:44:29 rivo volumio[3421]: info: CoreCommandRouter::volumioGetState Apr 22 12:44:29 rivo volumio[3421]: info: CorePlayQueue::getTrack 0 Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 12:44:31 rivo volumio[3421]: info: Retrieving Cloud Streaming UI Apr 22 12:44:31 rivo volumio[3421]: info: Getting Tidal Cloud Configuration Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 12:44:31 rivo volumio[3421]: info: Getting Qobuz Cloud Configuration Apr 22 12:44:31 rivo volumio[3421]: info: Asking plugin for UI Config Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 12:44:31 rivo volumio[3421]: info: Getting Spotify Cloud Configuration Apr 22 12:44:31 rivo volumio[3421]: info: Asking plugin for UI Config Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 12:44:31 rivo volumio[3421]: info: Saving Spotify Acccount Apr 22 12:44:31 rivo volumio[3421]: info: Got it Apr 22 12:44:31 rivo volumio[3421]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 22 12:44:31 rivo volumio[3421]: info: Got Tidal Cloud Configuration Apr 22 12:44:31 rivo volumio[3421]: info: Got it Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::volumioGetBrowseSources Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::volumioGetBrowseSources Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::volumioGetBrowseSources Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 12:44:31 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 22 12:44:32 rivo volumio[3421]: info: Executing endpoint metavolumio Apr 22 12:44:32 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 22 12:44:32 rivo volumio[3421]: info: Executing endpoint metavolumio Apr 22 12:44:32 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 22 12:44:32 rivo volumio[3421]: info: Executing endpoint metavolumio Apr 22 12:44:32 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 22 12:44:35 rivo volumio[3421]: Upnp client error: Error: This socket has been ended by the other party Apr 22 12:44:35 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 12:44:36 rivo volumio[3421]: error: Failed request for metavolumio API Apr 22 12:44:39 rivo volumio[3959]: ...........................................................................................................................++++ Apr 22 12:44:39 rivo volumio[3421]: Upnp client error: Error: This socket has been ended by the other party Apr 22 12:44:39 rivo volumio[3959]: ...++++ Apr 22 12:44:39 rivo volumio[3959]: e is 65537 (0x010001) Apr 22 12:44:39 rivo volumio[3959]: writing RSA key Apr 22 12:44:39 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 12:44:43 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 12:44:43 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 22 12:44:43 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 22 12:44:45 rivo volumio[3421]: Upnp client error: Error: This socket has been ended by the other party Apr 22 12:44:47 rivo volumio[3421]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 22 12:44:49 rivo volumio[3421]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 12:44:49 rivo volumio[3421]: Error: dns service error: unknown Apr 22 12:44:49 rivo volumio[3421]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Apr 22 12:44:49 rivo volumio[3421]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Apr 22 12:44:49 rivo volumio[3421]: errorCode: -65537 Apr 22 12:44:49 rivo volumio[3421]: } Apr 22 12:44:49 rivo volumio[3421]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 12:44:49 rivo sudo[5070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-22 12:43 Apr 22 12:44:49 rivo sudo[5070]: 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="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 08:01:39 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="71b19b3a3eb3d257ef93eee35eba5cf1"