-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Wed 2025-07-09 16:27:47 CST. -- Jul 09 16:26:05 smsl-n100 volumio[1196]: info: Preload queue cleared Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::ClearQueue Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::stop Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::stPlaybackTimer Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::updateTrackBlock Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CorePlayQueue::getTrackBlock Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::pushState Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CorePlayQueue::getTrack 0 Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioPushState Jul 09 16:26:05 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Jul 09 16:26:05 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::serviceStop Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CorePlayQueue::getTrack 0 Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::serviceStop Jul 09 16:26:05 smsl-n100 volumio[1196]: info: [1752049565140] ControllerTidal::stop Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 16:26:05 smsl-n100 volumio[1196]: info: ControllerMpd::stop Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CorePlayQueue::clearPlayQueue Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CorePlayQueue::saveQueue Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioPushQueue Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::addQueueItems Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CorePlayQueue::addQueueItems Jul 09 16:26:05 smsl-n100 volumio[1196]: info: Preload queue cleared Jul 09 16:26:05 smsl-n100 volumio[1196]: info: Adding Item to queue: tidal://song/77702761 Jul 09 16:26:05 smsl-n100 volumio[1196]: info: Using cached record of: tidal://song/77702761 Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioPushQueue Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CorePlayQueue::saveQueue Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::updateTrackBlock Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CorePlayQueue::getTrackBlock Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioPlay Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::play index 0 Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::stop Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::play index undefined Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CorePlayQueue::getTrack 0 Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::startPlaybackTimer Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CorePlayQueue::getTrack 0 Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioGetVisibleSources Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 09 16:26:05 smsl-n100 volumio[1196]: info: [1752049565213] ControllerTidal::clearAddPlayTrack Jul 09 16:26:05 smsl-n100 volumio[1196]: info: Getting stream with soundQuality HI_RES Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: ControllerMpd::sendMpdCommand stop Jul 09 16:26:05 smsl-n100 volumio[1196]: info: Jul 09 16:26:05 smsl-n100 volumio[1196]: ---------------------------- MPD announces state update: player Jul 09 16:26:05 smsl-n100 volumio[1196]: info: sendMpdCommand stop took 88 milliseconds Jul 09 16:26:05 smsl-n100 volumio[1196]: info: ControllerMpd::getState Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Jul 09 16:26:05 smsl-n100 volumio[1196]: info: sendMpdCommand stop took 18 milliseconds Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: ControllerMpd::sendMpdCommand clear Jul 09 16:26:05 smsl-n100 volumio[1196]: info: Jul 09 16:26:05 smsl-n100 volumio[1196]: ---------------------------- MPD announces system playlist update Jul 09 16:26:05 smsl-n100 volumio[1196]: info: Ignoring MPD Status Update Jul 09 16:26:05 smsl-n100 volumio[1196]: info: sendMpdCommand status took 7 milliseconds Jul 09 16:26:05 smsl-n100 volumio[1196]: info: sendMpdCommand clear took 5 milliseconds Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: ControllerMpd::parseState Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/77702761&soundQuality=HI_RES" Jul 09 16:26:05 smsl-n100 volumio[1196]: error: updateQueue error: null Jul 09 16:26:05 smsl-n100 volumio[1196]: info: Jul 09 16:26:05 smsl-n100 volumio[1196]: ---------------------------- MPD announces system playlist update Jul 09 16:26:05 smsl-n100 volumio[1196]: info: Ignoring MPD Status Update Jul 09 16:26:05 smsl-n100 volumio[1196]: info: ------------------------------ 14ms Jul 09 16:26:05 smsl-n100 volumio[1196]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 09 16:26:05 smsl-n100 volumio[1196]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/77702761&soundQuality=HI_RES" took 9 milliseconds Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: ControllerMpd::parseTrackInfo Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: ControllerMpd::sendMpdCommand play Jul 09 16:26:05 smsl-n100 volumio[1196]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 09 16:26:05 smsl-n100 volumio[1196]: info: ------------------------------ 30ms Jul 09 16:26:05 smsl-n100 volumio[1196]: info: ------------------------------ 14ms Jul 09 16:26:05 smsl-n100 volumio[1196]: info: sendMpdCommand play took 8 milliseconds Jul 09 16:26:05 smsl-n100 volumio[1196]: STREAMING PROXY: Handling url /?data=tidal://song/77702761&soundQuality=HI_RES Jul 09 16:26:05 smsl-n100 volumio[1196]: info: Executing endpoint getStreamUrltidal Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::pushState Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioPushState Jul 09 16:26:05 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Jul 09 16:26:05 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output Jul 09 16:26:05 smsl-n100 volumio[1196]: error: Serial API: Failed to decode command: 100, message: undefined Jul 09 16:26:05 smsl-n100 volumio[1196]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 09 16:26:05 smsl-n100 volumio[1196]: error: Serial API: Failed to decode command: 100, message: undefined Jul 09 16:26:05 smsl-n100 volumio[1196]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::pushState Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioPushState Jul 09 16:26:05 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Jul 09 16:26:05 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output Jul 09 16:26:05 smsl-n100 volumio[1196]: error: Serial API: Failed to decode command: 100, message: undefined Jul 09 16:26:05 smsl-n100 volumio[1196]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 09 16:26:05 smsl-n100 volumio[1196]: error: Serial API: Failed to decode command: 100, message: undefined Jul 09 16:26:05 smsl-n100 volumio[1196]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 09 16:26:05 smsl-n100 volumio[1196]: info: getStreamUrl took 419 milliseconds Jul 09 16:26:05 smsl-n100 volumio[1196]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidjZTliZWEyOTE1Nzc0ZGNhZmMxNGU2OTI1ZmFmYTFhZF82Mi5tcDQ/0.flac?token=1752053165~NGE3NmUyODliZGQ3NjczNzRkN2RlNTRiMGQyZWFlNWFjOTZhY2JmNg== Jul 09 16:26:05 smsl-n100 volumio[1196]: STREAMING PROXY: Response: 200, length: 98791854 Jul 09 16:26:05 smsl-n100 volumio[1196]: info: Jul 09 16:26:05 smsl-n100 volumio[1196]: ---------------------------- MPD announces state update: player Jul 09 16:26:05 smsl-n100 volumio[1196]: info: ControllerMpd::getState Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Jul 09 16:26:05 smsl-n100 volumio[1196]: info: Jul 09 16:26:05 smsl-n100 volumio[1196]: ---------------------------- MPD announces state update: player Jul 09 16:26:05 smsl-n100 volumio[1196]: info: sendMpdCommand status took 26 milliseconds Jul 09 16:26:05 smsl-n100 volumio[1196]: info: ControllerMpd::getState Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: ControllerMpd::parseState Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 16:26:05 smsl-n100 volumio[1196]: info: sendMpdCommand status took 20 milliseconds Jul 09 16:26:05 smsl-n100 volumio[1196]: info: sendMpdCommand playlistinfo took 13 milliseconds Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: ControllerMpd::parseState Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: ControllerMpd::parseTrackInfo Jul 09 16:26:05 smsl-n100 volumio[1196]: info: ControllerMpd::pushState Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::servicePushState Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CorePlayQueue::getTrack 0 Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"77702761&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/77702761&soundQuality=HI_RES","trackType":"tidal"} Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: CURRENT POSITION 0 Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::syncState stateService play Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::syncState currentStatus stop Jul 09 16:26:05 smsl-n100 volumio[1196]: info: ------------------------------ 103ms Jul 09 16:26:05 smsl-n100 volumio[1196]: info: sendMpdCommand playlistinfo took 30 milliseconds Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: ControllerMpd::parseTrackInfo Jul 09 16:26:05 smsl-n100 volumio[1196]: info: ControllerMpd::pushState Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::servicePushState Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CorePlayQueue::getTrack 0 Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"77702761&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/77702761&soundQuality=HI_RES","trackType":"tidal"} Jul 09 16:26:05 smsl-n100 volumio[1196]: verbose: CURRENT POSITION 0 Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::syncState stateService play Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::syncState currentStatus play Jul 09 16:26:05 smsl-n100 volumio[1196]: info: Received an update from plugin. extracting info from payload Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::pushState Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioPushState Jul 09 16:26:05 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Jul 09 16:26:05 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreStateMachine::pushState Jul 09 16:26:05 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioPushState Jul 09 16:26:05 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Jul 09 16:26:05 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output Jul 09 16:26:06 smsl-n100 volumio[1196]: info: ------------------------------ 264ms Jul 09 16:26:09 smsl-n100 volumio[1196]: STREAMING PROXY: Client dropped request, destroying Jul 09 16:26:10 smsl-n100 ntpd[981]: Soliciting pool server 111.90.158.16 Jul 09 16:26:12 smsl-n100 ntpd[981]: Soliciting pool server 47.254.196.78 Jul 09 16:26:18 smsl-n100 kernel: usb 4-1.1: USB disconnect, device number 14 Jul 09 16:26:18 smsl-n100 kernel: usb 4-1.1: cannot submit urb (err = -19) Jul 09 16:26:18 smsl-n100 volumio[1196]: info: Jul 09 16:26:18 smsl-n100 volumio[1196]: ---------------------------- MPD announces state update: player Jul 09 16:26:18 smsl-n100 volumio[1196]: info: ControllerMpd::getState Jul 09 16:26:18 smsl-n100 volumio[1196]: verbose: ControllerMpd::sendMpdCommand status Jul 09 16:26:18 smsl-n100 volumio[1196]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 09 16:26:18 smsl-n100 volumio[1196]: info: sendMpdCommand status took 18 milliseconds Jul 09 16:26:18 smsl-n100 volumio[1196]: verbose: ControllerMpd::parseState Jul 09 16:26:18 smsl-n100 volumio[1196]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 09 16:26:18 smsl-n100 volumio[1196]: info: sendMpdCommand clearerror took 7 milliseconds Jul 09 16:26:18 smsl-n100 volumio[1196]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 09 16:26:18 smsl-n100 volumio[1196]: verbose: ControllerMpd::parseTrackInfo Jul 09 16:26:18 smsl-n100 volumio[1196]: info: ControllerMpd::pushState Jul 09 16:26:18 smsl-n100 volumio[1196]: info: CoreCommandRouter::servicePushState Jul 09 16:26:18 smsl-n100 volumio[1196]: info: CorePlayQueue::getTrack 0 Jul 09 16:26:18 smsl-n100 volumio[1196]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14201,"duration":270,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"77702761&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/77702761&soundQuality=HI_RES","trackType":"tidal"} Jul 09 16:26:18 smsl-n100 volumio[1196]: verbose: CURRENT POSITION 0 Jul 09 16:26:18 smsl-n100 volumio[1196]: info: CoreStateMachine::syncState stateService pause Jul 09 16:26:18 smsl-n100 volumio[1196]: info: CoreStateMachine::syncState currentStatus play Jul 09 16:26:18 smsl-n100 volumio[1196]: info: CoreStateMachine::pushState Jul 09 16:26:18 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:26:18 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioPushState Jul 09 16:26:18 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Jul 09 16:26:18 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output Jul 09 16:26:18 smsl-n100 volumio[1196]: info: CoreStateMachine::stPlaybackTimer Jul 09 16:26:18 smsl-n100 volumio[1196]: info: ------------------------------ 77ms Jul 09 16:26:18 smsl-n100 volumio[1196]: info: Jul 09 16:26:18 smsl-n100 volumio[1196]: ---------------------------- USB Audio Device Detached Jul 09 16:26:18 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jul 09 16:26:18 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 09 16:26:18 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jul 09 16:26:18 smsl-n100 volumio[1196]: info: CoreStateMachine::pushState Jul 09 16:26:18 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:26:18 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioPushState Jul 09 16:26:18 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Jul 09 16:26:18 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output Jul 09 16:26:18 smsl-n100 volumio[1196]: error: Serial API: Failed to decode command: 100, message: undefined Jul 09 16:26:18 smsl-n100 volumio[1196]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 09 16:26:18 smsl-n100 volumio[1196]: error: Serial API: Failed to decode command: 100, message: undefined Jul 09 16:26:18 smsl-n100 volumio[1196]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 09 16:26:19 smsl-n100 kernel: usb 4-1.1: new high-speed USB device number 15 using xhci-hcd Jul 09 16:26:19 smsl-n100 kernel: usb 4-1.1: New USB device found, idVendor=152a, idProduct=85de, bcdDevice= 6.f2 Jul 09 16:26:19 smsl-n100 kernel: usb 4-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jul 09 16:26:19 smsl-n100 kernel: usb 4-1.1: Product: VMV D1 USB2.0 Jul 09 16:26:19 smsl-n100 kernel: usb 4-1.1: Manufacturer: VMV Jul 09 16:26:20 smsl-n100 kernel: usb 4-1.1: 1:3 : unsupported format bits 0x100000000 Jul 09 16:26:21 smsl-n100 volumio[1196]: info: Jul 09 16:26:21 smsl-n100 volumio[1196]: ---------------------------- USB Audio Device Attached Jul 09 16:26:21 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jul 09 16:26:21 smsl-n100 volumio[1196]: info: CoreCommandRouter::Close All Modals sent Jul 09 16:26:21 smsl-n100 volumio[1196]: info: Preparing to save Alsa Options, stopping services first Jul 09 16:26:21 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioGetState Jul 09 16:26:21 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioPause Jul 09 16:26:21 smsl-n100 volumio[1196]: info: CoreStateMachine::pause Jul 09 16:26:21 smsl-n100 volumio[1196]: info: CoreStateMachine::stPlaybackTimer Jul 09 16:26:21 smsl-n100 volumio[1196]: info: CoreStateMachine::servicePause Jul 09 16:26:21 smsl-n100 volumio[1196]: info: CorePlayQueue::getTrack 0 Jul 09 16:26:21 smsl-n100 volumio[1196]: info: CoreCommandRouter::servicePause Jul 09 16:26:21 smsl-n100 volumio[1196]: info: [1752049581464] ControllerTidal::pause Jul 09 16:26:21 smsl-n100 volumio[1196]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 09 16:26:21 smsl-n100 volumio[1196]: info: ControllerMpd::pause Jul 09 16:26:21 smsl-n100 volumio[1196]: verbose: ControllerMpd::sendMpdCommand pause Jul 09 16:26:21 smsl-n100 volumio[1196]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"VMV D1 USB2.0","alsacard":"USB20"},"i2s":false} Jul 09 16:26:21 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 09 16:26:21 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 09 16:26:21 smsl-n100 volumio[1196]: info: Setting mixer VMV D1 USB2.0 for card VMV D1 USB2.0 Jul 09 16:26:21 smsl-n100 vtcs[26435]: [2025-07-09 16:26:21.793] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Jul 09 16:26:21 smsl-n100 vtcs[26435]: [2025-07-09 16:26:21.794] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Jul 09 16:26:21 smsl-n100 sudo[31651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 09 16:26:21 smsl-n100 sudo[31651]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:21 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service... Jul 09 16:26:21 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jul 09 16:26:21 smsl-n100 systemd[1]: vtcs.service: Succeeded. Jul 09 16:26:21 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service. Jul 09 16:26:21 smsl-n100 sudo[31651]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:21 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 09 16:26:21 smsl-n100 volumio[1196]: info: Updating Volume Controller Parameters: Device: 5 Name: VMV D1 USB2.0 Mixer: VMV D1 USB2.0 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 09 16:26:21 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 09 16:26:21 smsl-n100 volumio[1196]: info: Disabling external Volume Control Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 09 16:26:22 smsl-n100 volumio[1196]: info: Preparing to generate the ALSA configuration file Jul 09 16:26:22 smsl-n100 volumio[1196]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 09 16:26:22 smsl-n100 volumio[1196]: info: Reading ALSA contributions from plugins. Jul 09 16:26:22 smsl-n100 sudo[31659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 09 16:26:22 smsl-n100 sudo[31659]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:22 smsl-n100 sudo[31659]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 09 16:26:22 smsl-n100 volumio[1196]: info: VolumeController:: Volume=100 Mute =false Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreStateMachine::pushState Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioPushState Jul 09 16:26:22 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Jul 09 16:26:22 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jul 09 16:26:22 smsl-n100 volumio[1196]: info: Asound.conf file unchanged, so no further update is needed Jul 09 16:26:22 smsl-n100 volumio[1196]: info: Output device has changed, restarting MPD Jul 09 16:26:22 smsl-n100 sudo[31668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 09 16:26:22 smsl-n100 sudo[31668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:22 smsl-n100 sudo[31668]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:22 smsl-n100 volumio[1196]: info: Output device has changed, restarting Shairport Sync Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 16:26:22 smsl-n100 sudo[31671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 09 16:26:22 smsl-n100 sudo[31671]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:22 smsl-n100 systemd[1]: Stopping Music Player Daemon... Jul 09 16:26:22 smsl-n100 volumio[1196]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 09 16:26:22 smsl-n100 volumio[1196]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:22 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:23 smsl-n100 sudo[31679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 09 16:26:23 smsl-n100 sudo[31679]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:23 smsl-n100 volumio[1196]: info: sendMpdCommand pause took 1593 milliseconds Jul 09 16:26:23 smsl-n100 volumio[1196]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jul 09 16:26:23 smsl-n100 volumio[1196]: info: MPD Permissions set Jul 09 16:26:23 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:23 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:23 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:23 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:23 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:23 smsl-n100 sudo[31679]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:23 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:23 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:23 smsl-n100 sudo[31682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 09 16:26:23 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:23 smsl-n100 sudo[31682]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:23 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 16:26:23 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 09 16:26:23 smsl-n100 sudo[31682]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:23 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:23 smsl-n100 volumio[1196]: info: Starting Shairport Sync Jul 09 16:26:23 smsl-n100 volumio[1196]: info: Preparing to generate the ALSA configuration file Jul 09 16:26:23 smsl-n100 sudo[31707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 09 16:26:23 smsl-n100 sudo[31707]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:23 smsl-n100 volumio[1196]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 09 16:26:23 smsl-n100 volumio[1196]: info: Reading ALSA contributions from plugins. Jul 09 16:26:23 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 09 16:26:23 smsl-n100 volumio[1196]: info: Asound.conf file written Jul 09 16:26:23 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Jul 09 16:26:23 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 09 16:26:23 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 09 16:26:23 smsl-n100 sudo[31707]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:23 smsl-n100 sudo[31711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 09 16:26:23 smsl-n100 sudo[31711]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:23 smsl-n100 sudo[31711]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:23 smsl-n100 systemd[1]: mpd.service: Succeeded. Jul 09 16:26:23 smsl-n100 volumio[1196]: info: Output device has changed, restarting MPD Jul 09 16:26:23 smsl-n100 systemd[1]: Stopped Music Player Daemon. Jul 09 16:26:23 smsl-n100 systemd[1]: Starting Music Player Daemon... Jul 09 16:26:23 smsl-n100 volumio[1196]: info: Output device has changed, restarting Shairport Sync Jul 09 16:26:23 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:23 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 16:26:24 smsl-n100 volumio[1196]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 09 16:26:24 smsl-n100 volumio[1196]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 09 16:26:24 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:24 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:24 smsl-n100 volumio[1196]: info: Shairport-Sync Started Jul 09 16:26:24 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:24 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:24 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:24 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:24 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:24 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:24 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:24 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:24 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 16:26:24 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 09 16:26:24 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:24 smsl-n100 volumio[1196]: info: Starting Shairport Sync Jul 09 16:26:24 smsl-n100 volumio[1196]: info: Preparing to generate the ALSA configuration file Jul 09 16:26:24 smsl-n100 volumio[1196]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 09 16:26:24 smsl-n100 volumio[1196]: info: Reading ALSA contributions from plugins. Jul 09 16:26:24 smsl-n100 volumio[1196]: info: Asound.conf file written Jul 09 16:26:24 smsl-n100 sudo[31722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 09 16:26:24 smsl-n100 sudo[31722]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:24 smsl-n100 sudo[31720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 09 16:26:24 smsl-n100 sudo[31720]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:24 smsl-n100 sudo[31741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 09 16:26:24 smsl-n100 sudo[31741]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:24 smsl-n100 sudo[31739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 09 16:26:24 smsl-n100 sudo[31720]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:24 smsl-n100 sudo[31731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 09 16:26:24 smsl-n100 sudo[31739]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:24 smsl-n100 sudo[31718]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 09 16:26:24 smsl-n100 sudo[31729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 09 16:26:24 smsl-n100 sudo[31718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:24 smsl-n100 sudo[31731]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:24 smsl-n100 sudo[31729]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:24 smsl-n100 sudo[31718]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:24 smsl-n100 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 09 16:26:24 smsl-n100 sudo[31741]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:24 smsl-n100 systemd[1]: mpd.service: Succeeded. Jul 09 16:26:24 smsl-n100 systemd[1]: Stopped Music Player Daemon. Jul 09 16:26:24 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 09 16:26:24 smsl-n100 systemd[1]: Starting Music Player Daemon... Jul 09 16:26:24 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Jul 09 16:26:24 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 09 16:26:24 smsl-n100 sudo[31729]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:24 smsl-n100 volumio[1196]: info: Output device has changed, restarting MPD Jul 09 16:26:24 smsl-n100 sudo[31731]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:24 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 09 16:26:25 smsl-n100 sudo[31739]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:25 smsl-n100 sudo[31758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 09 16:26:25 smsl-n100 volumio[1196]: info: Output device has changed, restarting Shairport Sync Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 16:26:25 smsl-n100 sudo[31758]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:25 smsl-n100 sudo[31758]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:25 smsl-n100 sudo[31753]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 09 16:26:25 smsl-n100 sudo[31753]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:25 smsl-n100 sudo[31761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 09 16:26:25 smsl-n100 sudo[31761]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:25 smsl-n100 sudo[31753]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:25 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 09 16:26:25 smsl-n100 systemd[1]: mpd.service: Succeeded. Jul 09 16:26:25 smsl-n100 systemd[1]: Stopped Music Player Daemon. Jul 09 16:26:25 smsl-n100 systemd[1]: Starting Music Player Daemon... Jul 09 16:26:25 smsl-n100 volumio[1196]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 09 16:26:25 smsl-n100 volumio[1196]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:25 smsl-n100 sudo[31770]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 09 16:26:25 smsl-n100 sudo[31770]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:25 smsl-n100 sudo[31774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 09 16:26:25 smsl-n100 sudo[31774]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:25 smsl-n100 sudo[31770]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:25 smsl-n100 volumio[1196]: info: MPD Permissions set Jul 09 16:26:25 smsl-n100 volumio[1196]: info: TidalConnect service stoped! Jul 09 16:26:25 smsl-n100 sudo[31779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 09 16:26:25 smsl-n100 sudo[31779]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:25 smsl-n100 sudo[31774]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:25 smsl-n100 volumio[1196]: info: Shairport-Sync Started Jul 09 16:26:25 smsl-n100 volumio[1196]: info: TidalConnect service stoped! Jul 09 16:26:25 smsl-n100 volumio[1196]: info: MPD Permissions set Jul 09 16:26:25 smsl-n100 sudo[31779]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 09 16:26:25 smsl-n100 volumio[1196]: info: Discovery: Getting this device information Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioGetState Jul 09 16:26:25 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 09 16:26:25 smsl-n100 volumio[1196]: info: Starting Shairport Sync Jul 09 16:26:26 smsl-n100 volumio[1196]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 09 16:26:26 smsl-n100 volumio[1196]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 09 16:26:26 smsl-n100 sudo[31798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 09 16:26:26 smsl-n100 sudo[31798]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:26 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 09 16:26:26 smsl-n100 volumio[1196]: info: CoreStateMachine::getcurrentVolume Jul 09 16:26:26 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioRetrievevolume Jul 09 16:26:26 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 09 16:26:26 smsl-n100 sudo[31801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 09 16:26:26 smsl-n100 sudo[31801]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:26 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Jul 09 16:26:26 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 09 16:26:26 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 09 16:26:26 smsl-n100 sudo[31798]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:26 smsl-n100 volumio[1196]: info: TidalConnect service stoped! Jul 09 16:26:26 smsl-n100 volumio[1196]: info: TidalConnect service stoped! Jul 09 16:26:26 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Jul 09 16:26:26 smsl-n100 sudo[31801]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:26 smsl-n100 volumio[1196]: info: Shairport-Sync Started Jul 09 16:26:26 smsl-n100 volumio[1196]: info: VolumeController:: Volume=100 Mute =false Jul 09 16:26:26 smsl-n100 volumio[1196]: info: CoreStateMachine::pushState Jul 09 16:26:26 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioPushState Jul 09 16:26:26 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Jul 09 16:26:26 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output Jul 09 16:26:26 smsl-n100 volumio[1196]: info: CoreStateMachine::updateTrackBlock Jul 09 16:26:26 smsl-n100 volumio[1196]: info: CorePlayQueue::getTrackBlock Jul 09 16:26:26 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioRetrievevolume Jul 09 16:26:26 smsl-n100 volumio[1196]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 09 16:26:26 smsl-n100 volumio[1196]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 09 16:26:26 smsl-n100 sudo[31821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 09 16:26:26 smsl-n100 sudo[31821]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:26 smsl-n100 sudo[31821]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:26 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 09 16:26:26 smsl-n100 volumio[1196]: info: VolumeController:: Setting startup Volume 100 Jul 09 16:26:26 smsl-n100 volumio[1196]: info: VolumeController::SetAlsaVolume100 Jul 09 16:26:27 smsl-n100 volumio[1196]: info: CoreStateMachine::pushState Jul 09 16:26:27 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioPushState Jul 09 16:26:27 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Jul 09 16:26:27 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output Jul 09 16:26:27 smsl-n100 volumio[1196]: info: VolumeController:: Volume=100 Mute =false Jul 09 16:26:27 smsl-n100 volumio[1196]: info: CoreStateMachine::pushState Jul 09 16:26:27 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioPushState Jul 09 16:26:27 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Jul 09 16:26:27 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output Jul 09 16:26:27 smsl-n100 volumio[1196]: info: Executing endpoint tc_getconfig Jul 09 16:26:27 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 09 16:26:27 smsl-n100 vtcs[31805]: STARTING TidalConnect services, version: 1.4.0.34 Jul 09 16:26:27 smsl-n100 vtcs[31805]: STARTED TidalConnect services. Jul 09 16:26:27 smsl-n100 volumio[1196]: info: Executing endpoint tc_connect Jul 09 16:26:27 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 09 16:26:27 smsl-n100 volumio[1196]: info: Connecting to TidalConnect Jul 09 16:26:27 smsl-n100 volumio[1196]: info: CoreCommandRouter::servicePushState Jul 09 16:26:27 smsl-n100 volumio[1196]: info: CoreStateMachine::pushState Jul 09 16:26:27 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioPushState Jul 09 16:26:27 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Jul 09 16:26:27 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output Jul 09 16:26:27 smsl-n100 volumio[1196]: info: CorePlayQueue::getTrack 0 Jul 09 16:26:27 smsl-n100 volumio[1196]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Jul 09 16:26:27 smsl-n100 volumio[1196]: info: CoreCommandRouter::servicePushState Jul 09 16:26:27 smsl-n100 volumio[1196]: info: CoreStateMachine::pushState Jul 09 16:26:27 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioPushState Jul 09 16:26:27 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Jul 09 16:26:27 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output Jul 09 16:26:27 smsl-n100 volumio[1196]: info: CorePlayQueue::getTrack 0 Jul 09 16:26:27 smsl-n100 volumio[1196]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Jul 09 16:26:27 smsl-n100 volumio[1196]: info: TidalConnect service stoped! Jul 09 16:26:27 smsl-n100 volumio[1196]: info: TidalConnect service stoped! Jul 09 16:26:28 smsl-n100 volumio[1196]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 09 16:26:28 smsl-n100 volumio[1196]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 09 16:26:28 smsl-n100 sudo[31854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 09 16:26:28 smsl-n100 sudo[31854]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:28 smsl-n100 sudo[31854]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:28 smsl-n100 volumio[1196]: info: TidalConnect service stoped! Jul 09 16:26:28 smsl-n100 volumio[1196]: info: TidalConnect service stoped! Jul 09 16:26:28 smsl-n100 volumio[1196]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 09 16:26:28 smsl-n100 volumio[1196]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 09 16:26:28 smsl-n100 sudo[31866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 09 16:26:28 smsl-n100 sudo[31866]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:28 smsl-n100 sudo[31866]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:29 smsl-n100 volumio[1196]: info: TidalConnect service started! Jul 09 16:26:29 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 09 16:26:29 smsl-n100 systemd[1]: mpd.service: Failed with result 'protocol'. Jul 09 16:26:29 smsl-n100 systemd[1]: Failed to start Music Player Daemon. Jul 09 16:26:29 smsl-n100 sudo[31671]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:29 smsl-n100 sudo[31761]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:29 smsl-n100 sudo[31722]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:29 smsl-n100 volumio[1196]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Jul 09 16:26:29 smsl-n100 volumio[1196]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Jul 09 16:26:29 smsl-n100 volumio[1196]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jul 09 16:26:29 smsl-n100 volumio[1196]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Jul 09 16:26:29 smsl-n100 volumio[1196]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Jul 09 16:26:29 smsl-n100 volumio[1196]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jul 09 16:26:29 smsl-n100 volumio[1196]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Jul 09 16:26:29 smsl-n100 volumio[1196]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Jul 09 16:26:29 smsl-n100 volumio[1196]: See "systemctl status mpd.service" and "journalctl -xe" for details. Jul 09 16:26:29 smsl-n100 systemd[1]: Starting Music Player Daemon... Jul 09 16:26:29 smsl-n100 sudo[31871]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 09 16:26:29 smsl-n100 sudo[31871]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 09 16:26:29 smsl-n100 sudo[31871]: pam_unix(sudo:session): session closed for user root Jul 09 16:26:29 smsl-n100 volumio[1196]: info: TidalConnect service started! Jul 09 16:26:31 smsl-n100 volumio[1196]: info: TidalConnect service started! Jul 09 16:26:32 smsl-n100 volumio[1196]: info: TidalConnect service started! Jul 09 16:26:32 smsl-n100 mpd[31873]: Jul 09 16:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 09 16:26:37 smsl-n100 systemd[1]: Started Music Player Daemon. Jul 09 16:26:37 smsl-n100 volumio[1196]: Upnp client error: Error: This socket has been ended by the other party Jul 09 16:26:37 smsl-n100 mpd_monitor.sh[548]: MPD restarted due to no mpc output. Jul 09 16:26:37 smsl-n100 volumio[1196]: error: MPD error: The expression evaluated to a falsy value: Jul 09 16:26:37 smsl-n100 volumio[1196]: assert.ok(self.idling) Jul 09 16:26:37 smsl-n100 volumio[1196]: error: The expression evaluated to a falsy value: Jul 09 16:26:37 smsl-n100 volumio[1196]: assert.ok(self.idling) Jul 09 16:26:37 smsl-n100 volumio[1196]: Upnp client error: Error: This socket has been ended by the other party Jul 09 16:26:37 smsl-n100 volumio[1196]: error: MPD error: The expression evaluated to a falsy value: Jul 09 16:26:37 smsl-n100 volumio[1196]: assert.ok(self.idling) Jul 09 16:26:37 smsl-n100 volumio[1196]: error: The expression evaluated to a falsy value: Jul 09 16:26:37 smsl-n100 volumio[1196]: assert.ok(self.idling) Jul 09 16:26:37 smsl-n100 volumio[1196]: Upnp client error: Error: This socket has been ended by the other party Jul 09 16:26:37 smsl-n100 volumio[1196]: Upnp client error: Error: This socket has been ended by the other party Jul 09 16:26:37 smsl-n100 volumio[1196]: error: updateQueue error: null Jul 09 16:26:49 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Down Jul 09 16:26:49 smsl-n100 dhcpcd[939]: eth0: carrier lost Jul 09 16:26:49 smsl-n100 dhcpcd[982]: eth0: carrier lost Jul 09 16:26:49 smsl-n100 avahi-daemon[542]: Withdrawing address record for 192.168.1.43 on eth0. Jul 09 16:26:49 smsl-n100 avahi-daemon[542]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.43. Jul 09 16:26:49 smsl-n100 avahi-daemon[542]: Interface eth0.IPv4 no longer relevant for mDNS. Jul 09 16:26:49 smsl-n100 dhcpcd[982]: eth0: deleting route to 192.168.1.0/24 Jul 09 16:26:49 smsl-n100 dhcpcd[982]: eth0: deleting default route via 192.168.1.254 Jul 09 16:26:49 smsl-n100 volumio[1196]: info: Discovery: A device disappeared from network Jul 09 16:26:49 smsl-n100 dhcpcd[939]: eth0: deleting route to 192.168.1.0/24 Jul 09 16:26:49 smsl-n100 dhcpcd[939]: eth0: deleting default route via 192.168.1.254 Jul 09 16:26:49 smsl-n100 ifplugd(eth0)[787]: Link beat lost. Jul 09 16:26:50 smsl-n100 ntpd[981]: Soliciting pool server 2a01:4f9:c010:1625::1 Jul 09 16:26:51 smsl-n100 ntpd[981]: Deleting interface #3 eth0, 192.168.1.43#123, interface stats: received=180, sent=480, dropped=0, active_time=6852 secs Jul 09 16:26:51 smsl-n100 ntpd[981]: 47.250.183.141 local addr 192.168.1.43 -> Jul 09 16:26:51 smsl-n100 ntpd[981]: 111.90.158.134 local addr 192.168.1.43 -> Jul 09 16:26:51 smsl-n100 ntpd[981]: 103.16.182.23 local addr 192.168.1.43 -> Jul 09 16:26:51 smsl-n100 ntpd[981]: 38.60.193.17 local addr 192.168.1.43 -> Jul 09 16:26:53 smsl-n100 volumio[1196]: info: Reporting MCU Network Status: 0 Jul 09 16:26:53 smsl-n100 volumio[1196]: info: Volumio Network Manager: Network status updated: 0 Jul 09 16:26:53 smsl-n100 volumio[1196]: info: CoreStateMachine::pushState Jul 09 16:26:53 smsl-n100 volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 09 16:26:53 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioPushState Jul 09 16:26:53 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output update for this device Jul 09 16:26:53 smsl-n100 volumio[1196]: info: MRS: Pushing multiroomSync output Jul 09 16:26:53 smsl-n100 volumio[1196]: error: Serial API: Failed to decode command: 100, message: undefined Jul 09 16:26:53 smsl-n100 volumio[1196]: error: Serial API: Failed to decode command: MAXVOL, message: Jul 09 16:26:53 smsl-n100 volumio[1196]: error: Serial API: Failed to decode command: 100, message: undefined Jul 09 16:26:53 smsl-n100 volumio[1196]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 09 16:26:53 smsl-n100 volumio[1196]: error: Serial API: Failed to decode command: 0, message: undefined Jul 09 16:26:59 smsl-n100 ifplugd(eth0)[787]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Jul 09 16:26:59 smsl-n100 ifplugd(eth0)[787]: client: sending commands to master dhcpcd process Jul 09 16:26:59 smsl-n100 dhcpcd[32066]: sending commands to master dhcpcd process Jul 09 16:26:59 smsl-n100 dhcpcd[939]: control command: /sbin/dhcpcd -k eth0 Jul 09 16:26:59 smsl-n100 dhcpcd[939]: eth0: removing interface Jul 09 16:26:59 smsl-n100 ifplugd(eth0)[787]: Program executed successfully. Jul 09 16:26:59 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PHY [stmmac-0:01] driver [Generic PHY] Jul 09 16:26:59 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: No Safety Features support found Jul 09 16:26:59 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: PTP not supported by HW Jul 09 16:26:59 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: configuring for phy/rgmii link mode Jul 09 16:27:09 smsl-n100 nmbd[770]: [2025/07/09 16:27:09.904037, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Jul 09 16:27:09 smsl-n100 nmbd[770]: reload_interfaces: No subnets to listen to. Waiting.. Jul 09 16:27:14 smsl-n100 ntpd[981]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 09 16:27:19 smsl-n100 ntpd[981]: Soliciting pool server 111.90.158.16 Jul 09 16:27:35 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Jul 09 16:27:35 smsl-n100 dhcpcd[982]: eth0: carrier acquired Jul 09 16:27:35 smsl-n100 dhcpcd[982]: eth0: IAID d8:37:3d:c1 Jul 09 16:27:35 smsl-n100 ifplugd(eth0)[787]: Link beat detected. Jul 09 16:27:35 smsl-n100 ifplugd(eth0)[787]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jul 09 16:27:35 smsl-n100 ifplugd(eth0)[787]: client: sending commands to master dhcpcd process Jul 09 16:27:35 smsl-n100 dhcpcd[32243]: sending commands to master dhcpcd process Jul 09 16:27:35 smsl-n100 dhcpcd[939]: control command: /sbin/dhcpcd eth0 Jul 09 16:27:35 smsl-n100 dhcpcd[939]: eth0: carrier acquired Jul 09 16:27:35 smsl-n100 dhcpcd[939]: eth0: IAID d8:37:3d:c1 Jul 09 16:27:35 smsl-n100 dhcpcd[939]: eth0: soliciting an IPv6 router Jul 09 16:27:35 smsl-n100 dhcpcd[982]: eth0: soliciting an IPv6 router Jul 09 16:27:36 smsl-n100 ifplugd(eth0)[787]: Program executed successfully. Jul 09 16:27:36 smsl-n100 dhcpcd[982]: eth0: soliciting a DHCP lease Jul 09 16:27:36 smsl-n100 dhcpcd[939]: eth0: soliciting a DHCP lease Jul 09 16:27:40 smsl-n100 dhcpcd[982]: eth0: offered 192.168.1.43 from 192.168.1.254 Jul 09 16:27:40 smsl-n100 dhcpcd[982]: eth0: probing address 192.168.1.43/24 Jul 09 16:27:40 smsl-n100 dhcpcd[939]: eth0: offered 192.168.1.43 from 192.168.1.254 Jul 09 16:27:41 smsl-n100 dhcpcd[939]: eth0: probing address 192.168.1.43/24 Jul 09 16:27:45 smsl-n100 dhcpcd[939]: eth0: leased 192.168.1.43 for 86400 seconds Jul 09 16:27:45 smsl-n100 dhcpcd[939]: eth0: adding route to 192.168.1.0/24 Jul 09 16:27:45 smsl-n100 avahi-daemon[542]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.43. Jul 09 16:27:45 smsl-n100 avahi-daemon[542]: New relevant interface eth0.IPv4 for mDNS. Jul 09 16:27:45 smsl-n100 dhcpcd[939]: eth0: adding default route via 192.168.1.254 Jul 09 16:27:45 smsl-n100 avahi-daemon[542]: Registering new address record for 192.168.1.43 on eth0.IPv4. Jul 09 16:27:45 smsl-n100 ntpd[981]: ntpd exiting on signal 15 (Terminated) Jul 09 16:27:45 smsl-n100 systemd[1]: Stopping Network Time Service... Jul 09 16:27:45 smsl-n100 systemd[1]: ntp.service: Succeeded. Jul 09 16:27:45 smsl-n100 systemd[1]: Stopped Network Time Service. Jul 09 16:27:45 smsl-n100 systemd[1]: Starting Network Time Service... Jul 09 16:27:45 smsl-n100 ntpd[32340]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 09 16:27:45 smsl-n100 ntpd[32340]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 09 16:27:45 smsl-n100 systemd[1]: Started Network Time Service. Jul 09 16:27:45 smsl-n100 ntpd[32346]: proto: precision = 1.166 usec (-20) Jul 09 16:27:45 smsl-n100 ntpd[32346]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 09 16:27:45 smsl-n100 ntpd[32346]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 09 16:27:45 smsl-n100 ntpd[32346]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 925 days ago Jul 09 16:27:45 smsl-n100 ntpd[32346]: Listen and drop on 0 v6wildcard [::]:123 Jul 09 16:27:45 smsl-n100 ntpd[32346]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 09 16:27:45 smsl-n100 ntpd[32346]: Listen normally on 2 lo 127.0.0.1:123 Jul 09 16:27:45 smsl-n100 ntpd[32346]: Listen normally on 3 eth0 192.168.1.43:123 Jul 09 16:27:45 smsl-n100 ntpd[32346]: Listening on routing socket on fd #20 for interface updates Jul 09 16:27:45 smsl-n100 ntpd[32346]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jul 09 16:27:45 smsl-n100 ntpd[32346]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jul 09 16:27:45 smsl-n100 dhcpcd[982]: eth0: leased 192.168.1.43 for 86400 seconds Jul 09 16:27:45 smsl-n100 dhcpcd[982]: eth0: adding route to 192.168.1.0/24 Jul 09 16:27:45 smsl-n100 dhcpcd[982]: eth0: adding default route via 192.168.1.254 Jul 09 16:27:45 smsl-n100 ntpd[32346]: ntpd exiting on signal 15 (Terminated) Jul 09 16:27:45 smsl-n100 systemd[1]: Stopping Network Time Service... Jul 09 16:27:45 smsl-n100 systemd[1]: ntp.service: Succeeded. Jul 09 16:27:45 smsl-n100 systemd[1]: Stopped Network Time Service. Jul 09 16:27:45 smsl-n100 systemd[1]: Starting Network Time Service... Jul 09 16:27:46 smsl-n100 ntpd[32379]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 09 16:27:46 smsl-n100 ntpd[32379]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 09 16:27:46 smsl-n100 systemd[1]: Started Network Time Service. Jul 09 16:27:46 smsl-n100 ntpd[32385]: proto: precision = 1.166 usec (-20) Jul 09 16:27:46 smsl-n100 ntpd[32385]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 09 16:27:46 smsl-n100 ntpd[32385]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 09 16:27:46 smsl-n100 ntpd[32385]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 925 days ago Jul 09 16:27:46 smsl-n100 ntpd[32385]: Listen and drop on 0 v6wildcard [::]:123 Jul 09 16:27:46 smsl-n100 ntpd[32385]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 09 16:27:46 smsl-n100 ntpd[32385]: Listen normally on 2 lo 127.0.0.1:123 Jul 09 16:27:46 smsl-n100 ntpd[32385]: Listen normally on 3 eth0 192.168.1.43:123 Jul 09 16:27:46 smsl-n100 ntpd[32385]: Listening on routing socket on fd #20 for interface updates Jul 09 16:27:46 smsl-n100 ntpd[32385]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jul 09 16:27:46 smsl-n100 ntpd[32385]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jul 09 16:27:46 smsl-n100 volumio[1196]: info: Discovery: adding c32a3912-3ad2-4e38-b09f-ae11ecd87622 Jul 09 16:27:46 smsl-n100 volumio[1196]: info: Discovery: Found device SMSL N100 Jul 09 16:27:46 smsl-n100 volumio[1196]: info: CoreCommandRouter::volumioGetState Jul 09 16:27:46 smsl-n100 volumio[1196]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 16:27:46 smsl-n100 volumio[1196]: Error: The value NaN is not a number Jul 09 16:27:46 smsl-n100 volumio[1196]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Jul 09 16:27:46 smsl-n100 volumio[1196]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Jul 09 16:27:46 smsl-n100 volumio[1196]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Jul 09 16:27:46 smsl-n100 volumio[1196]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Jul 09 16:27:46 smsl-n100 volumio[1196]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Jul 09 16:27:46 smsl-n100 volumio[1196]: at Browser.emit (events.js:400:28) Jul 09 16:27:46 smsl-n100 volumio[1196]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Jul 09 16:27:46 smsl-n100 volumio[1196]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Jul 09 16:27:46 smsl-n100 volumio[1196]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Jul 09 16:27:46 smsl-n100 volumio[1196]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Jul 09 16:27:46 smsl-n100 volumio[1196]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Jul 09 16:27:46 smsl-n100 volumio[1196]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Jul 09 16:27:46 smsl-n100 volumio[1196]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Jul 09 16:27:46 smsl-n100 volumio[1196]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 09 16:27:47 smsl-n100 ntpd[32385]: Soliciting pool server 111.90.158.16 Jul 09 16:27:47 smsl-n100 sudo[32398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-09 16:26 Jul 09 16:27:47 smsl-n100 sudo[32398]: 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="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"