-- Logs begin at Fri 2025-02-28 18:17:06 JST, end at Fri 2025-02-28 19:23:53 JST. -- Feb 28 19:22:45 rivo kernel: usb 1-1.2: new high-speed USB device number 8 using xhci-hcd Feb 28 19:22:45 rivo kernel: usb 1-1.2: config 1 has an invalid interface number: 3 but max is 2 Feb 28 19:22:45 rivo kernel: usb 1-1.2: config 1 has an invalid interface number: 3 but max is 2 Feb 28 19:22:45 rivo kernel: usb 1-1.2: config 1 has an invalid interface number: 3 but max is 2 Feb 28 19:22:45 rivo kernel: usb 1-1.2: config 1 has no interface number 1 Feb 28 19:22:45 rivo kernel: usb 1-1.2: New USB device found, idVendor=0644, idProduct=8038 Feb 28 19:22:45 rivo kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Feb 28 19:22:45 rivo kernel: usb 1-1.2: Product: TEAC USB AUDIO DEVICE Feb 28 19:22:45 rivo kernel: usb 1-1.2: Manufacturer: TEAC Feb 28 19:22:45 rivo kernel: input: TEAC TEAC USB AUDIO DEVICE as /devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.2/1-1.2:1.0/0003:0644:8038.0002/input/input7 Feb 28 19:22:45 rivo kernel: hid-generic 0003:0644:8038.0002: input,hidraw0: USB HID v1.00 Device [TEAC TEAC USB AUDIO DEVICE] on usb-xhci-hcd.0.auto-1.2/input0 Feb 28 19:22:46 rivo volumio[3185]: info: Feb 28 19:22:46 rivo volumio[3185]: ---------------------------- USB Audio Device Attached Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Feb 28 19:22:46 rivo volumio[3185]: No protocol specified Feb 28 19:22:46 rivo volumio[3185]: xcb_connection_has_error() returned true Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::Close All Modals sent Feb 28 19:22:46 rivo volumio[3185]: info: Preparing to save Alsa Options, stopping services first Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::volumioGetState Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::volumioPause Feb 28 19:22:46 rivo volumio[3185]: info: CoreStateMachine::pause Feb 28 19:22:46 rivo volumio[3185]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"TEAC USB AUDIO DEVICE","alsacard":"DEVICE"},"i2s":false} Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 28 19:22:46 rivo volumio[3185]: No protocol specified Feb 28 19:22:46 rivo volumio[3185]: xcb_connection_has_error() returned true Feb 28 19:22:46 rivo systemd-udevd[16482]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 28 19:22:46 rivo volumio[3185]: No protocol specified Feb 28 19:22:46 rivo volumio[3185]: xcb_connection_has_error() returned true Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 28 19:22:46 rivo volumio[3185]: No protocol specified Feb 28 19:22:46 rivo volumio[3185]: xcb_connection_has_error() returned true Feb 28 19:22:46 rivo volumio[3185]: info: Setting mixer Clock Source 12 Validity for card TEAC USB AUDIO DEVICE Feb 28 19:22:46 rivo volumio[3185]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Feb 28 19:22:46 rivo vtcs[6349]: [2025-02-28 19:22:46.361] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Feb 28 19:22:46 rivo vtcs[6349]: [2025-02-28 19:22:46.359] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Feb 28 19:22:46 rivo volumio[3185]: No protocol specified Feb 28 19:22:46 rivo volumio[3185]: xcb_connection_has_error() returned true Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 28 19:22:46 rivo volumio[3185]: info: Updating Volume Controller Parameters: Device: 5 Name: TEAC USB AUDIO DEVICE Mixer: Clock Source 12 Validity Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 28 19:22:46 rivo volumio[3185]: info: Disabling external Volume Control Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 28 19:22:46 rivo volumio[3185]: info: Preparing to generate the ALSA configuration file Feb 28 19:22:46 rivo volumio[3185]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 28 19:22:46 rivo volumio[3185]: info: Reading ALSA contributions from plugins. Feb 28 19:22:46 rivo volumio[3185]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Feb 28 19:22:46 rivo volumio[3185]: xcb_connection_has_error() returned true Feb 28 19:22:46 rivo volumio[3185]: No protocol specified Feb 28 19:22:46 rivo volumio[3185]: xcb_connection_has_error() returned true Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 28 19:22:46 rivo volumio[3185]: No protocol specified Feb 28 19:22:46 rivo volumio[3185]: xcb_connection_has_error() returned true Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:46 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: Not Reporting Auto name since its the default one Feb 28 19:22:47 rivo volumio[3185]: info: VolumeController:: Volume=undefined Mute =false Feb 28 19:22:47 rivo volumio[3185]: info: CoreStateMachine::pushState Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::volumioPushState Feb 28 19:22:47 rivo volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Feb 28 19:22:47 rivo volumio[3185]: info: MRS: Pushing multiroomSync output Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: Not Reporting Auto name since its the default one Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 28 19:22:47 rivo volumio[3185]: No protocol specified Feb 28 19:22:47 rivo volumio[3185]: xcb_connection_has_error() returned true Feb 28 19:22:47 rivo volumio[3185]: info: Asound.conf file unchanged, so no further update is needed Feb 28 19:22:47 rivo volumio[3185]: info: Output device has changed, restarting MPD Feb 28 19:22:47 rivo volumio[3185]: info: Output device has changed, restarting Shairport Sync Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: No protocol specified Feb 28 19:22:47 rivo volumio[3185]: xcb_connection_has_error() returned true Feb 28 19:22:47 rivo volumio[3185]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 28 19:22:47 rivo volumio[3185]: No protocol specified Feb 28 19:22:47 rivo volumio[3185]: xcb_connection_has_error() returned true Feb 28 19:22:47 rivo volumio[3185]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 28 19:22:47 rivo volumio[3185]: No protocol specified Feb 28 19:22:47 rivo volumio[3185]: xcb_connection_has_error() returned true Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: Starting Shairport Sync Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:22:47 rivo volumio[3185]: info: Not Reporting Auto name since its the default one Feb 28 19:22:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 28 19:22:48 rivo volumio[3185]: No protocol specified Feb 28 19:22:48 rivo volumio[3185]: xcb_connection_has_error() returned true Feb 28 19:22:50 rivo volumio[3185]: info: RAAT: Requesting Headphone Status Feb 28 19:22:50 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Feb 28 19:22:50 rivo volumio[3185]: info: MCU Signalled Headphone Mode Disabled Feb 28 19:22:50 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Feb 28 19:22:50 rivo volumio[3185]: info: Reporting Headphone State: false Feb 28 19:22:50 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:50 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:50 rivo volumio[3185]: info: Updating RAAT Signal Path Feb 28 19:22:50 rivo volumio[3185]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Feb 28 19:22:50 rivo volumio[3185]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 28 19:22:50 rivo volumio[3185]: info: CoreStateMachine::getcurrentVolume Feb 28 19:22:50 rivo volumio[3185]: info: CoreCommandRouter::volumioRetrievevolume Feb 28 19:22:50 rivo volumio[3185]: Unable to parse: Feb 28 19:22:50 rivo volumio[3185]: Simple mixer control 'Clock Source 12 Validity',0 Feb 28 19:22:50 rivo volumio[3185]: Capabilities: pswitch pswitch-joined Feb 28 19:22:50 rivo volumio[3185]: Playback channels: Mono Feb 28 19:22:50 rivo volumio[3185]: Mono: Playback [on] Feb 28 19:22:50 rivo volumio[3185]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 28 19:22:50 rivo volumio[3185]: info: RAAT: Requesting Headphone Status Feb 28 19:22:50 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Feb 28 19:22:50 rivo volumio[3185]: Unable to parse: Feb 28 19:22:50 rivo volumio[3185]: Simple mixer control 'Clock Source 12 Validity',0 Feb 28 19:22:50 rivo volumio[3185]: Capabilities: pswitch pswitch-joined Feb 28 19:22:50 rivo volumio[3185]: Playback channels: Mono Feb 28 19:22:50 rivo volumio[3185]: Mono: Playback [on] Feb 28 19:22:50 rivo volumio[3185]: info: VolumeController:: Volume=undefined Mute =false Feb 28 19:22:50 rivo volumio[3185]: info: CoreStateMachine::pushState Feb 28 19:22:50 rivo volumio[3185]: info: CoreCommandRouter::volumioPushState Feb 28 19:22:50 rivo volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Feb 28 19:22:50 rivo volumio[3185]: info: MRS: Pushing multiroomSync output Feb 28 19:22:50 rivo volumio[3185]: info: CoreStateMachine::updateTrackBlock Feb 28 19:22:50 rivo volumio[3185]: info: CorePlayQueue::getTrackBlock Feb 28 19:22:50 rivo volumio[3185]: info: CoreCommandRouter::volumioRetrievevolume Feb 28 19:22:50 rivo volumio[3185]: info: MCU Signalled Headphone Mode Disabled Feb 28 19:22:50 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Feb 28 19:22:50 rivo volumio[3185]: info: Reporting Headphone State: false Feb 28 19:22:50 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:50 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:50 rivo volumio[3185]: info: Updating RAAT Signal Path Feb 28 19:22:50 rivo volumio[3185]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Feb 28 19:22:50 rivo volumio[3185]: Unable to parse: Feb 28 19:22:50 rivo volumio[3185]: Simple mixer control 'Clock Source 12 Validity',0 Feb 28 19:22:50 rivo volumio[3185]: Capabilities: pswitch pswitch-joined Feb 28 19:22:50 rivo volumio[3185]: Playback channels: Mono Feb 28 19:22:50 rivo volumio[3185]: Mono: Playback [on] Feb 28 19:22:50 rivo volumio[3185]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Feb 28 19:22:50 rivo volumio[3185]: info: VolumeController:: Volume=undefined Mute =false Feb 28 19:22:50 rivo volumio[3185]: info: CoreStateMachine::pushState Feb 28 19:22:50 rivo volumio[3185]: info: CoreCommandRouter::volumioPushState Feb 28 19:22:50 rivo volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Feb 28 19:22:50 rivo volumio[3185]: info: MRS: Pushing multiroomSync output Feb 28 19:22:51 rivo volumio[3185]: info: RAAT: Requesting Headphone Status Feb 28 19:22:51 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Feb 28 19:22:51 rivo volumio[3185]: info: MCU Signalled Headphone Mode Disabled Feb 28 19:22:51 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Feb 28 19:22:51 rivo volumio[3185]: info: Reporting Headphone State: false Feb 28 19:22:51 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:22:51 rivo volumio[3185]: info: Updating RAAT Signal Path Feb 28 19:22:51 rivo volumio[3185]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Feb 28 19:22:55 rivo volumio[3185]: info: Discovery: Disconnected from remote: 192.168.11.36 Feb 28 19:23:01 rivo volumio[3185]: info: Discovery: Remote connection error: 192.168.11.36 Feb 28 19:23:01 rivo volumio[3185]: info: Discovery: Remote connection timeout: 192.168.11.36 Feb 28 19:23:04 rivo dhcpcd[3408]: eth0: carrier acquired Feb 28 19:23:04 rivo kernel: meson6-dwmac ff3f0000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Feb 28 19:23:04 rivo dhcpcd[3408]: eth0: IAID 00:1c:1f:01 Feb 28 19:23:04 rivo dhcpcd[3408]: eth0: soliciting an IPv6 router Feb 28 19:23:04 rivo dhcpcd[3408]: eth0: rebinding lease of 192.168.11.44 Feb 28 19:23:04 rivo dhcpcd[3408]: eth0: probing address 192.168.11.44/24 Feb 28 19:23:05 rivo ifplugd(eth0)[3088]: Link beat detected. Feb 28 19:23:05 rivo ifplugd(eth0)[3088]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 28 19:23:05 rivo ifplugd(eth0)[3088]: client: sending commands to master dhcpcd process Feb 28 19:23:05 rivo dhcpcd[16632]: sending commands to master dhcpcd process Feb 28 19:23:05 rivo dhcpcd[3351]: control command: /sbin/dhcpcd eth0 Feb 28 19:23:05 rivo dhcpcd[3351]: eth0: carrier acquired Feb 28 19:23:05 rivo dhcpcd[3351]: eth0: IAID 00:1c:1f:01 Feb 28 19:23:05 rivo sudo[16504]: volumio : unable to resolve host rivo Feb 28 19:23:05 rivo sudo[16504]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:05 rivo sudo[16504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 28 19:23:05 rivo sudo[16504]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:05 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Feb 28 19:23:05 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Feb 28 19:23:05 rivo ifplugd(eth0)[3088]: Program executed successfully. Feb 28 19:23:05 rivo systemd[1]: vtcs.service: Succeeded. Feb 28 19:23:05 rivo volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 28 19:23:05 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Feb 28 19:23:05 rivo sudo[16504]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:05 rivo dhcpcd[3351]: eth0: soliciting an IPv6 router Feb 28 19:23:05 rivo sudo[16510]: volumio : unable to resolve host rivo Feb 28 19:23:05 rivo sudo[16510]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:05 rivo sudo[16510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 28 19:23:05 rivo sudo[16510]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:05 rivo sudo[16510]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:05 rivo dhcpcd[3351]: eth0: rebinding lease of 192.168.11.44 Feb 28 19:23:05 rivo dhcpcd[3351]: eth0: probing address 192.168.11.44/24 Feb 28 19:23:06 rivo sudo[16528]: volumio : unable to resolve host rivo Feb 28 19:23:06 rivo sudo[16528]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:06 rivo sudo[16528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 28 19:23:06 rivo sudo[16528]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:06 rivo sudo[16530]: volumio : unable to resolve host rivo Feb 28 19:23:06 rivo sudo[16530]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:06 rivo sudo[16530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 28 19:23:06 rivo sudo[16530]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:06 rivo sudo[16530]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:06 rivo sudo[16528]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:06 rivo volumio[3185]: info: MPD Permissions set Feb 28 19:23:06 rivo sudo[16532]: volumio : unable to resolve host rivo Feb 28 19:23:06 rivo sudo[16532]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:06 rivo sudo[16532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 28 19:23:06 rivo sudo[16532]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:06 rivo systemd[1]: Stopping Music Player Daemon... Feb 28 19:23:06 rivo volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 28 19:23:06 rivo sudo[16674]: volumio : unable to resolve host rivo Feb 28 19:23:06 rivo sudo[16674]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:06 rivo sudo[16674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 28 19:23:06 rivo sudo[16674]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:06 rivo systemd[1]: Started RAAT DAEMON. Feb 28 19:23:06 rivo sudo[16674]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:06 rivo volumio[3185]: info: Raat Daemon started successfully Feb 28 19:23:06 rivo sudo[16539]: volumio : unable to resolve host rivo Feb 28 19:23:06 rivo sudo[16539]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:06 rivo sudo[16542]: volumio : unable to resolve host rivo Feb 28 19:23:06 rivo sudo[16542]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:06 rivo sudo[16542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 28 19:23:06 rivo sudo[16542]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:06 rivo sudo[16539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 28 19:23:06 rivo sudo[16539]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:06 rivo volumio[3185]: info: Executing endpoint restartRAATSocket Feb 28 19:23:06 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Feb 28 19:23:06 rivo systemd[1]: mpd.service: Succeeded. Feb 28 19:23:06 rivo systemd[1]: Stopped Music Player Daemon. Feb 28 19:23:06 rivo systemd[1]: Starting Music Player Daemon... Feb 28 19:23:06 rivo sudo[16546]: volumio : unable to resolve host rivo Feb 28 19:23:06 rivo sudo[16546]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:06 rivo volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 28 19:23:06 rivo sudo[16542]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:06 rivo volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 28 19:23:06 rivo sudo[16539]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:06 rivo sudo[16546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 28 19:23:06 rivo sudo[16546]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:06 rivo volumio[3185]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 28 19:23:06 rivo sudo[16546]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:06 rivo sudo[16695]: volumio : unable to resolve host rivo Feb 28 19:23:06 rivo sudo[16695]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:06 rivo sudo[16695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 28 19:23:06 rivo sudo[16695]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:06 rivo sudo[16554]: volumio : unable to resolve host rivo Feb 28 19:23:06 rivo sudo[16554]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:06 rivo systemd[1]: Stopping RAAT DAEMON... Feb 28 19:23:06 rivo sudo[16554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 28 19:23:06 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 28 19:23:06 rivo systemd[1]: raat-daemon.service: Succeeded. Feb 28 19:23:06 rivo sudo[16554]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:06 rivo systemd[1]: Stopped RAAT DAEMON. Feb 28 19:23:06 rivo volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 28 19:23:06 rivo systemd[1]: Started RAAT DAEMON. Feb 28 19:23:06 rivo volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 28 19:23:06 rivo sudo[16695]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:06 rivo sudo[16690]: root : unable to resolve host rivo Feb 28 19:23:06 rivo sudo[16690]: sudo: unable to resolve host rivo: System error Feb 28 19:23:06 rivo volumio[3185]: info: Raat Daemon started successfully Feb 28 19:23:06 rivo sudo[16690]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:06 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 28 19:23:06 rivo sudo[16690]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 28 19:23:06 rivo sudo[16690]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:06 rivo volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 28 19:23:06 rivo systemd[1]: shairport-sync.service: Succeeded. Feb 28 19:23:06 rivo sudo[16690]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:06 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 28 19:23:06 rivo volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 28 19:23:06 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 28 19:23:06 rivo sudo[16554]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:06 rivo volumio[3185]: info: Shairport-Sync Started Feb 28 19:23:06 rivo volumio[3185]: info: Executing endpoint restartRAATSocket Feb 28 19:23:06 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Feb 28 19:23:07 rivo volumio[3185]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 28 19:23:07 rivo sudo[16562]: volumio : unable to resolve host rivo Feb 28 19:23:07 rivo sudo[16562]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:07 rivo sudo[16562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Feb 28 19:23:07 rivo sudo[16562]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:07 rivo sudo[16562]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:07 rivo sudo[16720]: volumio : unable to resolve host rivo Feb 28 19:23:07 rivo sudo[16720]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:07 rivo sudo[16720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 28 19:23:07 rivo sudo[16720]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:07 rivo systemd[1]: Stopping RAAT DAEMON... Feb 28 19:23:07 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 28 19:23:07 rivo systemd[1]: raat-daemon.service: Succeeded. Feb 28 19:23:07 rivo systemd[1]: Stopped RAAT DAEMON. Feb 28 19:23:07 rivo volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 28 19:23:07 rivo volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 28 19:23:07 rivo systemd[1]: Started RAAT DAEMON. Feb 28 19:23:07 rivo sudo[16720]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:07 rivo volumio[3185]: info: Raat Daemon started successfully Feb 28 19:23:07 rivo volumio[3185]: info: Executing endpoint restartRAATSocket Feb 28 19:23:07 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Feb 28 19:23:07 rivo volumio[3185]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 28 19:23:08 rivo volumio[3185]: info: Discovery: Remote connection error: 192.168.11.36 Feb 28 19:23:08 rivo volumio[3185]: info: Discovery: Remote connection timeout: 192.168.11.36 Feb 28 19:23:08 rivo volumio[3185]: error: Unable to stop TidalConnect service: Feb 28 19:23:08 rivo volumio[3185]: info: TidalConnect service stoped! Feb 28 19:23:08 rivo volumio[3185]: error: Unable to stop TidalConnect service: Feb 28 19:23:08 rivo volumio[3185]: info: TidalConnect service stoped! Feb 28 19:23:08 rivo volumio[3185]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 28 19:23:08 rivo volumio[3185]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 28 19:23:08 rivo mpd[16713]: Feb 28 19:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 28 19:23:08 rivo sudo[16743]: volumio : unable to resolve host rivo Feb 28 19:23:08 rivo sudo[16743]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:08 rivo systemd[1]: Started Music Player Daemon. Feb 28 19:23:08 rivo sudo[16532]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:08 rivo sudo[16743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 28 19:23:08 rivo sudo[16743]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:08 rivo volumio[3185]: error: updateQueue error: null Feb 28 19:23:08 rivo systemd[1]: Started Volumio Tidal Connect Service. Feb 28 19:23:08 rivo sudo[16743]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:09 rivo volumio[3185]: info: Executing endpoint tc_getconfig Feb 28 19:23:09 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 28 19:23:09 rivo vtcs[16764]: STARTING TidalConnect services, version: 1.4.0.34 Feb 28 19:23:09 rivo vtcs[16764]: STARTED TidalConnect services. Feb 28 19:23:09 rivo volumio[3185]: info: Executing endpoint tc_connect Feb 28 19:23:09 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 28 19:23:09 rivo volumio[3185]: info: Connecting to TidalConnect Feb 28 19:23:09 rivo volumio[3185]: info: CoreCommandRouter::servicePushState Feb 28 19:23:09 rivo volumio[3185]: info: CoreStateMachine::pushState Feb 28 19:23:09 rivo volumio[3185]: info: CoreCommandRouter::volumioPushState Feb 28 19:23:09 rivo volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Feb 28 19:23:09 rivo volumio[3185]: info: MRS: Pushing multiroomSync output Feb 28 19:23:09 rivo volumio[3185]: info: CorePlayQueue::getTrack 287 Feb 28 19:23:09 rivo volumio[3185]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Feb 28 19:23:09 rivo volumio[3185]: info: CoreCommandRouter::servicePushState Feb 28 19:23:09 rivo volumio[3185]: info: CoreStateMachine::pushState Feb 28 19:23:09 rivo volumio[3185]: info: CoreCommandRouter::volumioPushState Feb 28 19:23:09 rivo volumio[3185]: info: MRS: Pushing multiroomSync output update for this device Feb 28 19:23:09 rivo volumio[3185]: info: MRS: Pushing multiroomSync output Feb 28 19:23:09 rivo volumio[3185]: info: CorePlayQueue::getTrack 287 Feb 28 19:23:09 rivo volumio[3185]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Feb 28 19:23:09 rivo dhcpcd[3408]: eth0: leased 192.168.11.44 for 172800 seconds Feb 28 19:23:09 rivo dhcpcd[3408]: eth0: adding route to 192.168.11.0/24 Feb 28 19:23:09 rivo dhcpcd[3408]: eth0: adding default route via 192.168.11.1 Feb 28 19:23:09 rivo avahi-daemon[2847]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.11.44. Feb 28 19:23:09 rivo avahi-daemon[2847]: New relevant interface eth0.IPv4 for mDNS. Feb 28 19:23:09 rivo avahi-daemon[2847]: Registering new address record for 192.168.11.44 on eth0.IPv4. Feb 28 19:23:09 rivo volumio[3185]: error: Unable to stop TidalConnect service: Feb 28 19:23:09 rivo volumio[3185]: info: TidalConnect service stoped! Feb 28 19:23:09 rivo volumio[3185]: error: Unable to stop TidalConnect service: Feb 28 19:23:09 rivo volumio[3185]: info: TidalConnect service stoped! Feb 28 19:23:09 rivo volumio[3185]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 28 19:23:09 rivo volumio[3185]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 28 19:23:09 rivo volumio[3185]: info: MRS: Found cast device: Google-Nest-Hub-5797970e965b262e445f5c9dc841b441 Feb 28 19:23:09 rivo ntpd[5967]: ntpd exiting on signal 15 (Terminated) Feb 28 19:23:09 rivo systemd[1]: Stopping Network Time Service... Feb 28 19:23:09 rivo ntpd[5967]: 167.179.119.205 local addr 192.168.11.45 -> Feb 28 19:23:09 rivo ntpd[5967]: 133.243.238.243 local addr 192.168.11.45 -> Feb 28 19:23:09 rivo ntpd[5967]: 133.243.238.163 local addr 192.168.11.45 -> Feb 28 19:23:09 rivo ntpd[5967]: 133.130.121.141 local addr 192.168.11.45 -> Feb 28 19:23:09 rivo ntpd[5967]: 160.16.113.133 local addr 192.168.11.45 -> Feb 28 19:23:09 rivo ntpd[5967]: 172.105.240.243 local addr 192.168.11.45 -> Feb 28 19:23:09 rivo ntpd[5967]: 64.176.59.83 local addr 192.168.11.45 -> Feb 28 19:23:09 rivo ntpd[5967]: 129.250.35.250 local addr 192.168.11.45 -> Feb 28 19:23:09 rivo sudo[16822]: volumio : unable to resolve host rivo Feb 28 19:23:09 rivo sudo[16822]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:09 rivo volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 28 19:23:09 rivo volumio[3185]: info: MRS: Found cast device: BRAVIA-4K-UR3-8725ccfb55347fe284943aefcc7f4fa1 Feb 28 19:23:09 rivo sudo[16822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 28 19:23:09 rivo sudo[16822]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:09 rivo systemd[1]: ntp.service: Succeeded. Feb 28 19:23:09 rivo systemd[1]: Stopped Network Time Service. Feb 28 19:23:09 rivo volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 28 19:23:09 rivo systemd[1]: Starting Network Time Service... Feb 28 19:23:09 rivo volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 28 19:23:09 rivo sudo[16822]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:09 rivo ntpd[16829]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 28 19:23:09 rivo ntpd[16829]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Feb 28 19:23:09 rivo systemd[1]: Started Network Time Service. Feb 28 19:23:09 rivo ntpd[16837]: proto: precision = 1.250 usec (-20) Feb 28 19:23:10 rivo ntpd[16837]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 28 19:23:10 rivo ntpd[16837]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 28 19:23:10 rivo ntpd[16837]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 794 days ago Feb 28 19:23:10 rivo ntpd[16837]: Listen and drop on 0 v6wildcard [::]:123 Feb 28 19:23:10 rivo ntpd[16837]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 28 19:23:10 rivo ntpd[16837]: Listen normally on 2 lo 127.0.0.1:123 Feb 28 19:23:10 rivo ntpd[16837]: Listen normally on 3 eth0 192.168.11.44:123 Feb 28 19:23:10 rivo ntpd[16837]: Listen normally on 4 wlan0 192.168.11.45:123 Feb 28 19:23:10 rivo ntpd[16837]: Listening on routing socket on fd #21 for interface updates Feb 28 19:23:10 rivo ntpd[16837]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Feb 28 19:23:10 rivo ntpd[16837]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Feb 28 19:23:10 rivo dhcpcd[3351]: eth0: leased 192.168.11.44 for 172800 seconds Feb 28 19:23:10 rivo dhcpcd[3351]: eth0: adding route to 192.168.11.0/24 Feb 28 19:23:10 rivo dhcpcd[3351]: eth0: adding default route via 192.168.11.1 Feb 28 19:23:10 rivo volumio[3185]: info: Discovery: adding c693c4f3-fa36-4cb9-a7c6-8ba0779258fa Feb 28 19:23:10 rivo volumio[3185]: info: Discovery: Found device Rivo_Black_Edition Feb 28 19:23:10 rivo volumio[3185]: info: CoreCommandRouter::volumioGetState Feb 28 19:23:10 rivo ntpd[16837]: ntpd exiting on signal 15 (Terminated) Feb 28 19:23:10 rivo systemd[1]: Stopping Network Time Service... Feb 28 19:23:10 rivo volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 28 19:23:10 rivo systemd[1]: ntp.service: Succeeded. Feb 28 19:23:10 rivo systemd[1]: Stopped Network Time Service. Feb 28 19:23:10 rivo volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 28 19:23:10 rivo systemd[1]: Starting Network Time Service... Feb 28 19:23:10 rivo volumio[3185]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 28 19:23:10 rivo ntpd[16870]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 28 19:23:10 rivo ntpd[16870]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Feb 28 19:23:10 rivo systemd[1]: Started Network Time Service. Feb 28 19:23:10 rivo ntpd[16876]: proto: precision = 1.291 usec (-19) Feb 28 19:23:10 rivo ntpd[16876]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 28 19:23:10 rivo ntpd[16876]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 28 19:23:10 rivo ntpd[16876]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 794 days ago Feb 28 19:23:10 rivo ntpd[16876]: Listen and drop on 0 v6wildcard [::]:123 Feb 28 19:23:10 rivo ntpd[16876]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 28 19:23:10 rivo ntpd[16876]: Listen normally on 2 lo 127.0.0.1:123 Feb 28 19:23:10 rivo ntpd[16876]: Listen normally on 3 eth0 192.168.11.44:123 Feb 28 19:23:10 rivo ntpd[16876]: Listen normally on 4 wlan0 192.168.11.45:123 Feb 28 19:23:10 rivo ntpd[16876]: Listening on routing socket on fd #21 for interface updates Feb 28 19:23:10 rivo ntpd[16876]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Feb 28 19:23:10 rivo ntpd[16876]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Feb 28 19:23:11 rivo volumio[3185]: error: Unable to start TidalConnect service: Feb 28 19:23:11 rivo volumio[3185]: info: TidalConnect service started! Feb 28 19:23:12 rivo ntpd[16876]: Soliciting pool server 202.181.103.212 Feb 28 19:23:12 rivo ntpd[16876]: Soliciting pool server 167.179.119.205 Feb 28 19:23:12 rivo volumio[3185]: error: Unable to start TidalConnect service: Feb 28 19:23:12 rivo volumio[3185]: info: TidalConnect service started! Feb 28 19:23:13 rivo ntpd[16876]: Soliciting pool server 133.130.121.141 Feb 28 19:23:13 rivo volumio[3185]: info: Discovery: Remote connection error: 192.168.11.36 Feb 28 19:23:13 rivo ntpd[16876]: Soliciting pool server 133.243.238.163 Feb 28 19:23:13 rivo ntpd[16876]: Soliciting pool server 108.160.132.224 Feb 28 19:23:13 rivo ntpd[16876]: Soliciting pool server 162.159.200.123 Feb 28 19:23:14 rivo ntpd[16876]: Soliciting pool server 133.243.238.243 Feb 28 19:23:14 rivo ntpd[16876]: Soliciting pool server 45.76.218.37 Feb 28 19:23:14 rivo ntpd[16876]: Soliciting pool server 122.215.240.52 Feb 28 19:23:14 rivo ntpd[16876]: Soliciting pool server 162.159.200.1 Feb 28 19:23:15 rivo ntpd[16876]: Soliciting pool server 129.250.35.250 Feb 28 19:23:15 rivo ntpd[16876]: Soliciting pool server 18.181.48.156 Feb 28 19:23:15 rivo ntpd[16876]: Soliciting pool server 18.181.48.156 Feb 28 19:23:16 rivo ntpd[16876]: Soliciting pool server 64.176.59.83 Feb 28 19:23:16 rivo ntpd[16876]: Soliciting pool server 129.250.35.251 Feb 28 19:23:16 rivo ntpd[16876]: Soliciting pool server 23.81.40.154 Feb 28 19:23:17 rivo ntpd[16876]: Soliciting pool server 45.77.20.103 Feb 28 19:23:17 rivo ntpd[16876]: Soliciting pool server 2606:4700:f1::123 Feb 28 19:23:19 rivo volumio[3185]: info: Reporting MCU Network Status: 3 Feb 28 19:23:19 rivo volumio[3185]: info: Volumio Network Manager: Network status updated: 3 Feb 28 19:23:20 rivo volumio[3185]: info: Discovery: Remote connection error: 192.168.11.36 Feb 28 19:23:28 rivo volumio[3185]: info: Discovery: Remote connection error: 192.168.11.36 Feb 28 19:23:35 rivo sudo[16970]: volumio : unable to resolve host rivo Feb 28 19:23:35 rivo sudo[16970]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:35 rivo sudo[16970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 28 19:23:35 rivo sudo[16970]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:35 rivo sudo[16970]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:35 rivo sudo[16972]: volumio : unable to resolve host rivo Feb 28 19:23:35 rivo sudo[16972]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:35 rivo sudo[16972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 19:23:35 rivo sudo[16972]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:35 rivo sudo[16972]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:35 rivo volumio[3185]: verbose: New Socket.io Connection to 192.168.11.44 from 192.168.11.29 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 28 19:23:35 rivo sudo[16979]: volumio : unable to resolve host rivo Feb 28 19:23:35 rivo sudo[16979]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:35 rivo sudo[16979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 28 19:23:35 rivo sudo[16979]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:35 rivo sudo[16981]: volumio : unable to resolve host rivo Feb 28 19:23:35 rivo sudo[16981]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:35 rivo sudo[16981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 19:23:35 rivo sudo[16981]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:23:35 rivo sudo[16979]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:35 rivo sudo[16981]: pam_unix(sudo:session): session closed for user root Feb 28 19:23:35 rivo volumio[3185]: verbose: New Socket.io Connection to 192.168.11.44 from 192.168.11.29 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 28 19:23:36 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 28 19:23:36 rivo volumio[3185]: info: CoreCommandRouter::volumioGetVisibleSources Feb 28 19:23:36 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 19:23:36 rivo volumio[3185]: info: CoreCommandRouter::volumioGetState Feb 28 19:23:36 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 28 19:23:36 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 28 19:23:36 rivo volumio[3185]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 28 19:23:36 rivo volumio[3185]: info: Received Get System Info Feb 28 19:23:36 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 19:23:36 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 19:23:36 rivo volumio[3185]: info: Discovery: Getting this device information Feb 28 19:23:36 rivo volumio[3185]: info: CoreCommandRouter::volumioGetState Feb 28 19:23:36 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 19:23:36 rivo volumio[3185]: info: CoreCommandRouter::volumioGetState Feb 28 19:23:36 rivo volumio[3185]: info: Listing playlists Feb 28 19:23:36 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 28 19:23:36 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 28 19:23:36 rivo volumio[3185]: info: Discovery: Remote connection error: 192.168.11.36 Feb 28 19:23:36 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 28 19:23:36 rivo volumio[3185]: info: CoreCommandRouter::volumioGetState Feb 28 19:23:37 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 28 19:23:37 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 28 19:23:37 rivo volumio[3185]: info: Received Get System Info Feb 28 19:23:37 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 19:23:37 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 19:23:37 rivo volumio[3185]: info: Discovery: Getting this device information Feb 28 19:23:37 rivo volumio[3185]: info: CoreCommandRouter::volumioGetState Feb 28 19:23:37 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 19:23:39 rivo volumio[3185]: info: Executing endpoint metavolumio Feb 28 19:23:39 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 28 19:23:39 rivo volumio[3185]: info: Executing endpoint metavolumio Feb 28 19:23:39 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 28 19:23:39 rivo volumio[3185]: info: Executing endpoint metavolumio Feb 28 19:23:39 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 28 19:23:39 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 28 19:23:39 rivo volumio[3185]: info: Received Get System Info Feb 28 19:23:39 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 28 19:23:39 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 28 19:23:39 rivo volumio[3185]: info: Discovery: Getting this device information Feb 28 19:23:39 rivo volumio[3185]: info: CoreCommandRouter::volumioGetState Feb 28 19:23:39 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 28 19:23:41 rivo volumio[3185]: info: Retrieving Cloud Streaming UI Feb 28 19:23:41 rivo volumio[3185]: info: Getting Tidal Cloud Configuration Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 28 19:23:41 rivo volumio[3185]: info: Getting Qobuz Cloud Configuration Feb 28 19:23:41 rivo volumio[3185]: info: Asking plugin for UI Config Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 28 19:23:41 rivo volumio[3185]: info: Getting Spotify Cloud Configuration Feb 28 19:23:41 rivo volumio[3185]: info: Asking plugin for UI Config Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 28 19:23:41 rivo volumio[3185]: info: Saving Spotify Acccount Feb 28 19:23:41 rivo volumio[3185]: info: Got it Feb 28 19:23:41 rivo volumio[3185]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 28 19:23:41 rivo volumio[3185]: info: Got Tidal Cloud Configuration Feb 28 19:23:41 rivo volumio[3185]: info: Got it Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::volumioGetBrowseSources Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::volumioGetBrowseSources Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::volumioGetBrowseSources Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 28 19:23:41 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 28 19:23:44 rivo volumio[3185]: info: Discovery: Remote connection error: 192.168.11.36 Feb 28 19:23:45 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 28 19:23:45 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 28 19:23:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Feb 28 19:23:47 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Feb 28 19:23:49 rivo volumio[3185]: info: Error browsing device Synology_DS115j for shares : Error: Command failed: echo volumio | smbclient -L Synology_DS115j.local. Feb 28 19:23:49 rivo volumio[3185]: Unable to initialize messaging context Feb 28 19:23:49 rivo volumio[3185]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 28 19:23:52 rivo volumio[3185]: info: Discovery: Remote connection error: 192.168.11.36 Feb 28 19:23:52 rivo volumio[3185]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 28 19:23:52 rivo volumio[3185]: Error: dns service error: unknown Feb 28 19:23:52 rivo volumio[3185]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Feb 28 19:23:52 rivo volumio[3185]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Feb 28 19:23:52 rivo volumio[3185]: errorCode: -65537 Feb 28 19:23:52 rivo volumio[3185]: } Feb 28 19:23:52 rivo volumio[3185]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 28 19:23:53 rivo sudo[17046]: volumio : unable to resolve host rivo Feb 28 19:23:53 rivo sudo[17046]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 28 19:23:53 rivo sudo[17046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-28 19:22 Feb 28 19:23:53 rivo sudo[17046]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 05:04:03 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e66dc85ea2c87aca7db72b97d00dff"