-- Logs begin at Thu 2019-02-14 19:11:58 JST, end at Sun 2026-01-11 13:23:31 JST. -- Jan 11 13:22:01 volumio volumio[1088]: info: CoreStateMachine::startPlaybackTimer Jan 11 13:22:01 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:22:02 volumio volumio[1088]: info: CoreStateMachine::pushState Jan 11 13:22:02 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:22:02 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 13:22:02 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Jan 11 13:22:02 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Jan 11 13:22:02 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:22:02 volumio volumio[1088]: info: CoreCommandRouter::volumioGetQueue Jan 11 13:22:02 volumio volumio[1088]: info: CoreStateMachine::getQueue Jan 11 13:22:02 volumio volumio[1088]: info: CorePlayQueue::getQueue Jan 11 13:22:02 volumio volumio[1088]: info: Jan 11 13:22:02 volumio volumio[1088]: ---------------------------- MPD announces system playlist update Jan 11 13:22:02 volumio volumio[1088]: info: Ignoring MPD Status Update Jan 11 13:22:02 volumio volumio[1088]: info: Jan 11 13:22:02 volumio volumio[1088]: ---------------------------- MPD announces state update: player Jan 11 13:22:02 volumio volumio[1088]: info: ControllerMpd::getState Jan 11 13:22:02 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Jan 11 13:22:02 volumio volumio[1088]: info: ------------------------------ 1ms Jan 11 13:22:02 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Jan 11 13:22:02 volumio volumio[1088]: verbose: ControllerMpd::parseState Jan 11 13:22:02 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 13:22:02 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 11 13:22:02 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Jan 11 13:22:02 volumio volumio[1088]: info: ControllerMpd::pushState Jan 11 13:22:02 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Jan 11 13:22:02 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:22:02 volumio volumio[1088]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":142,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Anything For Love","artist":"Dua Lipa","album":"Radical Optimism","uri":"http://192.168.0.4:32469/object/77da17ffbf8c70afce1a/file.mp3","trackType":"mp3"} Jan 11 13:22:02 volumio volumio[1088]: verbose: CURRENT POSITION 8 Jan 11 13:22:02 volumio volumio[1088]: info: CoreStateMachine::syncState stateService play Jan 11 13:22:02 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus play Jan 11 13:22:02 volumio volumio[1088]: info: Received an update from plugin. extracting info from payload Jan 11 13:22:02 volumio volumio[1088]: info: CoreStateMachine::pushState Jan 11 13:22:02 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:22:02 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 13:22:02 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Jan 11 13:22:02 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Jan 11 13:22:02 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:22:02 volumio volumio[1088]: info: CoreStateMachine::pushState Jan 11 13:22:02 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:22:02 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Jan 11 13:22:02 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Jan 11 13:22:02 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:22:02 volumio volumio[1088]: info: ------------------------------ 21ms Jan 11 13:22:02 volumio volumio[1088]: info: CoreCommandRouter::volumioGetQueue Jan 11 13:22:02 volumio volumio[1088]: info: CoreStateMachine::getQueue Jan 11 13:22:02 volumio volumio[1088]: info: CorePlayQueue::getQueue Jan 11 13:22:02 volumio volumio[1088]: info: CoreCommandRouter::volumioGetQueue Jan 11 13:22:02 volumio volumio[1088]: info: CoreStateMachine::getQueue Jan 11 13:22:02 volumio volumio[1088]: info: CorePlayQueue::getQueue Jan 11 13:22:07 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Jan 11 13:22:07 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jan 11 13:22:07 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jan 11 13:22:07 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jan 11 13:22:09 volumio wpa_supplicant[919]: wlan0: Failed to initiate sched scan Jan 11 13:22:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Jan 11 13:22:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jan 11 13:22:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jan 11 13:22:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jan 11 13:22:20 volumio wpa_supplicant[919]: wlan0: CTRL-EVENT-SSID-REENABLED id=1 ssid="aterm-a69fbb-a" Jan 11 13:22:20 volumio wpa_supplicant[919]: wlan0: Failed to initiate sched scan Jan 11 13:22:29 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Jan 11 13:22:29 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jan 11 13:22:30 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jan 11 13:22:30 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jan 11 13:22:31 volumio wpa_supplicant[919]: wlan0: Failed to initiate sched scan Jan 11 13:22:40 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Jan 11 13:22:41 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jan 11 13:22:41 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jan 11 13:22:41 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jan 11 13:22:43 volumio wpa_supplicant[919]: wlan0: Failed to initiate sched scan Jan 11 13:22:51 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Jan 11 13:22:52 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jan 11 13:22:52 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jan 11 13:22:52 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jan 11 13:22:54 volumio wpa_supplicant[919]: wlan0: Failed to initiate sched scan Jan 11 13:22:58 volumio volumio[1088]: info: CoreCommandRouter::volumioPause Jan 11 13:22:58 volumio volumio[1088]: info: CoreStateMachine::pause Jan 11 13:22:58 volumio volumio[1088]: info: CoreStateMachine::stPlaybackTimer Jan 11 13:22:58 volumio volumio[1088]: info: CoreStateMachine::servicePause Jan 11 13:22:58 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:22:58 volumio volumio[1088]: info: CoreCommandRouter::servicePause Jan 11 13:22:58 volumio volumio[1088]: info: [1768105378249] ControllerUPNPBrowser::pause Jan 11 13:22:58 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand pause Jan 11 13:22:58 volumio volumio[1088]: info: Jan 11 13:22:58 volumio volumio[1088]: ---------------------------- MPD announces state update: player Jan 11 13:22:58 volumio volumio[1088]: info: sendMpdCommand pause took 2 milliseconds Jan 11 13:22:58 volumio volumio[1088]: info: ControllerMpd::getState Jan 11 13:22:58 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand status Jan 11 13:22:58 volumio volumio[1088]: info: sendMpdCommand status took 1 milliseconds Jan 11 13:22:58 volumio volumio[1088]: verbose: ControllerMpd::parseState Jan 11 13:22:58 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 13:22:58 volumio volumio[1088]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 11 13:22:58 volumio volumio[1088]: verbose: ControllerMpd::parseTrackInfo Jan 11 13:22:58 volumio volumio[1088]: info: ControllerMpd::pushState Jan 11 13:22:58 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Jan 11 13:22:58 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:22:58 volumio volumio[1088]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":55874,"duration":142,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Anything For Love","artist":"Dua Lipa","album":"Radical Optimism","uri":"http://192.168.0.4:32469/object/77da17ffbf8c70afce1a/file.mp3","trackType":"mp3"} Jan 11 13:22:58 volumio volumio[1088]: verbose: CURRENT POSITION 8 Jan 11 13:22:58 volumio volumio[1088]: info: CoreStateMachine::syncState stateService pause Jan 11 13:22:58 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus pause Jan 11 13:22:58 volumio volumio[1088]: info: CoreStateMachine::pushState Jan 11 13:22:58 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:22:58 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 13:22:58 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Jan 11 13:22:58 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Jan 11 13:22:58 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:22:58 volumio volumio[1088]: info: CoreStateMachine::stPlaybackTimer Jan 11 13:22:58 volumio volumio[1088]: info: ------------------------------ 9ms Jan 11 13:22:58 volumio volumio[1088]: info: CoreCommandRouter::volumioGetQueue Jan 11 13:22:58 volumio volumio[1088]: info: CoreStateMachine::getQueue Jan 11 13:22:58 volumio volumio[1088]: info: CorePlayQueue::getQueue Jan 11 13:22:58 volumio volumio[1088]: info: touch_display: Setting screensaver timeout to 120 seconds. Jan 11 13:22:59 volumio kernel: usb 1-1.1: 1:0: usb_set_interface failed (-71) Jan 11 13:22:59 volumio kernel: usb 1-1.1: 1:0: usb_set_interface failed (-71) Jan 11 13:22:59 volumio kernel: usb 1-1.1: uac_clock_source_is_valid(): cannot get clock validity for id 33 Jan 11 13:22:59 volumio kernel: usb 1-1.1: clock source 33 is not valid, cannot use Jan 11 13:22:59 volumio kernel: usb 1-1.1: 1:2: cannot get freq (v2/v3): err -71 Jan 11 13:22:59 volumio kernel: usb 1-1.1: 1:2: cannot set freq 44100 (v2/v3): err -71 Jan 11 13:22:59 volumio kernel: usb 1-1.1: 1:0: usb_set_interface failed (-71) Jan 11 13:22:59 volumio kernel: usb 1-1.1: 1:0: usb_set_interface failed (-71) Jan 11 13:22:59 volumio kernel: usb 1-1.1: uac_clock_source_is_valid(): cannot get clock validity for id 33 Jan 11 13:22:59 volumio kernel: usb 1-1.1: clock source 33 is not valid, cannot use Jan 11 13:22:59 volumio kernel: usb 1-1.1: 1:2: cannot get freq (v2/v3): err -71 Jan 11 13:22:59 volumio kernel: usb 1-1.1: 1:2: cannot set freq 44100 (v2/v3): err -71 Jan 11 13:22:59 volumio kernel: usb 1-1.1: 1:0: usb_set_interface failed (-71) Jan 11 13:22:59 volumio kernel: usb 1-1.1: 1:0: usb_set_interface failed (-71) Jan 11 13:22:59 volumio kernel: usb 1-1.1: uac_clock_source_is_valid(): cannot get clock validity for id 33 Jan 11 13:22:59 volumio kernel: usb 1-1.1: clock source 33 is not valid, cannot use Jan 11 13:22:59 volumio kernel: usb 1-1.1: 1:2: cannot get freq (v2/v3): err -71 Jan 11 13:22:59 volumio kernel: usb 1-1.1: 1:2: cannot set freq 44100 (v2/v3): err -71 Jan 11 13:22:59 volumio kernel: usb 1-1.1: 1:0: usb_set_interface failed (-71) Jan 11 13:22:59 volumio kernel: usb 1-1.1: 1:0: usb_set_interface failed (-71) Jan 11 13:22:59 volumio kernel: usb 1-1.1: uac_clock_source_is_valid(): cannot get clock validity for id 33 Jan 11 13:22:59 volumio kernel: usb 1-1.1: clock source 33 is not valid, cannot use Jan 11 13:22:59 volumio kernel: usb 1-1.1: 1:2: cannot get freq (v2/v3): err -71 Jan 11 13:22:59 volumio kernel: usb 1-1.1: 1:2: cannot set freq 44100 (v2/v3): err -71 Jan 11 13:22:59 volumio kernel: usb 1-1.1: 1:0: usb_set_interface failed (-71) Jan 11 13:22:59 volumio kernel: usb 1-1.1: 1:0: usb_set_interface failed (-71) Jan 11 13:22:59 volumio kernel: usb 1-1.1: uac_clock_source_is_valid(): cannot get clock validity for id 33 Jan 11 13:22:59 volumio kernel: usb 1-1.1: clock source 33 is not valid, cannot use Jan 11 13:23:00 volumio kernel: usb 1-1.1: 1:2: cannot get freq (v2/v3): err -71 Jan 11 13:23:00 volumio kernel: usb 1-1.1: 1:2: cannot set freq 44100 (v2/v3): err -71 Jan 11 13:23:00 volumio kernel: usb 1-1.1: uac_clock_source_is_valid(): cannot get clock validity for id 33 Jan 11 13:23:00 volumio kernel: usb 1-1.1: clock source 33 is not valid, cannot use Jan 11 13:23:00 volumio kernel: usb 1-1.1: 1:2: cannot get freq (v2/v3): err -71 Jan 11 13:23:00 volumio kernel: usb 1-1.1: 1:2: cannot set freq 44100 (v2/v3): err -71 Jan 11 13:23:00 volumio kernel: usb 1-1.1: uac_clock_source_is_valid(): cannot get clock validity for id 33 Jan 11 13:23:00 volumio kernel: usb 1-1.1: clock source 33 is not valid, cannot use Jan 11 13:23:00 volumio kernel: usb 1-1.1: 1:2: cannot get freq (v2/v3): err -71 Jan 11 13:23:00 volumio kernel: usb 1-1.1: 1:2: cannot set freq 44100 (v2/v3): err -71 Jan 11 13:23:00 volumio kernel: usb 1-1.1: uac_clock_source_is_valid(): cannot get clock validity for id 33 Jan 11 13:23:00 volumio kernel: usb 1-1.1: clock source 33 is not valid, cannot use Jan 11 13:23:00 volumio kernel: usb 1-1.1: 1:2: cannot get freq (v2/v3): err -71 Jan 11 13:23:00 volumio kernel: usb 1-1.1: 1:2: cannot set freq 44100 (v2/v3): err -71 Jan 11 13:23:00 volumio kernel: usb 1-1.1: uac_clock_source_is_valid(): cannot get clock validity for id 33 Jan 11 13:23:00 volumio kernel: usb 1-1.1: clock source 33 is not valid, cannot use Jan 11 13:23:00 volumio kernel: usb 1-1.1: 1:2: cannot get freq (v2/v3): err -71 Jan 11 13:23:00 volumio kernel: usb 1-1.1: 1:2: cannot set freq 44100 (v2/v3): err -71 Jan 11 13:23:00 volumio kernel: usb 1-1.1: uac_clock_source_is_valid(): cannot get clock validity for id 33 Jan 11 13:23:00 volumio kernel: usb 1-1.1: clock source 33 is not valid, cannot use Jan 11 13:23:01 volumio kernel: usb 1-1.1: 1:2: cannot get freq (v2/v3): err -71 Jan 11 13:23:01 volumio kernel: usb 1-1.1: 1:2: cannot set freq 44100 (v2/v3): err -71 Jan 11 13:23:01 volumio kernel: usb 1-1.1: uac_clock_source_is_valid(): cannot get clock validity for id 33 Jan 11 13:23:01 volumio kernel: usb 1-1.1: clock source 33 is not valid, cannot use Jan 11 13:23:01 volumio kernel: usb 1-1.1: 1:2: cannot get freq (v2/v3): err -71 Jan 11 13:23:01 volumio kernel: usb 1-1.1: 1:2: cannot set freq 44100 (v2/v3): err -71 Jan 11 13:23:01 volumio kernel: usb 1-1.1: uac_clock_source_is_valid(): cannot get clock validity for id 33 Jan 11 13:23:01 volumio kernel: usb 1-1.1: clock source 33 is not valid, cannot use Jan 11 13:23:01 volumio kernel: usb 1-1.1: USB disconnect, device number 3 Jan 11 13:23:01 volumio kernel: usb 1-1.1: 1:2: cannot get freq (v2/v3): err -71 Jan 11 13:23:01 volumio kernel: usb 1-1.1: 1:2: cannot set freq 44100 (v2/v3): err -19 Jan 11 13:23:01 volumio volumio[1088]: info: Jan 11 13:23:01 volumio volumio[1088]: ---------------------------- USB Audio Device Detached Jan 11 13:23:01 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jan 11 13:23:01 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 13:23:01 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jan 11 13:23:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Jan 11 13:23:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jan 11 13:23:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jan 11 13:23:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jan 11 13:23:05 volumio wpa_supplicant[919]: wlan0: Failed to initiate sched scan Jan 11 13:23:06 volumio kernel: usb 1-1.1: new high-speed USB device number 4 using xhci_hcd Jan 11 13:23:06 volumio kernel: usb 1-1.1: New USB device found, idVendor=0644, idProduct=804a, bcdDevice= 1.00 Jan 11 13:23:07 volumio kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jan 11 13:23:07 volumio kernel: usb 1-1.1: Product: TEAC UD-301 Jan 11 13:23:07 volumio kernel: usb 1-1.1: Manufacturer: TEAC Corporation Jan 11 13:23:07 volumio kernel: usb 1-1.1: SerialNumber: 002B00003DE974014E452827500C0024 Jan 11 13:23:07 volumio volumio[1088]: info: Jan 11 13:23:07 volumio volumio[1088]: ---------------------------- USB Audio Device Attached Jan 11 13:23:07 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jan 11 13:23:07 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Jan 11 13:23:07 volumio volumio[1088]: info: Preparing to save Alsa Options, stopping services first Jan 11 13:23:07 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Jan 11 13:23:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPause Jan 11 13:23:07 volumio volumio[1088]: info: CoreStateMachine::pause Jan 11 13:23:07 volumio volumio[1088]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"TEAC UD-301","alsacard":"UD301"},"i2s":false} Jan 11 13:23:07 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 13:23:07 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 13:23:07 volumio volumio[1088]: info: Device 5 does not have any Mixer Control Available Jan 11 13:23:07 volumio volumio[1088]: info: QobuzConnect: setDeactiveState invoked Jan 11 13:23:07 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Jan 11 13:23:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:07 volumio vtcs[1903]: [2026-01-11 13:23:07.725] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jan 11 13:23:07 volumio vtcs[1903]: [2026-01-11 13:23:07.726] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Jan 11 13:23:07 volumio vtcs[1903]: [2026-01-11 13:23:07.726] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jan 11 13:23:07 volumio sudo[9639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 11 13:23:07 volumio sudo[9639]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:23:07 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Jan 11 13:23:07 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jan 11 13:23:07 volumio systemd[1]: vtcs.service: Succeeded. Jan 11 13:23:07 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Jan 11 13:23:07 volumio volumio[1088]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 11 13:23:07 volumio volumio[1088]: info: Updating Volume Controller Parameters: Device: 5 Name: TEAC UD-301 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 11 13:23:07 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 11 13:23:07 volumio volumio[1088]: info: Disabling external Volume Control Jan 11 13:23:07 volumio sudo[9639]: pam_unix(sudo:session): session closed for user root Jan 11 13:23:07 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 13:23:07 volumio volumio[1088]: info: CoreStateMachine::pushState Jan 11 13:23:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:07 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 13:23:07 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Jan 11 13:23:07 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Jan 11 13:23:07 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:07 volumio volumio[1088]: info: Preparing to generate the ALSA configuration file Jan 11 13:23:07 volumio sudo[9646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 11 13:23:07 volumio sudo[9646]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:23:07 volumio sudo[9646]: pam_unix(sudo:session): session closed for user root Jan 11 13:23:07 volumio volumio[1088]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 11 13:23:07 volumio volumio[1088]: info: Reading ALSA contributions from plugins. Jan 11 13:23:07 volumio volumio[1088]: info: CoreCommandRouter::volumioGetQueue Jan 11 13:23:07 volumio volumio[1088]: info: CoreStateMachine::getQueue Jan 11 13:23:07 volumio volumio[1088]: info: CorePlayQueue::getQueue Jan 11 13:23:07 volumio sudo[9653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 11 13:23:07 volumio sudo[9653]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:23:07 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 11 13:23:07 volumio sudo[9653]: pam_unix(sudo:session): session closed for user root Jan 11 13:23:07 volumio sudo[9656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 11 13:23:07 volumio sudo[9656]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:23:07 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Jan 11 13:23:07 volumio qobuz-connect[1826]: 20260111 13:23:07.994 [1826.1826] INFO SampleApp: Stopping Local configuration server Jan 11 13:23:08 volumio qobuz-connect[1826]: 20260111 13:23:08.004 [1826.1826] INFO SampleApp: shat down connection on UNIX socket Jan 11 13:23:08 volumio systemd[1]: qobuz-connect.service: Succeeded. Jan 11 13:23:08 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 11 13:23:08 volumio systemd[1]: Started Volumio Qobuz Connect Service. Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 11 13:23:08 volumio sudo[9656]: pam_unix(sudo:session): session closed for user root Jan 11 13:23:08 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 11 13:23:08 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 11 13:23:08 volumio volumio[1088]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 11 13:23:08 volumio volumio[1088]: info: QobuzConnect: setDeactiveState invoked Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Jan 11 13:23:08 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:08 volumio volumio[1088]: info: Executing endpoint qc_getconfig Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 11 13:23:08 volumio qobuz-connect[9661]: 20260111 13:23:08.172 [9661.9661] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 11 13:23:08 volumio volumio[1088]: info: Asound.conf file unchanged, so no further update is needed Jan 11 13:23:08 volumio volumio[1088]: info: Output device has changed, restarting MPD Jan 11 13:23:08 volumio qobuz-connect[9661]: 20260111 13:23:08.176 [9661.9661] INFO VolumeManager: [0x10f22a0]: Setting new playback volume: 75 Jan 11 13:23:08 volumio qobuz-connect[9661]: 20260111 13:23:08.176 [9661.9661] INFO VolumeManager: [0x10f22a0]: Setting new mute state: 0 Jan 11 13:23:08 volumio qobuz-connect[9661]: 20260111 13:23:08.176 [9661.9661] INFO QobuzConnect: [0x10f2c70]: Client initialized! Jan 11 13:23:08 volumio qobuz-connect[9661]: 20260111 13:23:08.176 [9661.9661] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jan 11 13:23:08 volumio qobuz-connect[9661]: 20260111 13:23:08.184 [9661.9661] INFO LocalConfigManager: [0x10f1b80]: Starting Local Configuration server Jan 11 13:23:08 volumio qobuz-connect[9661]: 20260111 13:23:08.184 [9661.9661] INFO SampleApp: Starting Local configuration server Jan 11 13:23:08 volumio qobuz-connect[9661]: 20260111 13:23:08.184 [9661.9661] INFO SampleApp: Connected to UNIX socket client 0x10e7f60 Jan 11 13:23:08 volumio sudo[9674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 13:23:08 volumio sudo[9674]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:23:08 volumio volumio[1088]: info: Output device has changed, restarting Shairport Sync Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:23:08 volumio sudo[9674]: pam_unix(sudo:session): session closed for user root Jan 11 13:23:08 volumio sudo[9677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 13:23:08 volumio sudo[9677]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:23:08 volumio systemd[1]: Stopping Music Player Daemon... Jan 11 13:23:08 volumio volumio[1088]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 13:23:08 volumio volumio[1088]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:23:08 volumio systemd[1]: mpd.service: Succeeded. Jan 11 13:23:08 volumio systemd[1]: Stopped Music Player Daemon. Jan 11 13:23:08 volumio volumio[1088]: info: QobuzConnect: setDeactiveState invoked Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Jan 11 13:23:08 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:08 volumio systemd[1]: Starting Music Player Daemon... Jan 11 13:23:08 volumio qobuz-connect[9661]: 20260111 13:23:08.323 [9661.9661] INFO SampleApp: Playback volume changed: 75 Jan 11 13:23:08 volumio sudo[9686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 11 13:23:08 volumio sudo[9686]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:23:08 volumio sudo[9686]: pam_unix(sudo:session): session closed for user root Jan 11 13:23:08 volumio sudo[9689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 11 13:23:08 volumio sudo[9689]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:23:08 volumio sudo[9682]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 13:23:08 volumio volumio[1088]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 11 13:23:08 volumio volumio[1088]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 11 13:23:08 volumio sudo[9682]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jan 11 13:23:08 volumio sudo[9689]: pam_unix(sudo:session): session closed for user root Jan 11 13:23:08 volumio sudo[9682]: pam_unix(sudo:session): session closed for user root Jan 11 13:23:08 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 11 13:23:08 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 11 13:23:08 volumio sudo[9696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 11 13:23:08 volumio sudo[9696]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:23:08 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Jan 11 13:23:08 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:08 volumio volumio[1088]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jan 11 13:23:08 volumio volumio[1088]: info: MPD Permissions set Jan 11 13:23:08 volumio volumio[1088]: error: Upnp client error: Error: This socket has been ended by the other party Jan 11 13:23:08 volumio sudo[9696]: pam_unix(sudo:session): session closed for user root Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:23:08 volumio sudo[9701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 11 13:23:08 volumio sudo[9701]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 13:23:08 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Jan 11 13:23:08 volumio qobuz-connect[9661]: 20260111 13:23:08.516 [9661.9661] INFO SampleApp: Stopping Local configuration server Jan 11 13:23:08 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 11 13:23:08 volumio qobuz-connect[9661]: 20260111 13:23:08.527 [9661.9661] INFO SampleApp: shat down connection on UNIX socket Jan 11 13:23:08 volumio volumio[1088]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 11 13:23:08 volumio volumio[1088]: info: QobuzConnect: setDeactiveState invoked Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Jan 11 13:23:08 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:08 volumio volumio[1088]: info: Starting Shairport Sync Jan 11 13:23:08 volumio systemd[1]: qobuz-connect.service: Succeeded. Jan 11 13:23:08 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Jan 11 13:23:08 volumio systemd[1]: Started Volumio Qobuz Connect Service. Jan 11 13:23:08 volumio sudo[9701]: pam_unix(sudo:session): session closed for user root Jan 11 13:23:08 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 11 13:23:08 volumio volumio[1088]: info: Executing endpoint qc_getconfig Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 11 13:23:08 volumio sudo[9709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 13:23:08 volumio sudo[9709]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:23:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 11 13:23:08 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 11 13:23:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 11 13:23:08 volumio qobuz-connect[9707]: 20260111 13:23:08.654 [9707.9707] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 11 13:23:08 volumio qobuz-connect[9707]: 20260111 13:23:08.659 [9707.9707] INFO VolumeManager: [0x77e2a0]: Setting new playback volume: 75 Jan 11 13:23:08 volumio qobuz-connect[9707]: 20260111 13:23:08.659 [9707.9707] INFO VolumeManager: [0x77e2a0]: Setting new mute state: 0 Jan 11 13:23:08 volumio qobuz-connect[9707]: 20260111 13:23:08.659 [9707.9707] INFO QobuzConnect: [0x77ec70]: Client initialized! Jan 11 13:23:08 volumio qobuz-connect[9707]: 20260111 13:23:08.659 [9707.9707] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jan 11 13:23:08 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 11 13:23:08 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 11 13:23:08 volumio volumio[1088]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 11 13:23:08 volumio volumio[1088]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 11 13:23:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 11 13:23:08 volumio sudo[9709]: pam_unix(sudo:session): session closed for user root Jan 11 13:23:08 volumio qobuz-connect[9707]: 20260111 13:23:08.687 [9707.9707] INFO LocalConfigManager: [0x77db80]: Starting Local Configuration server Jan 11 13:23:08 volumio qobuz-connect[9707]: 20260111 13:23:08.687 [9707.9707] INFO SampleApp: Starting Local configuration server Jan 11 13:23:08 volumio qobuz-connect[9707]: 20260111 13:23:08.688 [9707.9707] INFO SampleApp: Connected to UNIX socket client 0x773f60 Jan 11 13:23:08 volumio volumio[1088]: info: Shairport-Sync Started Jan 11 13:23:08 volumio qobuz-connect[9707]: 20260111 13:23:08.804 [9707.9707] INFO SampleApp: Playback volume changed: 75 Jan 11 13:23:08 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Jan 11 13:23:08 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:09 volumio mpd[9699]: Jan 11 13:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 11 13:23:09 volumio systemd[1]: Started Music Player Daemon. Jan 11 13:23:09 volumio sudo[9677]: pam_unix(sudo:session): session closed for user root Jan 11 13:23:09 volumio volumio[1088]: error: updateQueue error: null Jan 11 13:23:10 volumio volumio[1088]: info: TidalConnect service stoped! Jan 11 13:23:10 volumio volumio[1088]: info: TidalConnect service stoped! Jan 11 13:23:10 volumio volumio[1088]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 11 13:23:10 volumio volumio[1088]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 11 13:23:10 volumio sudo[9744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 11 13:23:11 volumio sudo[9744]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:23:11 volumio systemd[1]: Started Volumio Tidal Connect Service. Jan 11 13:23:11 volumio sudo[9744]: pam_unix(sudo:session): session closed for user root Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::volumioPlay Jan 11 13:23:11 volumio volumio[1088]: info: CoreStateMachine::play index undefined Jan 11 13:23:11 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:11 volumio volumio[1088]: info: CoreStateMachine::startPlaybackTimer Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:11 volumio volumio[1088]: info: [1768105391185] ControllerUPNPBrowser::resume Jan 11 13:23:11 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand play Jan 11 13:23:11 volumio volumio[1088]: info: sendMpdCommand play took 2 milliseconds Jan 11 13:23:11 volumio volumio[1088]: info: Executing endpoint tc_getconfig Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 11 13:23:11 volumio vtcs[9746]: STARTING TidalConnect services, version: 1.5.2.56 Jan 11 13:23:11 volumio vtcs[9746]: STARTED TidalConnect services. Jan 11 13:23:11 volumio volumio[1088]: info: Executing endpoint tc_connect Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 11 13:23:11 volumio volumio[1088]: info: Connecting to TidalConnect Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Jan 11 13:23:11 volumio volumio[1088]: info: CoreStateMachine::pushState Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:11 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jan 11 13:23:11 volumio volumio[1088]: verbose: CURRENT POSITION 8 Jan 11 13:23:11 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Jan 11 13:23:11 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus pause Jan 11 13:23:11 volumio volumio[1088]: info: CoreStateMachine::pushState Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::servicePushState Jan 11 13:23:11 volumio volumio[1088]: info: CoreStateMachine::pushState Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:11 volumio volumio[1088]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jan 11 13:23:11 volumio volumio[1088]: verbose: CURRENT POSITION 8 Jan 11 13:23:11 volumio volumio[1088]: info: CoreStateMachine::syncState stateService stop Jan 11 13:23:11 volumio volumio[1088]: info: CoreStateMachine::syncState currentStatus pause Jan 11 13:23:11 volumio volumio[1088]: info: CoreStateMachine::pushState Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::volumioGetQueue Jan 11 13:23:11 volumio volumio[1088]: info: CoreStateMachine::getQueue Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getQueue Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::volumioGetQueue Jan 11 13:23:11 volumio volumio[1088]: info: CoreStateMachine::getQueue Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getQueue Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::volumioGetQueue Jan 11 13:23:11 volumio volumio[1088]: info: CoreStateMachine::getQueue Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getQueue Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::volumioGetQueue Jan 11 13:23:11 volumio volumio[1088]: info: CoreStateMachine::getQueue Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getQueue Jan 11 13:23:11 volumio volumio[1088]: info: TidalConnect service stoped! Jan 11 13:23:11 volumio volumio[1088]: info: TidalConnect service stoped! Jan 11 13:23:11 volumio volumio[1088]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 11 13:23:11 volumio volumio[1088]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 11 13:23:11 volumio sudo[9776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 11 13:23:11 volumio sudo[9776]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:23:11 volumio sudo[9776]: pam_unix(sudo:session): session closed for user root Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 11 13:23:11 volumio volumio[1088]: info: CoreStateMachine::getcurrentVolume Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 13:23:11 volumio volumio[1088]: info: CoreStateMachine::pushState Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::volumioPushState Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:11 volumio volumio[1088]: info: CoreCommandRouter::volumioGetQueue Jan 11 13:23:11 volumio volumio[1088]: info: CoreStateMachine::getQueue Jan 11 13:23:11 volumio volumio[1088]: info: CorePlayQueue::getQueue Jan 11 13:23:12 volumio sudo[9780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 11 13:23:12 volumio sudo[9780]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:23:12 volumio systemd[1]: Stopping MPD Monitor Service... Jan 11 13:23:12 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 11 13:23:12 volumio systemd[1]: mpd_monitor.service: Succeeded. Jan 11 13:23:12 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 11 13:23:12 volumio systemd[1]: Stopped MPD Monitor Service. Jan 11 13:23:12 volumio volumio[1088]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 11 13:23:12 volumio systemd[1]: Started MPD Monitor Service. Jan 11 13:23:12 volumio sudo[9780]: pam_unix(sudo:session): session closed for user root Jan 11 13:23:12 volumio volumio[1088]: info: Successfully started MPD Monitor Jan 11 13:23:12 volumio mpd_monitor.sh[9782]: MPD Monitor Service: Starting MPD Monitor Service Jan 11 13:23:14 volumio volumio[1088]: info: TidalConnect service started! Jan 11 13:23:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Jan 11 13:23:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jan 11 13:23:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jan 11 13:23:14 volumio volumio[1088]: info: TidalConnect service started! Jan 11 13:23:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jan 11 13:23:16 volumio wpa_supplicant[919]: wlan0: Failed to initiate sched scan Jan 11 13:23:17 volumio volumio[1088]: info: CoreCommandRouter::volumioPlay Jan 11 13:23:17 volumio volumio[1088]: info: CoreStateMachine::play index undefined Jan 11 13:23:17 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 13:23:17 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:17 volumio volumio[1088]: info: CoreStateMachine::startPlaybackTimer Jan 11 13:23:17 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:17 volumio volumio[1088]: info: [1768105397274] ControllerUPNPBrowser::resume Jan 11 13:23:17 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand play Jan 11 13:23:17 volumio volumio[1088]: info: sendMpdCommand play took 3 milliseconds Jan 11 13:23:20 volumio volumio[1088]: info: CoreCommandRouter::volumioPlay Jan 11 13:23:20 volumio volumio[1088]: info: CoreStateMachine::play index undefined Jan 11 13:23:20 volumio volumio[1088]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 13:23:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:20 volumio volumio[1088]: info: CoreStateMachine::startPlaybackTimer Jan 11 13:23:20 volumio volumio[1088]: info: CorePlayQueue::getTrack 8 Jan 11 13:23:20 volumio volumio[1088]: info: [1768105400154] ControllerUPNPBrowser::resume Jan 11 13:23:20 volumio volumio[1088]: verbose: ControllerMpd::sendMpdCommand play Jan 11 13:23:20 volumio volumio[1088]: info: sendMpdCommand play took 1 milliseconds Jan 11 13:23:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0x100e fail, reason -52 Jan 11 13:23:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jan 11 13:23:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jan 11 13:23:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jan 11 13:23:27 volumio wpa_supplicant[919]: wlan0: Failed to initiate sched scan Jan 11 13:23:30 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPause Jan 11 13:23:30 volumio volumio[1088]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPause] in plugin multiroom Jan 11 13:23:30 volumio volumio[1088]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 13:23:30 volumio volumio[1088]: TypeError: Cannot read property 'then' of undefined Jan 11 13:23:30 volumio volumio[1088]: at outputs.audioOutputPause (/volumio/app/plugins/audio_interface/outputs/index.js:417:9) Jan 11 13:23:30 volumio volumio[1088]: at CoreCommandRouter.audioOutputPause (/volumio/app/index.js:2290:30) Jan 11 13:23:30 volumio volumio[1088]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1473:26) Jan 11 13:23:30 volumio volumio[1088]: at Socket.emit (events.js:315:20) Jan 11 13:23:30 volumio volumio[1088]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jan 11 13:23:30 volumio volumio[1088]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jan 11 13:23:30 volumio volumio[1088]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 13:23:31 volumio sudo[9864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-11 13:22 Jan 11 13:23:31 volumio sudo[9864]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"