-- Logs begin at Thu 2024-04-25 19:07:42 CEST, end at Thu 2024-04-25 19:10:55 CEST. -- Apr 25 19:09:01 rivo volumio[4995]: info: Starting UPNP Playback Apr 25 19:09:01 rivo volumio[4995]: info: Preparing playback through UPNP Apr 25 19:09:01 rivo volumio[4995]: info: Consume mode Apr 25 19:09:01 rivo volumio[4995]: info: CoreCommandRouter::volumioGetState Apr 25 19:09:01 rivo volumio[4995]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 25 19:09:01 rivo volumio[4995]: error: updateQueue error: null Apr 25 19:09:02 rivo volumio[4995]: info: Starting UPNP Playback Apr 25 19:09:02 rivo volumio[4995]: info: Preparing playback through UPNP Apr 25 19:09:02 rivo volumio[4995]: info: Consume mode Apr 25 19:09:02 rivo volumio[4995]: info: CoreCommandRouter::volumioGetState Apr 25 19:09:02 rivo volumio[4995]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 25 19:09:20 rivo kernel: usb 1-1.4.2: USB disconnect, device number 5 Apr 25 19:09:20 rivo thd[4535]: Error reading device '/dev/input/event5' Apr 25 19:09:20 rivo volumio[4995]: info: Apr 25 19:09:20 rivo volumio[4995]: ---------------------------- USB Audio Device Detached Apr 25 19:09:20 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 25 19:09:20 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 19:09:20 rivo volumio[4995]: No protocol specified Apr 25 19:09:20 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:20 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 25 19:09:20 rivo volumio[4995]: info: Stopping RAAT Plugin Apr 25 19:09:20 rivo sudo[7256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop raat-daemon.service Apr 25 19:09:20 rivo sudo[7256]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:20 rivo systemd[1]: Stopping RAAT DAEMON... Apr 25 19:09:20 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 25 19:09:20 rivo systemd[1]: raat-daemon.service: Succeeded. Apr 25 19:09:20 rivo systemd[1]: Stopped RAAT DAEMON. Apr 25 19:09:20 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 25 19:09:20 rivo sudo[7256]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:20 rivo volumio[4995]: info: Raat Daemon stopped successfully Apr 25 19:09:23 rivo kernel: usb 1-1.4.2: new high-speed USB device number 6 using xhci-hcd Apr 25 19:09:28 rivo kernel: usb 1-1.4.2: new high-speed USB device number 7 using xhci-hcd Apr 25 19:09:28 rivo kernel: usb 1-1.4.2: New USB device found, idVendor=2d87, idProduct=000a Apr 25 19:09:28 rivo kernel: usb 1-1.4.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Apr 25 19:09:28 rivo kernel: usb 1-1.4.2: Product: Cayin iDAC-6 Apr 25 19:09:28 rivo kernel: usb 1-1.4.2: Manufacturer: Cayin Apr 25 19:09:28 rivo kernel: usb 1-1.4.2: Unsupported device Apr 25 19:09:28 rivo kernel: input: Cayin Cayin iDAC-6 as /devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.4/1-1.4.2/1-1.4.2:1.3/0003:2D87:000A.0002/input/input7 Apr 25 19:09:28 rivo kernel: hid-generic 0003:2D87:000A.0002: input,hidraw0: USB HID v1.10 Device [Cayin Cayin iDAC-6] on usb-xhci-hcd.0.auto-1.4.2/input3 Apr 25 19:09:28 rivo volumio[4995]: info: Apr 25 19:09:28 rivo volumio[4995]: ---------------------------- USB Audio Device Attached Apr 25 19:09:28 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 25 19:09:28 rivo volumio[4995]: No protocol specified Apr 25 19:09:28 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:28 rivo volumio[4995]: info: CoreCommandRouter::Close All Modals sent Apr 25 19:09:28 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 19:09:28 rivo systemd-udevd[7286]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 25 19:09:28 rivo volumio[4995]: No protocol specified Apr 25 19:09:28 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:28 rivo volumio[4995]: No protocol specified Apr 25 19:09:28 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:28 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 19:09:28 rivo volumio[4995]: No protocol specified Apr 25 19:09:28 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:28 rivo volumio[4995]: info: Setting mixer Cayin iDAC-6 for card Cayin iDAC-6 Apr 25 19:09:28 rivo volumio[4995]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Apr 25 19:09:28 rivo vtcs[6941]: [2024-04-25 19:09:28.965] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Apr 25 19:09:28 rivo vtcs[6941]: [2024-04-25 19:09:28.966] [tisoc] [error] [SpkconServer.cpp:382] recv error. client fd=7 errorno=104 error=Connection reset by peer Apr 25 19:09:28 rivo vtcs[6941]: [2024-04-25 19:09:28.966] [tisoc] [error] [SpkconServer.cpp:377] recv error. socket disconnected Apr 25 19:09:29 rivo sudo[7305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 19:09:29 rivo sudo[7305]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:29 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Apr 25 19:09:29 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 25 19:09:29 rivo systemd[1]: vtcs.service: Succeeded. Apr 25 19:09:29 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Apr 25 19:09:29 rivo sudo[7305]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:29 rivo volumio[4995]: No protocol specified Apr 25 19:09:29 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 25 19:09:29 rivo volumio[4995]: info: Updating Volume Controller Parameters: Device: 5 Name: Cayin iDAC-6 Mixer: Cayin iDAC-6 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 25 19:09:29 rivo volumio[4995]: info: Disabling external Volume Control Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:09:29 rivo volumio[4995]: info: Preparing to generate the ALSA configuration file Apr 25 19:09:29 rivo volumio[4995]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 25 19:09:29 rivo volumio[4995]: info: Reading ALSA contributions from plugins. Apr 25 19:09:29 rivo sudo[7312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 19:09:29 rivo sudo[7312]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:29 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 25 19:09:29 rivo volumio[4995]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Apr 25 19:09:29 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:29 rivo sudo[7312]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:29 rivo volumio[4995]: No protocol specified Apr 25 19:09:29 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 19:09:29 rivo volumio[4995]: No protocol specified Apr 25 19:09:29 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:09:29 rivo volumio[4995]: info: Not Reporting Auto name since its the default one Apr 25 19:09:29 rivo volumio[4995]: info: VolumeController:: Volume=undefined Mute =false Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::volumioGetState Apr 25 19:09:29 rivo volumio[4995]: info: CoreStateMachine::pushState Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::volumioPushState Apr 25 19:09:29 rivo volumio[4995]: info: MRS: Pushing multiroomSync output update for this device Apr 25 19:09:29 rivo volumio[4995]: info: MRS: Pushing multiroomSync output Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:09:29 rivo volumio[4995]: info: Not Reporting Auto name since its the default one Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 25 19:09:29 rivo volumio[4995]: No protocol specified Apr 25 19:09:29 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:29 rivo volumio[4995]: info: Asound.conf file unchanged, so no further update is needed Apr 25 19:09:29 rivo volumio[4995]: info: Output device has changed, restarting MPD Apr 25 19:09:29 rivo sudo[7332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 25 19:09:29 rivo sudo[7332]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:29 rivo sudo[7335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 19:09:29 rivo volumio[4995]: info: Output device has changed, restarting Shairport Sync Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:29 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 19:09:29 rivo sudo[7335]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:29 rivo sudo[7335]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:29 rivo sudo[7332]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:30 rivo sudo[7337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 19:09:30 rivo sudo[7337]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:30 rivo sudo[7340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 25 19:09:30 rivo sudo[7340]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:30 rivo systemd[1]: Stopping Music Player Daemon... Apr 25 19:09:30 rivo volumio[4995]: No protocol specified Apr 25 19:09:30 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:30 rivo volumio[4995]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 19:09:30 rivo volumio[4995]: No protocol specified Apr 25 19:09:30 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:30 rivo volumio[4995]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 25 19:09:30 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:30 rivo systemd[1]: Started RAAT DAEMON. Apr 25 19:09:30 rivo sudo[7340]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:30 rivo systemd[1]: mpd.service: Succeeded. Apr 25 19:09:30 rivo systemd[1]: Stopped Music Player Daemon. Apr 25 19:09:30 rivo volumio[4995]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 25 19:09:30 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:30 rivo systemd[1]: Starting Music Player Daemon... Apr 25 19:09:30 rivo sudo[7380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 19:09:30 rivo sudo[7380]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:30 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 25 19:09:30 rivo sudo[7388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 19:09:30 rivo sudo[7388]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:30 rivo sudo[7380]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:30 rivo sudo[7388]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:30 rivo volumio[4995]: No protocol specified Apr 25 19:09:30 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:30 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 25 19:09:30 rivo sudo[7439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 25 19:09:30 rivo sudo[7439]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:30 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 25 19:09:30 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 25 19:09:30 rivo sudo[7439]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:30 rivo volumio[4995]: info: MPD Permissions set Apr 25 19:09:30 rivo volumio[4995]: info: CoreCommandRouter::volumioGetState Apr 25 19:09:30 rivo volumio[4995]: info: Raat Daemon started successfully Apr 25 19:09:30 rivo volumio[4995]: info: Executing endpoint restartRAATSocket Apr 25 19:09:30 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 25 19:09:30 rivo sudo[7441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 25 19:09:30 rivo sudo[7441]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:30 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:30 rivo systemd[1]: Stopping RAAT DAEMON... Apr 25 19:09:30 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 25 19:09:30 rivo systemd[1]: raat-daemon.service: Succeeded. Apr 25 19:09:30 rivo systemd[1]: Stopped RAAT DAEMON. Apr 25 19:09:30 rivo systemd[1]: Started RAAT DAEMON. Apr 25 19:09:30 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 19:09:30 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 25 19:09:30 rivo sudo[7441]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:30 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:30 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 25 19:09:30 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 25 19:09:30 rivo volumio[4995]: info: Raat Daemon started successfully Apr 25 19:09:30 rivo volumio[4995]: info: Starting Shairport Sync Apr 25 19:09:30 rivo volumio[4995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 15 Apr 25 19:09:30 rivo volumio[4995]: info: Executing endpoint restartRAATSocket Apr 25 19:09:30 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 25 19:09:30 rivo sudo[7483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 19:09:30 rivo sudo[7483]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:30 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 25 19:09:30 rivo systemd[1]: shairport-sync.service: Succeeded. Apr 25 19:09:30 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 25 19:09:30 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:30 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:30 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:30 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:30 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:30 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:09:30 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 25 19:09:30 rivo volumio[4995]: info: Not Reporting Auto name since its the default one Apr 25 19:09:30 rivo volumio[4995]: info: Preparing to generate the ALSA configuration file Apr 25 19:09:30 rivo sudo[7483]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:30 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 25 19:09:30 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 25 19:09:30 rivo volumio[4995]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 25 19:09:30 rivo volumio[4995]: info: Reading ALSA contributions from plugins. Apr 25 19:09:30 rivo volumio[4995]: info: Shairport-Sync Started Apr 25 19:09:30 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 25 19:09:30 rivo volumio[4995]: No protocol specified Apr 25 19:09:30 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:30 rivo volumio[4995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 14 Apr 25 19:09:30 rivo sudo[7547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 25 19:09:30 rivo sudo[7547]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:30 rivo sudo[7547]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:30 rivo volumio[4995]: info: Asound.conf file written Apr 25 19:09:31 rivo sudo[7549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 25 19:09:31 rivo sudo[7549]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:31 rivo systemd[1]: Stopping RAAT DAEMON... Apr 25 19:09:31 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 25 19:09:31 rivo systemd[1]: raat-daemon.service: Succeeded. Apr 25 19:09:31 rivo systemd[1]: Stopped RAAT DAEMON. Apr 25 19:09:31 rivo sudo[7552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 25 19:09:31 rivo sudo[7552]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:31 rivo systemd[1]: Started RAAT DAEMON. Apr 25 19:09:31 rivo sudo[7552]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:31 rivo sudo[7549]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:31 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Apr 25 19:09:31 rivo kernel: Err: can not set rate to 4274967296! Apr 25 19:09:31 rivo kernel: Range[3920000 - 500000000] Apr 25 19:09:31 rivo kernel: out of value, fixed it Apr 25 19:09:31 rivo kernel: id=0 set inskew=0 Apr 25 19:09:31 rivo volumio[4995]: No protocol specified Apr 25 19:09:31 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:31 rivo volumio[4995]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Apr 25 19:09:31 rivo volumio[4995]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Apr 25 19:09:31 rivo volumio[4995]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Apr 25 19:09:31 rivo volumio[4995]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Apr 25 19:09:31 rivo volumio[4995]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Cayin Internal Clock Validity:0' : Operation not permitted Apr 25 19:09:31 rivo volumio[4995]: info: Output device has changed, restarting MPD Apr 25 19:09:31 rivo sudo[7610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 19:09:31 rivo sudo[7610]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:31 rivo volumio[4995]: info: Output device has changed, restarting Shairport Sync Apr 25 19:09:31 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:31 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 19:09:31 rivo sudo[7610]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:31 rivo sudo[7612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 19:09:31 rivo sudo[7612]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:31 rivo volumio[4995]: No protocol specified Apr 25 19:09:31 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:31 rivo volumio[4995]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 19:09:31 rivo volumio[4995]: No protocol specified Apr 25 19:09:31 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:31 rivo volumio[4995]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 25 19:09:31 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 25 19:09:31 rivo systemd[1]: mpd.service: Succeeded. Apr 25 19:09:31 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:31 rivo systemd[1]: Stopped Music Player Daemon. Apr 25 19:09:31 rivo systemd[1]: Starting Music Player Daemon... Apr 25 19:09:31 rivo volumio[4995]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Apr 25 19:09:31 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:31 rivo sudo[7630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 19:09:31 rivo sudo[7630]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:31 rivo sudo[7630]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:31 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 25 19:09:31 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 25 19:09:31 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 25 19:09:31 rivo sudo[7634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 25 19:09:31 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 25 19:09:31 rivo sudo[7634]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:31 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 25 19:09:31 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 25 19:09:31 rivo volumio[4995]: info: MPD Permissions set Apr 25 19:09:31 rivo volumio[4995]: info: Raat Daemon started successfully Apr 25 19:09:31 rivo volumio[4995]: info: Executing endpoint restartRAATSocket Apr 25 19:09:31 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 25 19:09:31 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:31 rivo sudo[7634]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:31 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 19:09:31 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 25 19:09:31 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:31 rivo volumio[4995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 15 Apr 25 19:09:31 rivo volumio[4995]: info: Starting Shairport Sync Apr 25 19:09:31 rivo sudo[7662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 19:09:31 rivo sudo[7662]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:31 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 25 19:09:31 rivo systemd[1]: shairport-sync.service: Succeeded. Apr 25 19:09:31 rivo volumio[4995]: info: Executing endpoint metavolumio Apr 25 19:09:31 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 19:09:31 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 25 19:09:31 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 25 19:09:31 rivo sudo[7662]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:31 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 25 19:09:31 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 25 19:09:31 rivo volumio[4995]: info: Shairport-Sync Started Apr 25 19:09:31 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:31 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:31 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:31 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:31 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:09:31 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:09:31 rivo volumio[4995]: info: Not Reporting Auto name since its the default one Apr 25 19:09:31 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 25 19:09:31 rivo volumio[4995]: No protocol specified Apr 25 19:09:31 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:31 rivo sudo[7705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 25 19:09:31 rivo sudo[7705]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:32 rivo sudo[7705]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:32 rivo sudo[7707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 25 19:09:32 rivo sudo[7707]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:32 rivo systemd[1]: Stopping RAAT DAEMON... Apr 25 19:09:32 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Apr 25 19:09:32 rivo systemd[1]: raat-daemon.service: Succeeded. Apr 25 19:09:32 rivo systemd[1]: Stopped RAAT DAEMON. Apr 25 19:09:32 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 25 19:09:32 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 25 19:09:32 rivo systemd[1]: Started RAAT DAEMON. Apr 25 19:09:32 rivo sudo[7707]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:32 rivo volumio[4995]: info: TidalConnect service stoped! Apr 25 19:09:32 rivo volumio[4995]: info: Raat Daemon started successfully Apr 25 19:09:32 rivo volumio[4995]: info: Executing endpoint restartRAATSocket Apr 25 19:09:32 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 25 19:09:32 rivo volumio[4995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 15 Apr 25 19:09:32 rivo volumio[4995]: info: TidalConnect service stoped! Apr 25 19:09:32 rivo volumio[4995]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 25 19:09:32 rivo volumio[4995]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 25 19:09:32 rivo sudo[7749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 25 19:09:32 rivo sudo[7749]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:32 rivo systemd[1]: Started Volumio Tidal Connect Service. Apr 25 19:09:32 rivo sudo[7749]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:32 rivo volumio[4995]: info: Executing endpoint tc_getconfig Apr 25 19:09:32 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 25 19:09:32 rivo vtcs[7751]: STARTING TidalConnect services, version: 1.3.0.19 Apr 25 19:09:32 rivo vtcs[7751]: STARTED TidalConnect services. Apr 25 19:09:32 rivo volumio[4995]: info: Executing endpoint tc_connect Apr 25 19:09:32 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 25 19:09:32 rivo volumio[4995]: info: Connecting to TidalConnect Apr 25 19:09:32 rivo volumio[4995]: info: CoreCommandRouter::servicePushState Apr 25 19:09:32 rivo volumio[4995]: info: CoreStateMachine::pushState Apr 25 19:09:32 rivo volumio[4995]: info: CoreCommandRouter::volumioPushState Apr 25 19:09:32 rivo volumio[4995]: info: MRS: Pushing multiroomSync output update for this device Apr 25 19:09:32 rivo volumio[4995]: info: MRS: Pushing multiroomSync output Apr 25 19:09:32 rivo volumio[4995]: info: CorePlayQueue::getTrack 0 Apr 25 19:09:32 rivo volumio[4995]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 25 19:09:32 rivo volumio[4995]: info: CoreCommandRouter::servicePushState Apr 25 19:09:32 rivo volumio[4995]: info: CoreStateMachine::pushState Apr 25 19:09:32 rivo volumio[4995]: info: CoreCommandRouter::volumioPushState Apr 25 19:09:32 rivo volumio[4995]: info: MRS: Pushing multiroomSync output update for this device Apr 25 19:09:32 rivo volumio[4995]: info: MRS: Pushing multiroomSync output Apr 25 19:09:32 rivo volumio[4995]: info: CorePlayQueue::getTrack 0 Apr 25 19:09:32 rivo volumio[4995]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 25 19:09:33 rivo volumio[4995]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 25 19:09:33 rivo volumio[4995]: info: CoreStateMachine::getcurrentVolume Apr 25 19:09:33 rivo volumio[4995]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 19:09:33 rivo volumio[4995]: info: VolumeController:: Volume=100 Mute =false Apr 25 19:09:33 rivo volumio[4995]: info: CoreCommandRouter::volumioGetState Apr 25 19:09:33 rivo volumio[4995]: info: CoreStateMachine::pushState Apr 25 19:09:33 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 19:09:33 rivo volumio[4995]: info: CoreCommandRouter::volumioPushState Apr 25 19:09:33 rivo volumio[4995]: info: MRS: Pushing multiroomSync output update for this device Apr 25 19:09:33 rivo volumio[4995]: info: MRS: Pushing multiroomSync output Apr 25 19:09:33 rivo volumio[4995]: info: CoreStateMachine::updateTrackBlock Apr 25 19:09:33 rivo volumio[4995]: info: CorePlayQueue::getTrackBlock Apr 25 19:09:33 rivo volumio[4995]: info: CoreCommandRouter::volumioRetrievevolume Apr 25 19:09:33 rivo volumio[4995]: info: Updating RAAT Signal Path Apr 25 19:09:33 rivo volumio[4995]: info: TidalConnect service stoped! Apr 25 19:09:33 rivo volumio[4995]: info: TidalConnect service stoped! Apr 25 19:09:33 rivo mpd[7618]: Apr 25 19:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 25 19:09:33 rivo systemd[1]: Started Music Player Daemon. Apr 25 19:09:33 rivo sudo[7612]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:33 rivo sudo[7337]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:33 rivo volumio[4995]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Apr 25 19:09:33 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:09:33 rivo volumio[4995]: error: MPD error: The expression evaluated to a falsy value: Apr 25 19:09:33 rivo volumio[4995]: assert.ok(self.idling) Apr 25 19:09:33 rivo volumio[4995]: error: The expression evaluated to a falsy value: Apr 25 19:09:33 rivo volumio[4995]: assert.ok(self.idling) Apr 25 19:09:33 rivo volumio[4995]: info: VolumeController:: Volume=undefined Mute =false Apr 25 19:09:33 rivo volumio[4995]: info: CoreCommandRouter::volumioGetState Apr 25 19:09:33 rivo volumio[4995]: info: CoreStateMachine::pushState Apr 25 19:09:33 rivo volumio[4995]: info: CoreCommandRouter::volumioPushState Apr 25 19:09:33 rivo volumio[4995]: info: MRS: Pushing multiroomSync output update for this device Apr 25 19:09:33 rivo volumio[4995]: info: MRS: Pushing multiroomSync output Apr 25 19:09:33 rivo volumio[4995]: error: updateQueue error: null Apr 25 19:09:33 rivo volumio[4995]: info: Updating RAAT Signal Path Apr 25 19:09:33 rivo volumio[4995]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 25 19:09:33 rivo volumio[4995]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 25 19:09:33 rivo sudo[7811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 25 19:09:33 rivo sudo[7811]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:33 rivo sudo[7811]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:34 rivo volumio[4995]: info: TidalConnect service stoped! Apr 25 19:09:34 rivo volumio[4995]: info: TidalConnect service stoped! Apr 25 19:09:34 rivo volumio[4995]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 25 19:09:34 rivo volumio[4995]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 25 19:09:34 rivo sudo[7824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 25 19:09:34 rivo sudo[7824]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:09:34 rivo sudo[7824]: pam_unix(sudo:session): session closed for user root Apr 25 19:09:34 rivo volumio[4995]: error: Upnp client error: Error: This socket has been ended by the other party Apr 25 19:09:35 rivo volumio[4995]: info: TidalConnect service started! Apr 25 19:09:35 rivo volumio[4995]: info: Executing endpoint metavolumio Apr 25 19:09:35 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 25 19:09:36 rivo volumio[4995]: info: TidalConnect service started! Apr 25 19:09:37 rivo volumio[4995]: info: TidalConnect service started! Apr 25 19:09:38 rivo volumio[4995]: error: Failed request for metavolumio API Apr 25 19:09:47 rivo volumio[4995]: info: Clearing queue after UPNP request Apr 25 19:09:47 rivo volumio[4995]: info: CoreStateMachine::ClearQueue Apr 25 19:09:47 rivo volumio[4995]: info: CoreStateMachine::stop Apr 25 19:09:47 rivo volumio[4995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 19:09:47 rivo volumio[4995]: info: CorePlayQueue::clearPlayQueue Apr 25 19:09:47 rivo volumio[4995]: info: CorePlayQueue::saveQueue Apr 25 19:09:47 rivo volumio[4995]: info: CoreCommandRouter::volumioPushState Apr 25 19:09:47 rivo volumio[4995]: info: MRS: Pushing multiroomSync output update for this device Apr 25 19:09:47 rivo volumio[4995]: info: MRS: Pushing multiroomSync output Apr 25 19:09:47 rivo volumio[4995]: info: CoreCommandRouter::volumioPushQueue Apr 25 19:09:47 rivo volumio[4995]: info: Updating RAAT Signal Path Apr 25 19:09:47 rivo volumio[4995]: info: Apr 25 19:09:47 rivo volumio[4995]: ---------------------------- MPD announces system playlist update Apr 25 19:09:47 rivo volumio[4995]: info: Ignoring MPD Status Update Apr 25 19:09:47 rivo volumio[4995]: info: Apr 25 19:09:47 rivo volumio[4995]: ---------------------------- MPD announces system playlist update Apr 25 19:09:47 rivo volumio[4995]: info: Ignoring MPD Status Update Apr 25 19:09:47 rivo volumio[4995]: error: updateQueue error: null Apr 25 19:09:47 rivo volumio[4995]: info: ------------------------------ 9ms Apr 25 19:09:47 rivo volumio[4995]: info: ------------------------------ 6ms Apr 25 19:09:47 rivo volumio[4995]: info: Starting UPNP Playback Apr 25 19:09:47 rivo volumio[4995]: info: Preparing playback through UPNP Apr 25 19:09:47 rivo volumio[4995]: info: CoreCommandRouter::volumioGetState Apr 25 19:09:47 rivo volumio[4995]: info: CorePlayQueue::getTrack 0 Apr 25 19:09:47 rivo volumio[4995]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 25 19:09:55 rivo volumio[4995]: info: Starting UPNP Playback Apr 25 19:09:55 rivo volumio[4995]: info: Preparing playback through UPNP Apr 25 19:09:55 rivo volumio[4995]: info: Consume mode Apr 25 19:09:55 rivo volumio[4995]: info: CoreCommandRouter::volumioGetState Apr 25 19:09:55 rivo volumio[4995]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 25 19:09:55 rivo volumio[4995]: info: Clearing queue after UPNP request Apr 25 19:09:56 rivo volumio[4995]: info: CoreStateMachine::ClearQueue Apr 25 19:09:56 rivo volumio[4995]: info: CoreStateMachine::stop Apr 25 19:09:56 rivo volumio[4995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 19:09:56 rivo volumio[4995]: info: CorePlayQueue::clearPlayQueue Apr 25 19:09:56 rivo volumio[4995]: info: CorePlayQueue::saveQueue Apr 25 19:09:56 rivo volumio[4995]: info: CoreCommandRouter::volumioPushState Apr 25 19:09:56 rivo volumio[4995]: info: MRS: Pushing multiroomSync output update for this device Apr 25 19:09:56 rivo volumio[4995]: info: MRS: Pushing multiroomSync output Apr 25 19:09:56 rivo volumio[4995]: info: CoreCommandRouter::volumioPushQueue Apr 25 19:09:56 rivo volumio[4995]: info: Updating RAAT Signal Path Apr 25 19:09:56 rivo volumio[4995]: info: Apr 25 19:09:56 rivo volumio[4995]: ---------------------------- MPD announces system playlist update Apr 25 19:09:56 rivo volumio[4995]: info: Ignoring MPD Status Update Apr 25 19:09:56 rivo volumio[4995]: info: Apr 25 19:09:56 rivo volumio[4995]: ---------------------------- MPD announces system playlist update Apr 25 19:09:56 rivo volumio[4995]: info: Ignoring MPD Status Update Apr 25 19:09:56 rivo volumio[4995]: error: updateQueue error: null Apr 25 19:09:56 rivo volumio[4995]: error: updateQueue error: null Apr 25 19:09:56 rivo volumio[4995]: info: ------------------------------ 6ms Apr 25 19:09:56 rivo volumio[4995]: info: ------------------------------ 4ms Apr 25 19:09:56 rivo volumio[4995]: info: Starting UPNP Playback Apr 25 19:09:56 rivo volumio[4995]: info: Preparing playback through UPNP Apr 25 19:09:56 rivo volumio[4995]: info: CoreCommandRouter::volumioGetState Apr 25 19:09:56 rivo volumio[4995]: info: CorePlayQueue::getTrack 0 Apr 25 19:09:56 rivo volumio[4995]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 25 19:10:01 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:01 rivo volumio[4995]: No protocol specified Apr 25 19:10:01 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:10:01 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 25 19:10:01 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 19:10:01 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 25 19:10:02 rivo volumio[4995]: No protocol specified Apr 25 19:10:02 rivo volumio[4995]: xcb_connection_has_error() returned true Apr 25 19:10:02 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:02 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:02 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:02 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:02 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:02 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:02 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:02 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 25 19:10:02 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 25 19:10:02 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Apr 25 19:10:02 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:10:02 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 19:10:04 rivo volumio[4995]: info: Starting UPNP Playback Apr 25 19:10:04 rivo volumio[4995]: info: Preparing playback through UPNP Apr 25 19:10:04 rivo volumio[4995]: info: Consume mode Apr 25 19:10:04 rivo volumio[4995]: info: CoreCommandRouter::volumioGetState Apr 25 19:10:04 rivo volumio[4995]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 25 19:10:04 rivo volumio[4995]: info: Clearing queue after UPNP request Apr 25 19:10:05 rivo volumio[4995]: info: CoreStateMachine::ClearQueue Apr 25 19:10:05 rivo volumio[4995]: info: CoreStateMachine::stop Apr 25 19:10:05 rivo volumio[4995]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 19:10:05 rivo volumio[4995]: info: CorePlayQueue::clearPlayQueue Apr 25 19:10:05 rivo volumio[4995]: info: CorePlayQueue::saveQueue Apr 25 19:10:05 rivo volumio[4995]: info: CoreCommandRouter::volumioPushState Apr 25 19:10:05 rivo volumio[4995]: info: MRS: Pushing multiroomSync output update for this device Apr 25 19:10:05 rivo volumio[4995]: info: MRS: Pushing multiroomSync output Apr 25 19:10:05 rivo volumio[4995]: info: CoreCommandRouter::volumioPushQueue Apr 25 19:10:05 rivo volumio[4995]: info: Updating RAAT Signal Path Apr 25 19:10:05 rivo volumio[4995]: info: Apr 25 19:10:05 rivo volumio[4995]: ---------------------------- MPD announces system playlist update Apr 25 19:10:05 rivo volumio[4995]: info: Ignoring MPD Status Update Apr 25 19:10:05 rivo volumio[4995]: info: Apr 25 19:10:05 rivo volumio[4995]: ---------------------------- MPD announces system playlist update Apr 25 19:10:05 rivo volumio[4995]: info: Ignoring MPD Status Update Apr 25 19:10:05 rivo volumio[4995]: error: updateQueue error: null Apr 25 19:10:05 rivo volumio[4995]: error: updateQueue error: null Apr 25 19:10:05 rivo volumio[4995]: info: ------------------------------ 7ms Apr 25 19:10:05 rivo volumio[4995]: info: ------------------------------ 5ms Apr 25 19:10:05 rivo volumio[4995]: info: Starting UPNP Playback Apr 25 19:10:05 rivo volumio[4995]: info: Preparing playback through UPNP Apr 25 19:10:05 rivo volumio[4995]: info: CoreCommandRouter::volumioGetState Apr 25 19:10:05 rivo volumio[4995]: info: CorePlayQueue::getTrack 0 Apr 25 19:10:05 rivo volumio[4995]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 25 19:10:05 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:05 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 25 19:10:06 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 25 19:10:06 rivo sudo[7961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 25 19:10:06 rivo sudo[7961]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:10:06 rivo sudo[7961]: pam_unix(sudo:session): session closed for user root Apr 25 19:10:06 rivo sudo[7966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 25 19:10:06 rivo sudo[7966]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: Error getting the SSID Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo sudo[7966]: pam_unix(sudo:session): session closed for user root Apr 25 19:10:06 rivo sudo[7973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 25 19:10:06 rivo sudo[7973]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: Error getting the SSID Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo sudo[7973]: pam_unix(sudo:session): session closed for user root Apr 25 19:10:06 rivo sudo[7982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 25 19:10:06 rivo sudo[7982]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: Error getting the SSID Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo kernel: dhd_ioctl_entry: Interface is down Apr 25 19:10:06 rivo sudo[7982]: pam_unix(sudo:session): session closed for user root Apr 25 19:10:06 rivo sudo[7987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 25 19:10:06 rivo sudo[7987]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:10:06 rivo sudo[7987]: pam_unix(sudo:session): session closed for user root Apr 25 19:10:06 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 25 19:10:06 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 25 19:10:06 rivo sudo[7993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 25 19:10:06 rivo sudo[7993]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:10:06 rivo sudo[7993]: pam_unix(sudo:session): session closed for user root Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:12 rivo volumio[4995]: info: Retrieving Cloud Streaming UI Apr 25 19:10:12 rivo volumio[4995]: info: Getting Tidal Cloud Configuration Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:12 rivo volumio[4995]: info: Getting Qobuz Cloud Configuration Apr 25 19:10:12 rivo volumio[4995]: info: Asking plugin for UI Config Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:12 rivo volumio[4995]: info: Getting Spotify Cloud Configuration Apr 25 19:10:12 rivo volumio[4995]: info: Asking plugin for UI Config Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:12 rivo volumio[4995]: info: Saving Spotify Acccount Apr 25 19:10:12 rivo volumio[4995]: info: Got it Apr 25 19:10:12 rivo volumio[4995]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 25 19:10:12 rivo volumio[4995]: info: Got Tidal Cloud Configuration Apr 25 19:10:12 rivo volumio[4995]: info: Got it Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::volumioGetBrowseSources Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::volumioGetBrowseSources Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::volumioGetBrowseSources Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 19:10:12 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 25 19:10:16 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 19:10:17 rivo volumio[4995]: info: Disabling MyMusic plugin upnp Apr 25 19:10:17 rivo sudo[7998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Apr 25 19:10:17 rivo sudo[7998]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:10:17 rivo systemd[1]: Stopping UPnP Renderer front-end to MPD... Apr 25 19:10:17 rivo volumio[4995]: error: Upnp client error: Error: This socket has been ended by the other party Apr 25 19:10:17 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 25 19:10:20 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:26 rivo volumio[4995]: info: Retrieving Cloud Streaming UI Apr 25 19:10:26 rivo volumio[4995]: info: Getting Tidal Cloud Configuration Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:26 rivo volumio[4995]: info: Getting Qobuz Cloud Configuration Apr 25 19:10:26 rivo volumio[4995]: info: Asking plugin for UI Config Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:26 rivo volumio[4995]: info: Getting Spotify Cloud Configuration Apr 25 19:10:26 rivo volumio[4995]: info: Asking plugin for UI Config Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:26 rivo volumio[4995]: info: Saving Spotify Acccount Apr 25 19:10:26 rivo volumio[4995]: info: Got it Apr 25 19:10:26 rivo volumio[4995]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 25 19:10:26 rivo volumio[4995]: info: Got Tidal Cloud Configuration Apr 25 19:10:26 rivo volumio[4995]: info: Got it Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::volumioGetBrowseSources Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::volumioGetBrowseSources Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::volumioGetBrowseSources Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 19:10:26 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 25 19:10:30 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 19:10:34 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 19:10:38 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 19:10:42 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 19:10:42 rivo volumio[4995]: info: Disabling MyMusic plugin upnp Apr 25 19:10:42 rivo sudo[8002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Apr 25 19:10:42 rivo sudo[8002]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:10:42 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 25 19:10:46 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:50 rivo volumio[4995]: info: Retrieving Cloud Streaming UI Apr 25 19:10:50 rivo volumio[4995]: info: Getting Tidal Cloud Configuration Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:50 rivo volumio[4995]: info: Getting Qobuz Cloud Configuration Apr 25 19:10:50 rivo volumio[4995]: info: Asking plugin for UI Config Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:50 rivo volumio[4995]: info: Getting Spotify Cloud Configuration Apr 25 19:10:50 rivo volumio[4995]: info: Asking plugin for UI Config Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:50 rivo volumio[4995]: info: Saving Spotify Acccount Apr 25 19:10:50 rivo volumio[4995]: info: Got it Apr 25 19:10:50 rivo volumio[4995]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 25 19:10:50 rivo volumio[4995]: info: Got Tidal Cloud Configuration Apr 25 19:10:50 rivo volumio[4995]: info: Got it Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::volumioGetBrowseSources Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::volumioGetBrowseSources Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::volumioGetBrowseSources Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 19:10:50 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 25 19:10:51 rivo volumio[4995]: info: Disabling MyMusic plugin cd_controller Apr 25 19:10:51 rivo volumio[4995]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD Apr 25 19:10:51 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 25 19:10:51 rivo volumio[4995]: Cannot find translation for source TIDAL Apr 25 19:10:51 rivo volumio[4995]: info: Stopping UDEV Watcher for CD Apr 25 19:10:51 rivo volumio[4995]: info: Disabling plugin cd_controller Apr 25 19:10:51 rivo volumio[4995]: info: Done. Apr 25 19:10:54 rivo volumio[4995]: info: Disabling MyMusic plugin upnp Apr 25 19:10:54 rivo sudo[8047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Apr 25 19:10:54 rivo sudo[8047]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 19:10:54 rivo volumio[4995]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 25 19:10:54 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 19:10:55 rivo volumio[4995]: info: Enabling MyMusic plugin upnp Apr 25 19:10:55 rivo volumio[4995]: info: Enabling plugin upnp Apr 25 19:10:55 rivo volumio[4995]: info: Loading plugin "upnp"... Apr 25 19:10:55 rivo volumio[4995]: info: [1714065055008] Starting Upmpd Daemon Apr 25 19:10:55 rivo volumio[4995]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 25 19:10:55 rivo volumio[4995]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 19:10:55 rivo volumio[4995]: Error: listen EADDRINUSE: address already in use :::6599 Apr 25 19:10:55 rivo volumio[4995]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Apr 25 19:10:55 rivo volumio[4995]: at listenInCluster (net.js:1379:12) Apr 25 19:10:55 rivo volumio[4995]: at Server.listen (net.js:1465:7) Apr 25 19:10:55 rivo volumio[4995]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Apr 25 19:10:55 rivo volumio[4995]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Apr 25 19:10:55 rivo volumio[4995]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Apr 25 19:10:55 rivo volumio[4995]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 25 19:10:55 rivo volumio[4995]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Apr 25 19:10:55 rivo volumio[4995]: code: 'EADDRINUSE', Apr 25 19:10:55 rivo volumio[4995]: errno: -98, Apr 25 19:10:55 rivo volumio[4995]: syscall: 'listen', Apr 25 19:10:55 rivo volumio[4995]: address: '::', Apr 25 19:10:55 rivo volumio[4995]: port: 6599 Apr 25 19:10:55 rivo volumio[4995]: } Apr 25 19:10:55 rivo volumio[4995]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 19:10:55 rivo sudo[8058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-25 19:09 Apr 25 19:10:55 rivo sudo[8058]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e831866afe2db61aee9333a8f5ba9d9fd36609c7" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 18 Feb 2024 11:41:55 AM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b0437c7088d6645a661a49f6d9fa596a"