Oct 15 20:58:05 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:05.023Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-589.005925ms timeout=10s Oct 15 20:58:05 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:05.061Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-589.005925ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=38.537509ms Oct 15 20:58:05 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:05.093Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-589.005925ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=69.699262ms Oct 15 20:58:05 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:05.093Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-589.005925ms timeout=10s endpoint=http://pushupdates.volumio.org duration=69.455202ms Oct 15 20:58:05 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:05.094Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-589.005925ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=70.527795ms Oct 15 20:58:05 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:05.150Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-589.005925ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=126.276944ms Oct 15 20:58:05 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:05.150Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-589.005925ms timeout=10s endpoint=https://www.googleapis.com duration=127.202455ms Oct 15 20:58:05 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:05.169Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-589.005925ms timeout=10s endpoint=https://database.volumio.cloud duration=145.585542ms Oct 15 20:58:05 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:05.169Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-589.005925ms timeout=10s endpoint=https://functions.volumio.cloud duration=146.194702ms Oct 15 20:58:05 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:05.170Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-589.005925ms timeout=10s endpoint=https://functions.volumio.cloud duration=146.016996ms Oct 15 20:58:05 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:05.174Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-589.005925ms timeout=10s endpoint=https://securetoken.googleapis.com duration=150.489188ms Oct 15 20:58:05 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:05.213Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-589.005925ms timeout=10s endpoint=http://plugins.volumio.org duration=188.809771ms Oct 15 20:58:05 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:05.271Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-589.005925ms timeout=10s endpoint=https://google.com duration=247.907322ms Oct 15 20:58:05 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:05.433Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-589.005925ms timeout=10s endpoint=http://cddb.volumio.org duration=409.741181ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 15 20:58:09 volumio go-librespot[9756]: time="2026-10-15T20:58:09+02:00" level=debug msg="fetched chunk 7/26, size: 524288" uri="spotify:track:5u2pW2af1Em61DWrbPZMic" Oct 15 20:58:10 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:10.491Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-544.199818ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 15 20:58:10 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:10.582Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-573.223976ms timeout=10s Oct 15 20:58:10 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:10.599Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-573.223976ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=16.326859ms Oct 15 20:58:10 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:10.626Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-573.223976ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=42.618455ms Oct 15 20:58:10 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:10.627Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-573.223976ms timeout=10s endpoint=http://pushupdates.volumio.org duration=43.79813ms Oct 15 20:58:10 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:10.627Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-573.223976ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=43.943232ms Oct 15 20:58:10 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:10.706Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-573.223976ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=122.475995ms Oct 15 20:58:10 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:10.706Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-573.223976ms timeout=10s endpoint=https://www.googleapis.com duration=122.682086ms Oct 15 20:58:10 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:10.716Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-573.223976ms timeout=10s endpoint=https://database.volumio.cloud duration=132.582971ms Oct 15 20:58:10 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:10.720Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-573.223976ms timeout=10s endpoint=https://functions.volumio.cloud duration=136.917664ms Oct 15 20:58:10 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:10.720Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-573.223976ms timeout=10s endpoint=https://functions.volumio.cloud duration=136.59324ms Oct 15 20:58:10 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:10.729Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-573.223976ms timeout=10s endpoint=http://plugins.volumio.org duration=145.52799ms Oct 15 20:58:10 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:10.750Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-573.223976ms timeout=10s endpoint=https://securetoken.googleapis.com duration=167.23401ms Oct 15 20:58:10 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:10.790Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-573.223976ms timeout=10s endpoint=https://google.com duration=207.349627ms Oct 15 20:58:10 volumio volumio5-onboarding[1649]: time=2026-10-15T18:58:10.936Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-573.223976ms timeout=10s endpoint=http://cddb.volumio.org duration=353.68464ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 15 20:58:11 volumio sudo[9948]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 20:58:11 volumio sudo[9948]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:58:11 volumio sudo[9950]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 20:58:11 volumio sudo[9950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:58:11 volumio sudo[9948]: pam_unix(sudo:session): session closed for user root Oct 15 20:58:11 volumio sudo[9950]: pam_unix(sudo:session): session closed for user root Oct 15 20:58:11 volumio volumio[1119]: verbose: New Socket.io Connection to 192.168.0.80 from 192.168.0.159 UA: Mozilla/5.0 (Linux; Android 16; V2337 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.106 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 15 20:58:12 volumio sudo[9956]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 20:58:12 volumio sudo[9956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:58:12 volumio sudo[9956]: pam_unix(sudo:session): session closed for user root Oct 15 20:58:12 volumio sudo[9958]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 20:58:12 volumio sudo[9958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:58:12 volumio sudo[9958]: pam_unix(sudo:session): session closed for user root Oct 15 20:58:12 volumio volumio[1119]: verbose: New Socket.io Connection to 192.168.0.80 from 192.168.0.159 UA: Mozilla/5.0 (Linux; Android 16; V2337 Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.106 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 15 20:58:12 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 15 20:58:12 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 15 20:58:12 volumio volumio[1119]: info: CoreCommandRouter::volumioGetVisibleSources Oct 15 20:58:12 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 20:58:12 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:58:12 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 15 20:58:12 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 15 20:58:12 volumio volumio[1119]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 15 20:58:12 volumio volumio[1119]: info: Received Get System Info Oct 15 20:58:12 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 20:58:12 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 20:58:12 volumio volumio[1119]: info: Discovery: Getting this device information Oct 15 20:58:12 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:58:12 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 20:58:12 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:58:12 volumio volumio[1119]: info: Listing playlists Oct 15 20:58:12 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 15 20:58:12 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 20:58:14 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 20:58:14 volumio volumio[1119]: info: Received Get System Info Oct 15 20:58:14 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 20:58:14 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 20:58:14 volumio volumio[1119]: info: Discovery: Getting this device information Oct 15 20:58:14 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:58:14 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 20:58:14 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 15 20:58:15 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 20:58:15 volumio volumio[1119]: info: Received Get System Info Oct 15 20:58:15 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 20:58:15 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 20:58:15 volumio volumio[1119]: info: Discovery: Getting this device information Oct 15 20:58:15 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:58:15 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 20:58:16 volumio volumio[1119]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 20:58:16 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 15 20:58:16 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 20:58:16 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 20:58:16 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:58:16 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:58:16 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:58:16 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:58:16 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:58:16 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:58:16 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:58:16 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 15 20:58:16 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 15 20:58:21 volumio go-librespot[9756]: time="2026-10-15T20:58:21+02:00" level=debug msg="fetched chunk 8/26, size: 524288" uri="spotify:track:5u2pW2af1Em61DWrbPZMic" Oct 15 20:58:22 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 15 20:58:30 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 20:58:30 volumio volumio[1119]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 15 20:58:30 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 15 20:58:30 volumio volumio[1119]: info: Received Get System Version Oct 15 20:58:30 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 20:58:30 volumio volumio[1119]: info: Received Get System Info Oct 15 20:58:30 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 20:58:30 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 20:58:30 volumio volumio[1119]: info: Discovery: Getting this device information Oct 15 20:58:30 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:58:30 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 20:58:34 volumio go-librespot[9756]: time="2026-10-15T20:58:34+02:00" level=debug msg="fetched chunk 9/26, size: 524288" uri="spotify:track:5u2pW2af1Em61DWrbPZMic" Oct 15 20:58:48 volumio go-librespot[9756]: time="2026-10-15T20:58:48+02:00" level=debug msg="fetched chunk 10/26, size: 524288" uri="spotify:track:5u2pW2af1Em61DWrbPZMic" Oct 15 20:59:00 volumio go-librespot[9756]: time="2026-10-15T20:59:00+02:00" level=debug msg="fetched chunk 11/26, size: 524288" uri="spotify:track:5u2pW2af1Em61DWrbPZMic" Oct 15 20:59:02 volumio kernel: usb 1-1.1.3: new full-speed USB device number 5 using dwc_otg Oct 15 20:59:03 volumio kernel: usb 1-1.1.3: New USB device found, idVendor=0451, idProduct=0003, bcdDevice= 1.02 Oct 15 20:59:03 volumio kernel: usb 1-1.1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Oct 15 20:59:03 volumio kernel: usb 1-1.1.3: Product: SMSL AD18 AMP Oct 15 20:59:03 volumio kernel: usb 1-1.1.3: Manufacturer: SMSL AUDIO Oct 15 20:59:03 volumio kernel: usb 1-1.1.3: 1:1: cannot get freq at ep 0x1 Oct 15 20:59:03 volumio kernel: usbcore: registered new interface driver snd-usb-audio Oct 15 20:59:03 volumio (udev-worker)[10085]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Oct 15 20:59:03 volumio volumio[1119]: info: Oct 15 20:59:03 volumio volumio[1119]: ---------------------------- USB Audio Device Attached Oct 15 20:59:03 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 15 20:59:08 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:08.107Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-588.814676ms timeout=10s Oct 15 20:59:08 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:08.134Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-588.814676ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=26.943465ms Oct 15 20:59:08 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:08.156Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-588.814676ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=48.206885ms Oct 15 20:59:08 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:08.163Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-588.814676ms timeout=10s endpoint=http://pushupdates.volumio.org duration=55.606755ms Oct 15 20:59:08 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:08.164Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-588.814676ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=56.586849ms Oct 15 20:59:08 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:08.238Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-588.814676ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=131.184548ms Oct 15 20:59:08 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:08.239Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-588.814676ms timeout=10s endpoint=https://www.googleapis.com duration=131.241683ms Oct 15 20:59:08 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:08.246Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-588.814676ms timeout=10s endpoint=https://functions.volumio.cloud duration=138.347805ms Oct 15 20:59:08 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:08.246Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-588.814676ms timeout=10s endpoint=https://database.volumio.cloud duration=138.363899ms Oct 15 20:59:08 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:08.247Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-588.814676ms timeout=10s endpoint=http://cddb.volumio.org duration=139.00634ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 15 20:59:08 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:08.254Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-588.814676ms timeout=10s endpoint=https://functions.volumio.cloud duration=146.537927ms Oct 15 20:59:08 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:08.264Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-588.814676ms timeout=10s endpoint=https://securetoken.googleapis.com duration=156.660632ms Oct 15 20:59:08 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:08.264Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-588.814676ms timeout=10s endpoint=http://plugins.volumio.org duration=156.113711ms Oct 15 20:59:08 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:08.315Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" latency=-588.814676ms timeout=10s endpoint=https://google.com duration=207.835975ms Oct 15 20:59:14 volumio go-librespot[9756]: time="2026-10-15T20:59:14+02:00" level=debug msg="fetched chunk 12/26, size: 524288" uri="spotify:track:5u2pW2af1Em61DWrbPZMic" Oct 15 20:59:15 volumio volumio[1119]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 20:59:15 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 15 20:59:15 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 20:59:15 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 20:59:15 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:15 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:15 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:15 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:15 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:15 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:15 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:15 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 15 20:59:15 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 15 20:59:20 volumio volumio[1119]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 15 20:59:20 volumio volumio[1119]: info: Preparing to save Alsa Options, stopping services first Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::volumioPause Oct 15 20:59:20 volumio volumio[1119]: info: CoreStateMachine::pause Oct 15 20:59:20 volumio volumio[1119]: info: CoreStateMachine::stPlaybackTimer Oct 15 20:59:20 volumio volumio[1119]: info: CoreStateMachine::servicePause Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::servicePause Oct 15 20:59:20 volumio volumio[1119]: info: Spotify Received pause Oct 15 20:59:20 volumio volumio[1119]: SPOTIFY: SPOTIFY PAUSE Oct 15 20:59:20 volumio volumio[1119]: SPOTIFY: {"status":"play","title":"Turn To Stone","artist":"Kenny Wayne Shepherd","album":"The Traveler","albumart":"https://i.scdn.co/image/ab67616d00001e026aa545ce98933a28800b83d2","uri":"spotify:track:5u2pW2af1Em61DWrbPZMic","trackType":"spotify","codec":"ogg","seek":42950,"duration":344,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":86,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Oct 15 20:59:20 volumio volumio[1119]: info: Sending Spotify command to local API: /player/pause Oct 15 20:59:20 volumio volumio[1119]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"SMSL AD18 AMP"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 20:59:20 volumio volumio[1119]: info: Device 5 does not have any Mixer Control Available Oct 15 20:59:20 volumio volumio[1119]: info: QobuzConnect: setDeactiveState invoked Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:20 volumio vtcs[9411]: [2026-10-15 20:59:20.447] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 15 20:59:20 volumio vtcs[9411]: [2026-10-15 20:59:20.447] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 15 20:59:20 volumio sudo[10148]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 20:59:20 volumio sudo[10148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 15 20:59:20 volumio volumio[1119]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL AD18 AMP Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 15 20:59:20 volumio volumio[1119]: info: Disabling external Volume Control Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 20:59:20 volumio volumio[1119]: info: CoreStateMachine::pushState Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Oct 15 20:59:20 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 15 20:59:20 volumio systemd[1]: vtcs.service: Killing process 9415 (vtcs) with signal SIGKILL. Oct 15 20:59:20 volumio volumio[1119]: info: MRS: Pushing multiroomSync output update for this device Oct 15 20:59:20 volumio systemd[1]: vtcs.service: Deactivated successfully. Oct 15 20:59:20 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 15 20:59:20 volumio volumio[1119]: info: MRS: Pushing multiroomSync output Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:20 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:20.539Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" state=STATUS_PLAYING positionMs=122950 volume=100 Oct 15 20:59:20 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:20.540Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" id=spotify:track:5u2pW2af1Em61DWrbPZMic title="Turn To Stone" Oct 15 20:59:20 volumio volumio[1119]: info: Preparing to generate the ALSA configuration file Oct 15 20:59:20 volumio sudo[10148]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:20 volumio sudo[10156]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 20:59:20 volumio sudo[10156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:20 volumio volumio[1119]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 15 20:59:20 volumio volumio[1119]: info: Reading ALSA contributions from plugins. Oct 15 20:59:20 volumio go-librespot[9756]: time="2026-10-15T20:59:20+02:00" level=debug msg="pause track at 123695ms" Oct 15 20:59:20 volumio sudo[10163]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 15 20:59:20 volumio sudo[10163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:20 volumio sudo[10156]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:20 volumio sudo[10163]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 20:59:20 volumio sudo[10168]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 15 20:59:20 volumio sudo[10168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:20 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 15 20:59:20 volumio qobuz-connect[9382]: 20261015 20:59:20.755 [9382.9382] INFO SampleApp: Stopping Local configuration server Oct 15 20:59:20 volumio go-librespot[9756]: time="2026-10-15T20:59:20+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 15 20:59:20 volumio go-librespot[9756]: time="2026-10-15T20:59:20+02:00" level=trace msg="emitting websocket event: paused" Oct 15 20:59:20 volumio volumio[1119]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1F5p3rmiWPIYgZ","uri":"spotify:track:5u2pW2af1Em61DWrbPZMic","play_origin":"playlist"}} Oct 15 20:59:20 volumio volumio[1119]: SPOTIFY: PUSH STATE SPOTIFY Oct 15 20:59:20 volumio volumio[1119]: SPOTIFY: {"status":"pause","service":"spop","title":"Turn To Stone","artist":"Kenny Wayne Shepherd","album":"The Traveler","albumart":"https://i.scdn.co/image/ab67616d00001e026aa545ce98933a28800b83d2","uri":"spotify:track:5u2pW2af1Em61DWrbPZMic","trackType":"spotify","seek":123950,"duration":344,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Oct 15 20:59:20 volumio volumio[1119]: info: CoreStateMachine::pushState Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Oct 15 20:59:20 volumio volumio[1119]: info: MRS: Pushing multiroomSync output update for this device Oct 15 20:59:20 volumio volumio[1119]: info: MRS: Pushing multiroomSync output Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:20 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:20.792Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" state=STATUS_PAUSED positionMs=123950 volume=100 Oct 15 20:59:20 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:20.793Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" id=spotify:track:5u2pW2af1Em61DWrbPZMic title="Turn To Stone" Oct 15 20:59:20 volumio volumio[1119]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:playlist:37i9dQZF1F5p3rmiWPIYgZ","uri":"spotify:track:5u2pW2af1Em61DWrbPZMic","play_origin":"playlist"}} Oct 15 20:59:20 volumio volumio[1119]: SPOTIFY: PUSH STATE SPOTIFY Oct 15 20:59:20 volumio volumio[1119]: SPOTIFY: {"status":"pause","service":"spop","title":"Turn To Stone","artist":"Kenny Wayne Shepherd","album":"The Traveler","albumart":"https://i.scdn.co/image/ab67616d00001e026aa545ce98933a28800b83d2","uri":"spotify:track:5u2pW2af1Em61DWrbPZMic","trackType":"spotify","seek":123950,"duration":344,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Oct 15 20:59:20 volumio volumio[1119]: info: CoreStateMachine::pushState Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Oct 15 20:59:20 volumio volumio[1119]: info: MRS: Pushing multiroomSync output update for this device Oct 15 20:59:20 volumio volumio[1119]: info: MRS: Pushing multiroomSync output Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:20 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:20.811Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" state=STATUS_PAUSED positionMs=123950 volume=100 Oct 15 20:59:20 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:20.812Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" id=spotify:track:5u2pW2af1Em61DWrbPZMic title="Turn To Stone" Oct 15 20:59:20 volumio volumio[1119]: info: Asound.conf file written Oct 15 20:59:20 volumio sudo[10176]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 15 20:59:20 volumio sudo[10176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:20 volumio sudo[10176]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:20 volumio volumio[1119]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 15 20:59:20 volumio volumio[1119]: No state is present for card b1 Oct 15 20:59:20 volumio volumio[1119]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 15 20:59:20 volumio volumio[1119]: /usr/share/alsa/init/bcm2835:14: control element not found Oct 15 20:59:20 volumio volumio[1119]: No state is present for card b1 Oct 15 20:59:20 volumio volumio[1119]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 15 20:59:20 volumio volumio[1119]: No state is present for card Headphones Oct 15 20:59:20 volumio volumio[1119]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 15 20:59:20 volumio volumio[1119]: /usr/share/alsa/init/bcm2835:20: control element not found Oct 15 20:59:20 volumio volumio[1119]: No state is present for card Headphones Oct 15 20:59:20 volumio volumio[1119]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 15 20:59:20 volumio volumio[1119]: No state is present for card vc4hdmi Oct 15 20:59:20 volumio volumio[1119]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 15 20:59:20 volumio volumio[1119]: Found hardware: "vc4-hdmi" "" "" "" "" Oct 15 20:59:20 volumio volumio[1119]: Hardware is initialized using a generic method Oct 15 20:59:20 volumio volumio[1119]: No state is present for card vc4hdmi Oct 15 20:59:20 volumio volumio[1119]: No state is present for card AMP Oct 15 20:59:20 volumio volumio[1119]: Found hardware: "USB-Audio" "USB Mixer" "USB0451:0003" "" "" Oct 15 20:59:20 volumio volumio[1119]: Hardware is initialized using a generic method Oct 15 20:59:20 volumio volumio[1119]: No state is present for card AMP Oct 15 20:59:20 volumio volumio[1119]: info: Output device has changed, restarting MPD Oct 15 20:59:20 volumio go-librespot[9756]: time="2026-10-15T20:59:20+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 15 20:59:20 volumio volumio[1119]: info: Output device has changed, restarting Shairport Sync Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 20:59:20 volumio sudo[10182]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 20:59:20 volumio sudo[10182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:20 volumio sudo[10184]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 20:59:20 volumio sudo[10184]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:20 volumio sudo[10182]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:20 volumio volumio[1119]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 20:59:20 volumio volumio[1119]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 15 20:59:20 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: QobuzConnect: setDeactiveState invoked Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:21 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 15 20:59:21 volumio volumio[1119]: info: Output device has changed, restarting MPD Oct 15 20:59:21 volumio sudo[10194]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 20:59:21 volumio sudo[10194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:21 volumio volumio[1119]: info: Output device has changed, restarting Shairport Sync Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 20:59:21 volumio sudo[10196]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 20:59:21 volumio sudo[10196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:21 volumio sudo[10196]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:21 volumio volumio[1119]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 20:59:21 volumio volumio[1119]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio sudo[10199]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 20:59:21 volumio sudo[10199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:21 volumio volumio[1119]: info: QobuzConnect: setDeactiveState invoked Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:21 volumio sudo[10194]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:21 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 15 20:59:21 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 15 20:59:21 volumio systemd[1]: mpd.service: Consumed 3.292s CPU time. Oct 15 20:59:21 volumio sudo[10209]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 20:59:21 volumio sudo[10209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:21 volumio sudo[10212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 20:59:21 volumio sudo[10212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:21 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 15 20:59:21 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 15 20:59:21 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 15 20:59:21 volumio sudo[10216]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 20:59:21 volumio sudo[10216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:21 volumio sudo[10224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 15 20:59:21 volumio sudo[10224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:21 volumio volumio[1119]: info: MPD Permissions set Oct 15 20:59:21 volumio volumio[1119]: info: MPD Permissions set Oct 15 20:59:21 volumio sudo[10231]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 15 20:59:21 volumio sudo[10231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:21 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: Starting Shairport Sync Oct 15 20:59:21 volumio sudo[10224]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:21 volumio sudo[10231]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:21 volumio volumio[1119]: info: Starting Shairport Sync Oct 15 20:59:21 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 15 20:59:21 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 15 20:59:21 volumio sudo[10246]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 15 20:59:21 volumio sudo[10246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:21 volumio volumio[1119]: info: Preparing to generate the ALSA configuration file Oct 15 20:59:21 volumio sudo[10248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 15 20:59:21 volumio sudo[10248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:21 volumio sudo[10247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 20:59:21 volumio sudo[10247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:21 volumio sudo[10251]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 20:59:21 volumio sudo[10251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:21 volumio volumio[1119]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 15 20:59:21 volumio volumio[1119]: info: Reading ALSA contributions from plugins. Oct 15 20:59:21 volumio sudo[10216]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:21 volumio sudo[10212]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:21 volumio volumio[1119]: info: Asound.conf file written Oct 15 20:59:21 volumio sudo[10209]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:21 volumio sudo[10259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 15 20:59:21 volumio sudo[10259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:21 volumio sudo[10259]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:21 volumio volumio[1119]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 15 20:59:21 volumio volumio[1119]: No state is present for card b1 Oct 15 20:59:21 volumio volumio[1119]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 15 20:59:21 volumio volumio[1119]: /usr/share/alsa/init/bcm2835:14: control element not found Oct 15 20:59:21 volumio volumio[1119]: No state is present for card b1 Oct 15 20:59:21 volumio volumio[1119]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 15 20:59:21 volumio volumio[1119]: No state is present for card Headphones Oct 15 20:59:21 volumio volumio[1119]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Oct 15 20:59:21 volumio volumio[1119]: /usr/share/alsa/init/bcm2835:20: control element not found Oct 15 20:59:21 volumio volumio[1119]: No state is present for card Headphones Oct 15 20:59:21 volumio volumio[1119]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 15 20:59:21 volumio volumio[1119]: No state is present for card vc4hdmi Oct 15 20:59:21 volumio volumio[1119]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Oct 15 20:59:21 volumio volumio[1119]: Found hardware: "vc4-hdmi" "" "" "" "" Oct 15 20:59:21 volumio volumio[1119]: Hardware is initialized using a generic method Oct 15 20:59:21 volumio volumio[1119]: No state is present for card vc4hdmi Oct 15 20:59:21 volumio volumio[1119]: No state is present for card AMP Oct 15 20:59:21 volumio volumio[1119]: Found hardware: "USB-Audio" "USB Mixer" "USB0451:0003" "" "" Oct 15 20:59:21 volumio volumio[1119]: Hardware is initialized using a generic method Oct 15 20:59:21 volumio volumio[1119]: No state is present for card AMP Oct 15 20:59:21 volumio volumio[1119]: info: Output device has changed, restarting MPD Oct 15 20:59:21 volumio volumio[1119]: info: Output device has changed, restarting Shairport Sync Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 20:59:21 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 15 20:59:21 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 15 20:59:21 volumio sudo[10266]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 20:59:21 volumio sudo[10266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:21 volumio sudo[10268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 20:59:21 volumio sudo[10268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:21 volumio sudo[10266]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:21 volumio volumio[1119]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 20:59:21 volumio volumio[1119]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: QobuzConnect: setDeactiveState invoked Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:21 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 15 20:59:21 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 15 20:59:21 volumio volumio[1119]: info: Preparing to generate the ALSA configuration file Oct 15 20:59:21 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 15 20:59:21 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 15 20:59:21 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 20:59:21 volumio systemd[1]: shairport-sync.service: Consumed 2.460s CPU time. Oct 15 20:59:21 volumio sudo[10280]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 20:59:21 volumio sudo[10280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:21 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 20:59:21 volumio sudo[10251]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:21 volumio sudo[10247]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:21 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 15 20:59:21 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 15 20:59:21 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 15 20:59:21 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 15 20:59:21 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 15 20:59:21 volumio volumio[1119]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 15 20:59:21 volumio volumio[1119]: info: Reading ALSA contributions from plugins. Oct 15 20:59:21 volumio sudo[10285]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 20:59:21 volumio sudo[10285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:21 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 15 20:59:21 volumio volumio[1119]: info: MPD Permissions set Oct 15 20:59:21 volumio volumio[1119]: info: Shairport-Sync Started Oct 15 20:59:21 volumio volumio[1119]: info: Shairport-Sync Started Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 15 20:59:21 volumio sudo[10303]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 15 20:59:21 volumio sudo[10303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:21 volumio volumio[1119]: info: Starting Shairport Sync Oct 15 20:59:21 volumio sudo[10303]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:22 volumio volumio[1119]: info: Asound.conf file unchanged, so no further update is needed Oct 15 20:59:22 volumio volumio[1119]: info: Output device has changed, restarting MPD Oct 15 20:59:22 volumio sudo[10285]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:22 volumio sudo[10317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 20:59:22 volumio volumio[1119]: info: Output device has changed, restarting Shairport Sync Oct 15 20:59:22 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:22 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 20:59:22 volumio sudo[10280]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:22 volumio sudo[10318]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 20:59:22 volumio sudo[10318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:22 volumio sudo[10318]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:22 volumio sudo[10315]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 15 20:59:22 volumio sudo[10315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:22 volumio sudo[10321]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 20:59:22 volumio sudo[10321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:22 volumio sudo[10317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:22 volumio volumio[1119]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 20:59:22 volumio volumio[1119]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 15 20:59:22 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:22 volumio sudo[10307]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 15 20:59:22 volumio sudo[10307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 15 20:59:22 volumio sudo[10307]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:22 volumio volumio[1119]: info: QobuzConnect: setDeactiveState invoked Oct 15 20:59:22 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:22 volumio sudo[10337]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 20:59:22 volumio sudo[10337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:22 volumio sudo[10339]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 20:59:22 volumio sudo[10339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:22 volumio volumio[1119]: info: MPD Permissions set Oct 15 20:59:22 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:22 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:22 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:22 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:22 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:22 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:22 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:22 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:22 volumio sudo[10346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 15 20:59:22 volumio sudo[10346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:22 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 20:59:22 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:22 volumio volumio[1119]: info: Starting Shairport Sync Oct 15 20:59:22 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 15 20:59:22 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 15 20:59:22 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 20:59:22 volumio sudo[10354]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 20:59:22 volumio sudo[10354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:22 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 15 20:59:22 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 15 20:59:22 volumio sudo[10337]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:22 volumio sudo[10346]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:22 volumio sudo[10339]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:22 volumio sudo[10357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 15 20:59:22 volumio sudo[10357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:22 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 15 20:59:22 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 15 20:59:22 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 15 20:59:22 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 15 20:59:22 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 15 20:59:22 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 20:59:22 volumio sudo[10317]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:22 volumio qobuz-connect[9382]: 20261015 20:59:22.723 [9382.9382] INFO SampleApp: shat down connection on UNIX socket Oct 15 20:59:22 volumio volumio[1119]: info: Shairport-Sync Started Oct 15 20:59:22 volumio volumio[1119]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 15 20:59:22 volumio volumio[1119]: info: QobuzConnect: setDeactiveState invoked Oct 15 20:59:22 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:22 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 15 20:59:22 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 15 20:59:22 volumio sudo[10354]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:22 volumio volumio[1119]: info: Shairport-Sync Started Oct 15 20:59:22 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 15 20:59:22 volumio sudo[10246]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:22 volumio sudo[10168]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:22 volumio sudo[10315]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:22 volumio sudo[10248]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:22 volumio sudo[10357]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:22 volumio volumio[1119]: info: Executing endpoint qc_getconfig Oct 15 20:59:22 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 15 20:59:22 volumio qobuz-connect[10375]: 20261015 20:59:22.894 [10375.10375] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 15 20:59:22 volumio volumio[1119]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 15 20:59:22 volumio volumio[1119]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 15 20:59:22 volumio qobuz-connect[10375]: 20261015 20:59:22.904 [10375.10375] INFO VolumeManager: [0x21f2058]: Setting new playback volume: 75 Oct 15 20:59:22 volumio qobuz-connect[10375]: 20261015 20:59:22.904 [10375.10375] INFO VolumeManager: [0x21f2058]: Setting new mute state: 0 Oct 15 20:59:22 volumio qobuz-connect[10375]: 20261015 20:59:22.905 [10375.10375] INFO AudioStreamManager: [0x21f1db0]: Setting new audio download buffer size: 1048576 Oct 15 20:59:22 volumio qobuz-connect[10375]: 20261015 20:59:22.905 [10375.10375] INFO QobuzConnect: [0x21f2920]: Client initialized! Oct 15 20:59:22 volumio qobuz-connect[10375]: 20261015 20:59:22.905 [10375.10375] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 15 20:59:22 volumio qobuz-connect[10375]: 20261015 20:59:22.924 [10375.10375] INFO LocalConfigManager: [0x21f1ad8]: Starting Local Configuration server Oct 15 20:59:22 volumio qobuz-connect[10375]: 20261015 20:59:22.924 [10375.10375] INFO SampleApp: Starting Local configuration server Oct 15 20:59:22 volumio qobuz-connect[10375]: 20261015 20:59:22.924 [10375.10375] INFO SampleApp: Connected to UNIX socket client 0x21dc818 Oct 15 20:59:22 volumio sudo[10361]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 15 20:59:22 volumio sudo[10361]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 15 20:59:22 volumio sudo[10361]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:23 volumio qobuz-connect[10375]: 20261015 20:59:23.053 [10375.10375] INFO SampleApp: Playback volume changed: 75 Oct 15 20:59:23 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:23 volumio volumio[1119]: info: TidalConnect service stoped! Oct 15 20:59:23 volumio volumio[1119]: info: TidalConnect service stoped! Oct 15 20:59:23 volumio volumio[1119]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 15 20:59:23 volumio volumio[1119]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 15 20:59:23 volumio sudo[10394]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 15 20:59:23 volumio sudo[10394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:23 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 15 20:59:23 volumio sudo[10394]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:24 volumio volumio[1119]: info: Executing endpoint tc_getconfig Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 15 20:59:24 volumio vtcs[10397]: STARTING TidalConnect services, version: 1.6.1 Oct 15 20:59:24 volumio vtcs[10397]: STARTED TidalConnect services. Oct 15 20:59:24 volumio volumio[1119]: info: TidalConnect service stoped! Oct 15 20:59:24 volumio volumio[1119]: info: Executing endpoint tc_connect Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 15 20:59:24 volumio volumio[1119]: info: Connecting to TidalConnect Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Oct 15 20:59:24 volumio volumio[1119]: info: CoreStateMachine::pushState Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Oct 15 20:59:24 volumio volumio[1119]: info: MRS: Pushing multiroomSync output update for this device Oct 15 20:59:24 volumio volumio[1119]: info: MRS: Pushing multiroomSync output Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:24 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Oct 15 20:59:24 volumio volumio[1119]: 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} Oct 15 20:59:24 volumio volumio[1119]: verbose: CURRENT POSITION 0 Oct 15 20:59:24 volumio volumio[1119]: info: CoreStateMachine::syncState stateService stop Oct 15 20:59:24 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus pause Oct 15 20:59:24 volumio volumio[1119]: info: CoreStateMachine::pushState Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Oct 15 20:59:24 volumio volumio[1119]: info: MRS: Pushing multiroomSync output update for this device Oct 15 20:59:24 volumio volumio[1119]: info: MRS: Pushing multiroomSync output Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::servicePushState Oct 15 20:59:24 volumio volumio[1119]: info: CoreStateMachine::pushState Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Oct 15 20:59:24 volumio volumio[1119]: info: MRS: Pushing multiroomSync output update for this device Oct 15 20:59:24 volumio volumio[1119]: info: MRS: Pushing multiroomSync output Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:24 volumio volumio[1119]: info: CorePlayQueue::getTrack 0 Oct 15 20:59:24 volumio volumio[1119]: 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} Oct 15 20:59:24 volumio volumio[1119]: verbose: CURRENT POSITION 0 Oct 15 20:59:24 volumio volumio[1119]: info: CoreStateMachine::syncState stateService stop Oct 15 20:59:24 volumio volumio[1119]: info: CoreStateMachine::syncState currentStatus pause Oct 15 20:59:24 volumio volumio[1119]: info: CoreStateMachine::pushState Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Oct 15 20:59:24 volumio volumio[1119]: info: MRS: Pushing multiroomSync output update for this device Oct 15 20:59:24 volumio volumio[1119]: info: MRS: Pushing multiroomSync output Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:24 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:24.211Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" state=STATUS_PAUSED positionMs=123950 volume=100 Oct 15 20:59:24 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:24.211Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" id=spotify:track:5u2pW2af1Em61DWrbPZMic title="Turn To Stone" Oct 15 20:59:24 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:24.213Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" state=STATUS_PAUSED positionMs=123950 volume=100 Oct 15 20:59:24 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:24.214Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" id=spotify:track:5u2pW2af1Em61DWrbPZMic title="Turn To Stone" Oct 15 20:59:24 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:24.217Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" state=STATUS_PAUSED positionMs=123950 volume=100 Oct 15 20:59:24 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:24.217Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" id=spotify:track:5u2pW2af1Em61DWrbPZMic title="Turn To Stone" Oct 15 20:59:24 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:24.224Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" state=STATUS_PAUSED positionMs=123950 volume=100 Oct 15 20:59:24 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:24.225Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" id=spotify:track:5u2pW2af1Em61DWrbPZMic title="Turn To Stone" Oct 15 20:59:24 volumio volumio[1119]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 15 20:59:24 volumio volumio[1119]: info: Preparing to save Alsa Options, stopping services first Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::volumioPause Oct 15 20:59:24 volumio volumio[1119]: info: CoreStateMachine::pause Oct 15 20:59:24 volumio volumio[1119]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"SMSL AD18 AMP"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 20:59:24 volumio volumio[1119]: info: Device 5 does not have any Mixer Control Available Oct 15 20:59:24 volumio volumio[1119]: info: QobuzConnect: setDeactiveState invoked Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:24 volumio vtcs[10397]: [2026-10-15 20:59:24.426] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 15 20:59:24 volumio vtcs[10397]: [2026-10-15 20:59:24.427] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=5 errorno=104 error=Connection reset by peer Oct 15 20:59:24 volumio vtcs[10397]: [2026-10-15 20:59:24.427] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 15 20:59:24 volumio sudo[10423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 20:59:24 volumio sudo[10423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 15 20:59:24 volumio volumio[1119]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL AD18 AMP Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 15 20:59:24 volumio volumio[1119]: info: Disabling external Volume Control Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 20:59:24 volumio volumio[1119]: info: CoreStateMachine::pushState Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Oct 15 20:59:24 volumio volumio[1119]: info: MRS: Pushing multiroomSync output update for this device Oct 15 20:59:24 volumio volumio[1119]: info: MRS: Pushing multiroomSync output Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:24 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:24.511Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" state=STATUS_PAUSED positionMs=123950 volume=100 Oct 15 20:59:24 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:24.511Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" id=spotify:track:5u2pW2af1Em61DWrbPZMic title="Turn To Stone" Oct 15 20:59:24 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 15 20:59:24 volumio volumio[1119]: info: Preparing to generate the ALSA configuration file Oct 15 20:59:24 volumio systemd[1]: vtcs.service: Killing process 10404 (vtcs) with signal SIGKILL. Oct 15 20:59:24 volumio systemd[1]: vtcs.service: Deactivated successfully. Oct 15 20:59:24 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 15 20:59:24 volumio sudo[10423]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:24 volumio sudo[10430]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 20:59:24 volumio sudo[10430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 15 20:59:24 volumio volumio[1119]: info: CoreStateMachine::getcurrentVolume Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 20:59:24 volumio volumio[1119]: info: CoreStateMachine::pushState Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Oct 15 20:59:24 volumio volumio[1119]: info: MRS: Pushing multiroomSync output update for this device Oct 15 20:59:24 volumio volumio[1119]: info: MRS: Pushing multiroomSync output Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:24 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:24.620Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" state=STATUS_PAUSED positionMs=123950 volume=100 Oct 15 20:59:24 volumio volumio5-onboarding[1649]: time=2026-10-15T18:59:24.621Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.159:42350,00:00:00:00:00:00%03 @ 0x277a030" id=spotify:track:5u2pW2af1Em61DWrbPZMic title="Turn To Stone" Oct 15 20:59:24 volumio sudo[10437]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 15 20:59:24 volumio sudo[10437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:24 volumio volumio[1119]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 15 20:59:24 volumio volumio[1119]: info: Reading ALSA contributions from plugins. Oct 15 20:59:24 volumio volumio[1119]: info: TidalConnect service stoped! Oct 15 20:59:24 volumio volumio[1119]: info: TidalConnect service stoped! Oct 15 20:59:24 volumio volumio[1119]: info: TidalConnect service stoped! Oct 15 20:59:24 volumio sudo[10430]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:24 volumio sudo[10437]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 20:59:24 volumio sudo[10451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 15 20:59:24 volumio sudo[10451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 15 20:59:24 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 15 20:59:24 volumio qobuz-connect[10375]: 20261015 20:59:24.878 [10375.10375] INFO SampleApp: Stopping Local configuration server Oct 15 20:59:24 volumio volumio[1119]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 15 20:59:24 volumio volumio[1119]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 15 20:59:24 volumio volumio[1119]: info: Asound.conf file unchanged, so no further update is needed Oct 15 20:59:24 volumio volumio[1119]: info: Output device has changed, restarting MPD Oct 15 20:59:24 volumio qobuz-connect[10375]: 20261015 20:59:24.939 [10375.10375] INFO SampleApp: shat down connection on UNIX socket Oct 15 20:59:24 volumio volumio[1119]: info: Output device has changed, restarting Shairport Sync Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:24 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 20:59:24 volumio sudo[10467]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 20:59:24 volumio sudo[10467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:24 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 15 20:59:24 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 15 20:59:24 volumio sudo[10467]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:24 volumio sudo[10469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 20:59:24 volumio sudo[10469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:24 volumio volumio[1119]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 20:59:25 volumio volumio[1119]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 15 20:59:25 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:25 volumio volumio[1119]: info: QobuzConnect: setDeactiveState invoked Oct 15 20:59:25 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:25 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 15 20:59:25 volumio sudo[10451]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:25 volumio sudo[10480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 20:59:25 volumio sudo[10480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:25 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 15 20:59:25 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 15 20:59:25 volumio systemd[1]: mpd.service: Consumed 2.180s CPU time. Oct 15 20:59:25 volumio sudo[10484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 15 20:59:25 volumio sudo[10484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:25 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 15 20:59:25 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 15 20:59:25 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 15 20:59:25 volumio sudo[10490]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 15 20:59:25 volumio sudo[10490]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:25 volumio volumio[1119]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 15 20:59:25 volumio volumio[1119]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 15 20:59:25 volumio volumio[1119]: info: MPD Permissions set Oct 15 20:59:25 volumio volumio[1119]: info: TidalConnect service stoped! Oct 15 20:59:25 volumio sudo[10493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 15 20:59:25 volumio sudo[10493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:25 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 15 20:59:25 volumio volumio[1119]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 15 20:59:25 volumio volumio[1119]: info: QobuzConnect: setDeactiveState invoked Oct 15 20:59:25 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Oct 15 20:59:25 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 15 20:59:25 volumio volumio[1119]: info: Executing endpoint qc_getconfig Oct 15 20:59:25 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 15 20:59:25 volumio qobuz-connect[10475]: 20261015 20:59:25.242 [10475.10475] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 15 20:59:25 volumio qobuz-connect[10475]: 20261015 20:59:25.249 [10475.10475] INFO VolumeManager: [0x1e8c058]: Setting new playback volume: 75 Oct 15 20:59:25 volumio qobuz-connect[10475]: 20261015 20:59:25.249 [10475.10475] INFO VolumeManager: [0x1e8c058]: Setting new mute state: 0 Oct 15 20:59:25 volumio qobuz-connect[10475]: 20261015 20:59:25.249 [10475.10475] INFO AudioStreamManager: [0x1e8bdb0]: Setting new audio download buffer size: 1048576 Oct 15 20:59:25 volumio qobuz-connect[10475]: 20261015 20:59:25.249 [10475.10475] INFO QobuzConnect: [0x1e8c920]: Client initialized! Oct 15 20:59:25 volumio qobuz-connect[10475]: 20261015 20:59:25.249 [10475.10475] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Oct 15 20:59:25 volumio volumio[1119]: info: TidalConnect service stoped! Oct 15 20:59:25 volumio volumio[1119]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 15 20:59:25 volumio volumio[1119]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 15 20:59:25 volumio qobuz-connect[10475]: 20261015 20:59:25.289 [10475.10475] INFO LocalConfigManager: [0x1e8bad8]: Starting Local Configuration server Oct 15 20:59:25 volumio qobuz-connect[10475]: 20261015 20:59:25.289 [10475.10475] INFO SampleApp: Starting Local configuration server Oct 15 20:59:25 volumio qobuz-connect[10475]: 20261015 20:59:25.290 [10475.10475] INFO SampleApp: Connected to UNIX socket client 0x1e76818 Oct 15 20:59:25 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:25 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:25 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:25 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:25 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:25 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:25 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:25 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:25 volumio sudo[10511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 15 20:59:25 volumio sudo[10511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:25 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 20:59:25 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 20:59:25 volumio sudo[10484]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:25 volumio sudo[10480]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:25 volumio volumio[1119]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 15 20:59:25 volumio volumio[1119]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 15 20:59:25 volumio sudo[10490]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:25 volumio volumio[1119]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 15 20:59:25 volumio volumio[1119]: Job for vtcs.service canceled. Oct 15 20:59:25 volumio volumio[1119]: {"cmd":"/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3\nJob for vtcs.service canceled.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Oct 15 20:59:25 volumio volumio[1119]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 20:59:25 volumio qobuz-connect[10475]: 20261015 20:59:25.391 [10475.10475] INFO SampleApp: Playback volume changed: 75 Oct 15 20:59:25 volumio sudo[10518]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 15 20:59:25 volumio sudo[10518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 20:59:25 volumio volumio[1119]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 15 20:59:25 volumio volumio[1119]: Job for vtcs.service canceled. Oct 15 20:59:25 volumio volumio[1119]: at ChildProcess.exithandler (node:child_process:421:12) Oct 15 20:59:25 volumio volumio[1119]: at ChildProcess.emit (node:events:514:28) Oct 15 20:59:25 volumio volumio[1119]: at maybeClose (node:internal/child_process:1105:16) Oct 15 20:59:25 volumio volumio[1119]: at Socket. (node:internal/child_process:457:11) Oct 15 20:59:25 volumio volumio[1119]: at Socket.emit (node:events:514:28) Oct 15 20:59:25 volumio volumio[1119]: at Pipe. (node:net:337:12) { Oct 15 20:59:25 volumio volumio[1119]: code: 1, Oct 15 20:59:25 volumio volumio[1119]: killed: false, Oct 15 20:59:25 volumio volumio[1119]: signal: null, Oct 15 20:59:25 volumio volumio[1119]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Oct 15 20:59:25 volumio volumio[1119]: stdout: '', Oct 15 20:59:25 volumio volumio[1119]: stderr: 'Job for vtcs.service canceled.\n' Oct 15 20:59:25 volumio volumio[1119]: } Oct 15 20:59:25 volumio volumio[1119]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 20:59:25 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 15 20:59:25 volumio sudo[10500]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 15 20:59:25 volumio sudo[10500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 15 20:59:25 volumio sudo[10493]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:25 volumio sudo[10500]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:25 volumio sudo[10511]: pam_unix(sudo:session): session closed for user root Oct 15 20:59:25 volumio qobuz-connect[10475]: 20261015 20:59:25.549 [10475.10475] INFO SampleApp: Stopping Local configuration server Oct 15 20:59:25 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 15 20:59:26 volumio sudo[10540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-15 20:58' Oct 15 20:59:26 volumio sudo[10540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"