Feb 21 20:52:00 volumio kernel: usb 2-2: new SuperSpeed USB device number 108 using xhci_hcd Feb 21 20:52:00 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:52:00 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:52:00 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:52:00 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:52:00 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:52:00 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:52:00 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:52:01 volumio kernel: usb 2-2: USB disconnect, device number 108 Feb 21 20:52:05 volumio kernel: usb 2-2: new SuperSpeed USB device number 109 using xhci_hcd Feb 21 20:52:05 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:52:05 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:52:05 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:52:05 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:52:05 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:52:05 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:52:05 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:52:06 volumio kernel: usb 2-2: USB disconnect, device number 109 Feb 21 20:52:10 volumio kernel: usb 2-2: new SuperSpeed USB device number 110 using xhci_hcd Feb 21 20:52:10 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:52:10 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:52:10 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:52:10 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:52:10 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:52:10 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:52:10 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:52:11 volumio kernel: usb 2-2: USB disconnect, device number 110 Feb 21 20:52:14 volumio kernel: usb 2-2: new SuperSpeed USB device number 111 using xhci_hcd Feb 21 20:52:14 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:52:14 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:52:14 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:52:14 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:52:14 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:52:14 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:52:14 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:52:15 volumio kernel: usb 2-2: USB disconnect, device number 111 Feb 21 20:52:19 volumio kernel: usb 2-2: new SuperSpeed USB device number 112 using xhci_hcd Feb 21 20:52:19 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:52:19 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:52:19 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:52:19 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:52:19 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:52:19 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:52:19 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:52:20 volumio kernel: usb 2-2: USB disconnect, device number 112 Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.399+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.102:54604 Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.457+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.0.102:54604 @ 0x1c000c0" latency=1.076670358s platform=PLATFORM_ANDROID version=5.260209.0 Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.461+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.0.102:54604 @ 0x1c000c0" latency=1.080271879s timeout=10s Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.461+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.0.102:54604 @ 0x1c000c0" Feb 21 20:52:23 volumio volumio[1274]: info: Received Get System Info Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 20:52:23 volumio volumio[1274]: info: Discovery: Getting this device information Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.464+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.0.102:54604 @ 0x1c000c0" name=Volumio Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.465+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.0.102:54604 @ 0x1c000c0" language=en Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.467+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.0.102:54604 @ 0x1c000c0" timezone=Europe/Athens Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.468+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.102:54604 @ 0x1c000c0" available=true connected=false macAddress= ip4Address= ip6Address= Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.472+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.102:54604 @ 0x1c000c0" available=true connected=true macAddress=dc:a6:32:51:b0:5f ip4Address=192.168.0.113/24 ip6Address= ssid=Teras_Archer_5G Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.472+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.0.102:54604 @ 0x1c000c0" setupComplete=true Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:52:23 volumio volumio[1274]: amixer -c 0 info | grep "bcm2835 ALSA" Feb 21 20:52:23 volumio volumio[1274]: amixer -c 1 info | grep "bcm2835 Headphones" Feb 21 20:52:23 volumio volumio[1274]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Feb 21 20:52:23 volumio volumio[1274]: amixer -c 2 info | grep "vc4-hdmi-0" Feb 21 20:52:23 volumio volumio[1274]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Feb 21 20:52:23 volumio volumio[1274]: amixer -c 3 info | grep "vc4-hdmi-1" Feb 21 20:52:23 volumio volumio[1274]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Feb 21 20:52:23 volumio volumio[1274]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Feb 21 20:52:23 volumio volumio[1274]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 21 20:52:23 volumio volumio[1274]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\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)"} Feb 21 20:52:23 volumio volumio[1274]: amixer -c 5 info | grep "Mojo" Feb 21 20:52:23 volumio volumio[1274]: Card sysdefault:5 'Mojo'/'Chord Electronics Ltd Mojo at usb-0000:01:00.0-1.3, high speed' Feb 21 20:52:23 volumio volumio[1274]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Feb 21 20:52:23 volumio volumio[1274]: /bin/sh: 1: /usr/local/bin/alsacap: not found Feb 21 20:52:23 volumio volumio[1274]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\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)"} Feb 21 20:52:23 volumio volumio[1274]: amixer -c 5 info | grep "Mojo" Feb 21 20:52:23 volumio volumio[1274]: Card sysdefault:5 'Mojo'/'Chord Electronics Ltd Mojo at usb-0000:01:00.0-1.3, high speed' Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.629+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.0.102:54604 @ 0x1c000c0" selectedOutputId=5 Feb 21 20:52:23 volumio volumio[1274]: info: Received Get System Info Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 20:52:23 volumio volumio[1274]: info: Discovery: Getting this device information Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.676+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.0.102:54604 @ 0x1c000c0" currentVersion=4.096 latestVersion=4.096 Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.677+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.0.102:54604 @ 0x1c000c0" status=UPDATE_STATUS_NONE progress=0 Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.677+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.0.102:54604 @ 0x1c000c0" userId= Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.677+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.0.102:54604 @ 0x1c000c0" providers=3 Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.677+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.0.102:54604 @ 0x1c000c0" plugins=60 Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.679+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54604 @ 0x1c000c0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.679+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54604 @ 0x1c000c0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 20:52:23 volumio volumio[1274]: info: Discovery: Getting this device information Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 20:52:23 volumio volumio[1274]: verbose: New Socket.io Connection to 192.168.0.113:3000 from 192.168.0.102 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 20:52:23 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.726+02:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.102:54604 Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.726+02:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.102:54604 Feb 21 20:52:23 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:23.734+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.102:54632 Feb 21 20:52:24 volumio kernel: usb 2-2: new SuperSpeed USB device number 113 using xhci_hcd Feb 21 20:52:24 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:52:24 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:52:24 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:52:24 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:52:24 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:52:24 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:52:24 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:52:24 volumio kernel: usb 2-2: USB disconnect, device number 113 Feb 21 20:52:25 volumio volumio5-onboarding[1702]: time=2026-02-21T20:52:25.121+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.102:54632 @ 0x1e203f0" latency=1.082744171s timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Feb 21 20:52:25 volumio sudo[7364]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 20:52:25 volumio sudo[7364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:52:25 volumio sudo[7364]: pam_unix(sudo:session): session closed for user root Feb 21 20:52:25 volumio sudo[7366]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 20:52:25 volumio sudo[7366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:52:25 volumio sudo[7366]: pam_unix(sudo:session): session closed for user root Feb 21 20:52:25 volumio volumio[1274]: verbose: New Socket.io Connection to 192.168.0.113 from 192.168.0.102 UA: Mozilla/5.0 (Linux; Android 15; SM-G996B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Feb 21 20:52:26 volumio sudo[7370]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 20:52:26 volumio sudo[7370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:52:26 volumio sudo[7370]: pam_unix(sudo:session): session closed for user root Feb 21 20:52:26 volumio sudo[7372]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 20:52:26 volumio sudo[7372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:52:26 volumio sudo[7372]: pam_unix(sudo:session): session closed for user root Feb 21 20:52:26 volumio volumio[1274]: verbose: New Socket.io Connection to 192.168.0.113 from 192.168.0.102 UA: Mozilla/5.0 (Linux; Android 15; SM-G996B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Feb 21 20:52:26 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 21 20:52:26 volumio volumio[1274]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 20:52:26 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 20:52:26 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:52:26 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 21 20:52:26 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 21 20:52:26 volumio volumio[1274]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 21 20:52:26 volumio volumio[1274]: info: Received Get System Info Feb 21 20:52:26 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 20:52:26 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 20:52:26 volumio volumio[1274]: info: Discovery: Getting this device information Feb 21 20:52:26 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:52:26 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 20:52:26 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:52:26 volumio volumio[1274]: info: Listing playlists Feb 21 20:52:26 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 21 20:52:26 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 20:52:26 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 21 20:52:27 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 21 20:52:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 20:52:28 volumio volumio[1274]: info: Received Get System Info Feb 21 20:52:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 20:52:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 20:52:28 volumio volumio[1274]: info: Discovery: Getting this device information Feb 21 20:52:28 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:52:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 20:52:28 volumio kernel: usb 2-2: new SuperSpeed USB device number 114 using xhci_hcd Feb 21 20:52:28 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:52:28 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:52:28 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:52:28 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:52:28 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:52:28 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:52:28 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:52:29 volumio kernel: usb 2-2: USB disconnect, device number 114 Feb 21 20:52:29 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 20:52:29 volumio volumio[1274]: info: Received Get System Info Feb 21 20:52:29 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 20:52:29 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 20:52:29 volumio volumio[1274]: info: Discovery: Getting this device information Feb 21 20:52:29 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:52:29 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 20:52:32 volumio kernel: usb 2-2: new SuperSpeed USB device number 115 using xhci_hcd Feb 21 20:52:32 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:52:32 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:52:32 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:52:32 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:52:32 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:52:32 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:52:32 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:52:33 volumio kernel: usb 2-2: USB disconnect, device number 115 Feb 21 20:52:36 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 21 20:52:37 volumio kernel: usb 2-2: new SuperSpeed USB device number 116 using xhci_hcd Feb 21 20:52:37 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:52:37 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:52:37 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:52:37 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:52:37 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:52:37 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:52:37 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:52:38 volumio kernel: usb 2-2: USB disconnect, device number 116 Feb 21 20:52:42 volumio kernel: usb 2-2: new SuperSpeed USB device number 117 using xhci_hcd Feb 21 20:52:42 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:52:42 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:52:42 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:52:42 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:52:42 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:52:42 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:52:42 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:52:43 volumio kernel: usb 2-2: USB disconnect, device number 117 Feb 21 20:52:44 volumio volumio[1274]: info: CoreCommandRouter::volumioGetQueue Feb 21 20:52:44 volumio volumio[1274]: info: CoreStateMachine::getQueue Feb 21 20:52:44 volumio volumio[1274]: info: CorePlayQueue::getQueue Feb 21 20:52:44 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 20:52:44 volumio volumio[1274]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 21 20:52:44 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 21 20:52:44 volumio volumio[1274]: info: Received Get System Version Feb 21 20:52:44 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 20:52:44 volumio volumio[1274]: info: Received Get System Info Feb 21 20:52:44 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 20:52:44 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 20:52:44 volumio volumio[1274]: info: Discovery: Getting this device information Feb 21 20:52:44 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:52:44 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 20:52:46 volumio kernel: usb 2-2: new SuperSpeed USB device number 118 using xhci_hcd Feb 21 20:52:46 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:52:46 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:52:46 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:52:46 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:52:46 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:52:46 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:52:46 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:52:47 volumio kernel: usb 2-2: USB disconnect, device number 118 Feb 21 20:52:49 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 21 20:52:49 volumio volumio[1274]: info: In handleBrowseUri, curUri=spotify Feb 21 20:52:50 volumio volumio[1274]: info: New access token = BQD1MpgED_cxZ08nbZQct1q4DX96AyqcOEvRNdFDEcrz55-hrPZu4JLY0EcExduy2DrPM2rqJeRCX3RX423vVMtLWnHfOuqL5WXaMdMy_taH5s_joLTfg_I9swP30aPSSXoAuaMeoF5CHkBHMIxoxbAfWv9t7JWtRyQOgUpOplda33Zn1FPfEpDQA9i-0yA71vZK5mcnjgCytlanfQUE8aaJVZT5DHZ1eH4CtVqBehmzBybfMDWmCF_t6OrRw7kjhiRK6mfogxB2Ng6b7FQl7VNgP03Mu7iYN6WznKraYB_qQn8UuJaG1xmmfv895QKlDwPHAu4PzuXv Feb 21 20:52:50 volumio volumio[1274]: info: New access token = BQDXB30LT8xMIKhRniAaMaW04C-YNX4ztOMZlYmqCQ9zV1-VbmMlCTd10fmj7eck5LgDwgox1aFXHIZncjUTHJmT5bf4v2vTzAtNVDRvJKxEHw2tspdRTle_LFV52tnEqKXIN39S54OWmWDFKJa-4Ii7WYxAVFl0ZjSts45OcIhdXZgOs__4MsSYJahxuDijz1MwaGQy0Al7uTdQBFcxyZseeYaT3TD7ARoXjNDCocFzV25x0n113y0bMV2G8_NOekIYVFXEa4OdtxDjetSLRmvRL3fwEUhDERZl1huPh5WfCC2XmG1lviEVohutX2xwOwlSNjySF5BC Feb 21 20:52:50 volumio volumio[1274]: info: New access token = BQBtsPR1AJ_Vlh6zj8r05FSKj5_2kinA94vgtfLv6gvivYzjW3tTwqoEIfVQt2gpQWe_ARMySi31bbF42fMzntDGl1xls4EUQrwUIkfjo-HDV39NZb_Z2etqq-bItTJlRBWlICJkhugErXoCIRMjijIkzQsxkuONdOpVr2Dmas6LqIMOtAalfW5ya8-3DDriaWaEAeSDc4hm2vXrO_zBfswheviD4wQvq2PhcPdAHPIVwn_sGIA7D4-HPlLyCfGZEo6N7J7BIgBTKLBo6htJNl97pAF5las_W0kdE0-T_0OCdZFVvz1ohtXsSx0uv6pNVVv95Gk_NcjL Feb 21 20:52:50 volumio volumio[1274]: info: Preload queue cleared Feb 21 20:52:50 volumio volumio[1274]: info: Preload queue cleared Feb 21 20:52:50 volumio volumio[1274]: info: Preload queue cleared Feb 21 20:52:50 volumio volumio[1274]: info: Preload queue cleared Feb 21 20:52:51 volumio kernel: usb 2-2: new SuperSpeed USB device number 119 using xhci_hcd Feb 21 20:52:51 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:52:51 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:52:51 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:52:51 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:52:51 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:52:51 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:52:51 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:52:52 volumio kernel: usb 2-2: USB disconnect, device number 119 Feb 21 20:52:56 volumio kernel: usb 2-2: new SuperSpeed USB device number 120 using xhci_hcd Feb 21 20:52:56 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:52:56 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:52:56 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:52:56 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:52:56 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:52:56 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:52:56 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:52:57 volumio kernel: usb 2-2: USB disconnect, device number 120 Feb 21 20:53:00 volumio kernel: usb 2-2: new SuperSpeed USB device number 121 using xhci_hcd Feb 21 20:53:00 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:53:00 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:53:00 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:53:00 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:53:00 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:53:00 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:53:00 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:53:01 volumio kernel: usb 2-2: USB disconnect, device number 121 Feb 21 20:53:02 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 21 20:53:03 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 21 20:53:03 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 21 20:53:04 volumio kernel: usb 2-2: new SuperSpeed USB device number 122 using xhci_hcd Feb 21 20:53:04 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:53:04 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:53:04 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:53:04 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:53:04 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:53:04 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:53:04 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:53:05 volumio volumio[1274]: info: CoreCommandRouter::volumioVolatilePlay Feb 21 20:53:05 volumio volumio[1274]: ------------------------------------ BT MESSAGE: [FUNC] play Feb 21 20:53:05 volumio volumio[1274]: ------------------------------------ BT MESSAGE: sendPlay skipped: activePlayer not bound or invalid Feb 21 20:53:05 volumio kernel: usb 2-2: USB disconnect, device number 122 Feb 21 20:53:07 volumio volumio[1274]: info: CoreCommandRouter::volumioVolatilePlay Feb 21 20:53:07 volumio volumio[1274]: ------------------------------------ BT MESSAGE: [FUNC] play Feb 21 20:53:07 volumio volumio[1274]: ------------------------------------ BT MESSAGE: sendPlay skipped: activePlayer not bound or invalid Feb 21 20:53:08 volumio kernel: usb 2-2: new SuperSpeed USB device number 123 using xhci_hcd Feb 21 20:53:08 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:53:08 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:53:08 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:53:08 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:53:08 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:53:08 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:53:08 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:53:08 volumio volumio[1274]: info: CoreCommandRouter::volumioVolatilePlay Feb 21 20:53:08 volumio volumio[1274]: ------------------------------------ BT MESSAGE: [FUNC] play Feb 21 20:53:08 volumio volumio[1274]: ------------------------------------ BT MESSAGE: sendPlay skipped: activePlayer not bound or invalid Feb 21 20:53:09 volumio kernel: usb 2-2: USB disconnect, device number 123 Feb 21 20:53:14 volumio kernel: usb 2-2: new SuperSpeed USB device number 124 using xhci_hcd Feb 21 20:53:14 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:53:14 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:53:14 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:53:14 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:53:14 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:53:14 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:53:14 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:53:15 volumio kernel: usb 2-2: USB disconnect, device number 124 Feb 21 20:53:19 volumio kernel: usb 2-2: new SuperSpeed USB device number 125 using xhci_hcd Feb 21 20:53:19 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:53:19 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:53:19 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:53:19 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:53:19 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:53:19 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:53:19 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:53:20 volumio kernel: usb 2-2: USB disconnect, device number 125 Feb 21 20:53:25 volumio volumio[1274]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 20:53:25 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 20:53:25 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:25 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:25 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:25 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:25 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:25 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:25 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:25 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:25 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:25 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 20:53:25 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 20:53:25 volumio kernel: usb 2-2: new SuperSpeed USB device number 126 using xhci_hcd Feb 21 20:53:25 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:53:25 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:53:25 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:53:25 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:53:25 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:53:25 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:53:25 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:53:26 volumio kernel: usb 2-2: USB disconnect, device number 126 Feb 21 20:53:28 volumio volumio[1274]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 21 20:53:28 volumio volumio[1274]: info: Preparing to save Alsa Options, stopping services first Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::volumioPause Feb 21 20:53:28 volumio volumio[1274]: info: CoreStateMachine::pause Feb 21 20:53:28 volumio volumio[1274]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Mojo"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:28 volumio volumio[1274]: info: Setting mixer PCM for card Mojo Feb 21 20:53:28 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:28 volumio vtcs[6604]: [2026-02-21 20:53:28.597] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Feb 21 20:53:28 volumio vtcs[6604]: [2026-02-21 20:53:28.598] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Feb 21 20:53:28 volumio sudo[7575]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:28 volumio sudo[7575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 21 20:53:28 volumio volumio[1274]: info: Updating Volume Controller Parameters: Device: 5 Name: Mojo Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 21 20:53:28 volumio volumio[1274]: info: Disabling external Volume Control Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 20:53:28 volumio volumio[1274]: info: Preparing to generate the ALSA configuration file Feb 21 20:53:28 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 21 20:53:28 volumio systemd[1]: vtcs.service: Deactivated successfully. Feb 21 20:53:28 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 21 20:53:28 volumio sudo[7575]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:28 volumio sudo[7582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:28 volumio sudo[7582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:28 volumio volumio[1274]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 21 20:53:28 volumio volumio[1274]: info: Reading ALSA contributions from plugins. Feb 21 20:53:28 volumio sudo[7588]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:28 volumio sudo[7588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 20:53:28 volumio sudo[7582]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:28 volumio sudo[7588]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 20:53:28 volumio sudo[7600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:28 volumio sudo[7600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:28 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:28 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:28 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:28 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:28 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 21 20:53:28 volumio qobuz-connect[6583]: 20260221 20:53:28.808 [6583.6583] INFO SampleApp: Stopping Local configuration server Feb 21 20:53:28 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:28.821+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:28 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:28.822+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:28 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:28 volumio volumio[1274]: info: Asound.conf file unchanged, so no further update is needed Feb 21 20:53:28 volumio volumio[1274]: info: Output device has changed, restarting MPD Feb 21 20:53:28 volumio volumio[1274]: info: Output device has changed, restarting Shairport Sync Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:28 volumio sudo[7603]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 20:53:28 volumio sudo[7603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:28 volumio sudo[7603]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:28 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 20:53:28 volumio volumio[1274]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:28 volumio sudo[7605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 20:53:28 volumio sudo[7605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:28 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:28 volumio sudo[7615]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:28 volumio sudo[7615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:28 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 21 20:53:28 volumio volumio[1274]: info: MPD Permissions set Feb 21 20:53:28 volumio sudo[7619]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:28 volumio sudo[7619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:28 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:29 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 21 20:53:29 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 21 20:53:29 volumio systemd[1]: mpd.service: Consumed 1.405s CPU time. Feb 21 20:53:29 volumio sudo[7624]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:29 volumio sudo[7624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:29 volumio sudo[7615]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:29 volumio volumio[1274]: info: Starting Shairport Sync Feb 21 20:53:29 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 21 20:53:29 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 21 20:53:29 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 21 20:53:29 volumio sudo[7636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 20:53:29 volumio sudo[7636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:29 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 20:53:29 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 21 20:53:29 volumio sudo[7624]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:29 volumio sudo[7619]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:29 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 21 20:53:29 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 21 20:53:29 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 20:53:29 volumio systemd[1]: shairport-sync.service: Consumed 2.707s CPU time. Feb 21 20:53:29 volumio sudo[7640]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:29 volumio sudo[7640]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:29 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 20:53:29 volumio sudo[7636]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:29 volumio volumio[1274]: info: Shairport-Sync Started Feb 21 20:53:29 volumio sudo[7639]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 20:53:29 volumio sudo[7639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 20:53:29 volumio sudo[7639]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:29 volumio volumio[1274]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 21 20:53:29 volumio volumio[1274]: info: Preparing to save Alsa Options, stopping services first Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::volumioPause Feb 21 20:53:29 volumio volumio[1274]: info: CoreStateMachine::pause Feb 21 20:53:29 volumio volumio[1274]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Mojo"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:29 volumio qobuz-connect[6583]: 20260221 20:53:29.533 [6583.6583] INFO SampleApp: shat down connection on UNIX socket Feb 21 20:53:29 volumio volumio[1274]: info: Setting mixer PCM for card Mojo Feb 21 20:53:29 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:29 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 21 20:53:29 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 21 20:53:29 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 21 20:53:29 volumio sudo[7600]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:29 volumio sudo[7677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:29 volumio sudo[7640]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:29 volumio sudo[7677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:29 volumio volumio[1274]: info: Updating Volume Controller Parameters: Device: 5 Name: Mojo Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 21 20:53:29 volumio volumio[1274]: info: Disabling external Volume Control Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 20:53:29 volumio volumio[1274]: info: Preparing to generate the ALSA configuration file Feb 21 20:53:29 volumio sudo[7677]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:29 volumio sudo[7681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:29 volumio sudo[7681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:29 volumio volumio[1274]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 21 20:53:29 volumio volumio[1274]: info: Reading ALSA contributions from plugins. Feb 21 20:53:29 volumio volumio[1274]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 21 20:53:29 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:29 volumio volumio[1274]: info: Executing endpoint qc_getconfig Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 21 20:53:29 volumio sudo[7689]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:29 volumio sudo[7689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:29 volumio qobuz-connect[7673]: 20260221 20:53:29.820 [7673.7673] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 21 20:53:29 volumio qobuz-connect[7673]: 20260221 20:53:29.825 [7673.7673] INFO VolumeManager: [0x1340258]: Setting new playback volume: 75 Feb 21 20:53:29 volumio qobuz-connect[7673]: 20260221 20:53:29.828 [7673.7673] INFO VolumeManager: [0x1340258]: Setting new mute state: 0 Feb 21 20:53:29 volumio qobuz-connect[7673]: 20260221 20:53:29.828 [7673.7673] INFO QobuzConnect: [0x1340c28]: Client initialized! Feb 21 20:53:29 volumio qobuz-connect[7673]: 20260221 20:53:29.828 [7673.7673] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Feb 21 20:53:29 volumio sudo[7681]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:29 volumio volumio[1274]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 21 20:53:29 volumio volumio[1274]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 21 20:53:29 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:29 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:29 volumio qobuz-connect[7673]: 20260221 20:53:29.855 [7673.7673] INFO LocalConfigManager: [0x133fb38]: Starting Local Configuration server Feb 21 20:53:29 volumio qobuz-connect[7673]: 20260221 20:53:29.855 [7673.7673] INFO SampleApp: Starting Local configuration server Feb 21 20:53:29 volumio qobuz-connect[7673]: 20260221 20:53:29.856 [7673.7673] INFO SampleApp: Connected to UNIX socket client 0x132a818 Feb 21 20:53:29 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:29 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:29 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:29.875+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:29 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:29.882+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:29 volumio sudo[7689]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:29 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:29 volumio volumio[1274]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 21 20:53:29 volumio volumio[1274]: info: Preparing to save Alsa Options, stopping services first Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::volumioPause Feb 21 20:53:29 volumio volumio[1274]: info: CoreStateMachine::pause Feb 21 20:53:29 volumio volumio[1274]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Mojo"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:29 volumio sudo[7698]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:29 volumio sudo[7698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:29 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:29 volumio qobuz-connect[7673]: 20260221 20:53:29.962 [7673.7673] INFO SampleApp: Stopping Local configuration server Feb 21 20:53:29 volumio volumio[1274]: info: Setting mixer PCM for card Mojo Feb 21 20:53:29 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:29 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:30 volumio sudo[7710]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:30 volumio sudo[7710]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 21 20:53:30 volumio volumio[1274]: info: Updating Volume Controller Parameters: Device: 5 Name: Mojo Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 21 20:53:30 volumio volumio[1274]: info: Disabling external Volume Control Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 20:53:30 volumio sudo[7717]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:30 volumio sudo[7717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:30 volumio sudo[7710]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:30 volumio sudo[7725]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:30 volumio sudo[7725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:30 volumio sudo[7717]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:30 volumio sudo[7725]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:30 volumio sudo[7728]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:30 volumio sudo[7728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 20:53:30 volumio volumio[1274]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 21 20:53:30 volumio volumio[1274]: info: Preparing to save Alsa Options, stopping services first Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::volumioPause Feb 21 20:53:30 volumio volumio[1274]: info: CoreStateMachine::pause Feb 21 20:53:30 volumio volumio[1274]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Mojo"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:30 volumio volumio[1274]: info: Setting mixer PCM for card Mojo Feb 21 20:53:30 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:30 volumio sudo[7748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:30 volumio sudo[7748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 21 20:53:30 volumio volumio[1274]: info: Updating Volume Controller Parameters: Device: 5 Name: Mojo Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 21 20:53:30 volumio volumio[1274]: info: Disabling external Volume Control Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 20:53:30 volumio kernel: usb 2-2: new SuperSpeed USB device number 127 using xhci_hcd Feb 21 20:53:30 volumio volumio[1274]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 21 20:53:30 volumio volumio[1274]: info: Preparing to save Alsa Options, stopping services first Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::volumioPause Feb 21 20:53:30 volumio volumio[1274]: info: CoreStateMachine::pause Feb 21 20:53:30 volumio volumio[1274]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Mojo"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:30 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:53:30 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:53:30 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:53:30 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:53:30 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:53:30 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:53:30 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:53:30 volumio sudo[7748]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:30 volumio volumio[1274]: info: Setting mixer PCM for card Mojo Feb 21 20:53:30 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:30 volumio sudo[7770]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:30 volumio sudo[7770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 21 20:53:30 volumio volumio[1274]: info: Updating Volume Controller Parameters: Device: 5 Name: Mojo Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 21 20:53:30 volumio volumio[1274]: info: Disabling external Volume Control Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 20:53:30 volumio sudo[7770]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:30 volumio sudo[7778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:30 volumio sudo[7778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:30 volumio sudo[7780]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:30 volumio sudo[7780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:30 volumio sudo[7778]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:30 volumio sudo[7788]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:30 volumio sudo[7788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:30 volumio sudo[7788]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:30 volumio sudo[7780]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:30 volumio sudo[7796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:30 volumio sudo[7797]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:30 volumio sudo[7797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:30 volumio volumio[1274]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 21 20:53:30 volumio volumio[1274]: info: Preparing to save Alsa Options, stopping services first Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::volumioPause Feb 21 20:53:30 volumio volumio[1274]: info: CoreStateMachine::pause Feb 21 20:53:30 volumio volumio[1274]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Mojo"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:30 volumio sudo[7796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:30 volumio sudo[7797]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:30 volumio sudo[7808]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:30 volumio sudo[7808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:30 volumio volumio[1274]: info: Setting mixer PCM for card Mojo Feb 21 20:53:30 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:30 volumio sudo[7814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:30 volumio sudo[7814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 21 20:53:30 volumio volumio[1274]: info: Updating Volume Controller Parameters: Device: 5 Name: Mojo Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 21 20:53:30 volumio volumio[1274]: info: Disabling external Volume Control Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 20:53:30 volumio volumio[1274]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 21 20:53:30 volumio volumio[1274]: info: Preparing to save Alsa Options, stopping services first Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::volumioPause Feb 21 20:53:30 volumio volumio[1274]: info: CoreStateMachine::pause Feb 21 20:53:30 volumio volumio[1274]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Mojo"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 21 20:53:30 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:31 volumio sudo[7814]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:31 volumio mpd[7658]: 2026-02-21T20:53:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 21 20:53:31 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 21 20:53:31 volumio sudo[7605]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio volumio[1274]: info: Setting mixer PCM for card Mojo Feb 21 20:53:31 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:31 volumio sudo[7833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:31 volumio sudo[7833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 21 20:53:31 volumio volumio[1274]: info: Updating Volume Controller Parameters: Device: 5 Name: Mojo Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 21 20:53:31 volumio volumio[1274]: info: Disabling external Volume Control Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 20:53:31 volumio volumio[1274]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 21 20:53:31 volumio volumio[1274]: info: Preparing to save Alsa Options, stopping services first Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioPause Feb 21 20:53:31 volumio volumio[1274]: info: CoreStateMachine::pause Feb 21 20:53:31 volumio volumio[1274]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Mojo"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:31 volumio sudo[7833]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:31 volumio volumio[1274]: info: Setting mixer PCM for card Mojo Feb 21 20:53:31 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:31 volumio sudo[7851]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:31 volumio sudo[7851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 21 20:53:31 volumio volumio[1274]: info: Updating Volume Controller Parameters: Device: 5 Name: Mojo Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 21 20:53:31 volumio volumio[1274]: info: Disabling external Volume Control Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 20:53:31 volumio sudo[7860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:31 volumio sudo[7860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio sudo[7851]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio sudo[7859]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:31 volumio sudo[7859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio sudo[7864]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:31 volumio sudo[7864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio sudo[7872]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:31 volumio sudo[7872]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio sudo[7859]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio sudo[7879]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:31 volumio sudo[7879]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio kernel: usb 2-2: USB disconnect, device number 127 Feb 21 20:53:31 volumio sudo[7887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:31 volumio sudo[7864]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio sudo[7887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio sudo[7860]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio volumio[1274]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 21 20:53:31 volumio volumio[1274]: info: Preparing to save Alsa Options, stopping services first Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioPause Feb 21 20:53:31 volumio volumio[1274]: info: CoreStateMachine::pause Feb 21 20:53:31 volumio volumio[1274]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Mojo"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 21 20:53:31 volumio sudo[7872]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:31 volumio sudo[7879]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio sudo[7894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:31 volumio sudo[7894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio sudo[7887]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio sudo[7896]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:31 volumio sudo[7896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio sudo[7899]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:31 volumio sudo[7899]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:31 volumio volumio[1274]: info: Setting mixer PCM for card Mojo Feb 21 20:53:31 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:31 volumio sudo[7909]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:31 volumio sudo[7909]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 21 20:53:31 volumio volumio[1274]: info: Updating Volume Controller Parameters: Device: 5 Name: Mojo Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 21 20:53:31 volumio volumio[1274]: info: Disabling external Volume Control Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 20:53:31 volumio volumio[1274]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 21 20:53:31 volumio volumio[1274]: info: Preparing to save Alsa Options, stopping services first Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioPause Feb 21 20:53:31 volumio volumio[1274]: info: CoreStateMachine::pause Feb 21 20:53:31 volumio volumio[1274]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Mojo"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:31 volumio sudo[7909]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio volumio[1274]: info: Setting mixer PCM for card Mojo Feb 21 20:53:31 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:31 volumio sudo[7927]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:31 volumio sudo[7927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 21 20:53:31 volumio volumio[1274]: info: Updating Volume Controller Parameters: Device: 5 Name: Mojo Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 21 20:53:31 volumio volumio[1274]: info: Disabling external Volume Control Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 20:53:31 volumio volumio[1274]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 21 20:53:31 volumio volumio[1274]: info: Preparing to save Alsa Options, stopping services first Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioPause Feb 21 20:53:31 volumio volumio[1274]: info: CoreStateMachine::pause Feb 21 20:53:31 volumio volumio[1274]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Mojo"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:31 volumio sudo[7927]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:31 volumio volumio[1274]: info: Setting mixer PCM for card Mojo Feb 21 20:53:31 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:31 volumio sudo[7944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:31 volumio sudo[7944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 21 20:53:31 volumio volumio[1274]: info: Updating Volume Controller Parameters: Device: 5 Name: Mojo Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 21 20:53:31 volumio volumio[1274]: info: Disabling external Volume Control Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 20:53:31 volumio sudo[7951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:31 volumio sudo[7951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio sudo[7944]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio sudo[7953]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:31 volumio sudo[7953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio sudo[7955]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:31 volumio sudo[7955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio sudo[7966]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:31 volumio sudo[7966]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio qobuz-connect[7673]: 20260221 20:53:31.861 [7673.7673] INFO SampleApp: shat down connection on UNIX socket Feb 21 20:53:31 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 21 20:53:31 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 21 20:53:31 volumio sudo[7971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:31 volumio sudo[7971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:31 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:31 volumio sudo[7981]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:31 volumio sudo[7981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:31 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:31 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:31 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:31.927+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:31 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:31.927+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:31 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 21 20:53:31 volumio sudo[7796]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio sudo[7808]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio sudo[7896]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio sudo[7698]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio sudo[7728]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio sudo[7899]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio sudo[7894]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:31 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:31 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:31 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:31 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:31 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:31 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:31.998+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:31 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:31.999+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:32 volumio sudo[7971]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio sudo[7966]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 20:53:32 volumio sudo[7992]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:32 volumio sudo[7992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:32 volumio sudo[7951]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio volumio[1274]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 21 20:53:32 volumio volumio[1274]: info: Preparing to save Alsa Options, stopping services first Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioPause Feb 21 20:53:32 volumio volumio[1274]: info: CoreStateMachine::pause Feb 21 20:53:32 volumio volumio[1274]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Mojo"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:32 volumio sudo[7955]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio sudo[7993]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:32 volumio sudo[7953]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio sudo[7993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:32 volumio sudo[7981]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio sudo[8002]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:32 volumio sudo[8002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:32 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:32 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 21 20:53:32 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 21 20:53:32 volumio volumio[1274]: info: Setting mixer PCM for card Mojo Feb 21 20:53:32 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:32 volumio sudo[8012]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:32 volumio sudo[8012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 21 20:53:32 volumio volumio[1274]: info: Updating Volume Controller Parameters: Device: 5 Name: Mojo Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 21 20:53:32 volumio volumio[1274]: info: Disabling external Volume Control Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 20:53:32 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 21 20:53:32 volumio sudo[7993]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio sudo[7992]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio sudo[8002]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio sudo[8012]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio sudo[8019]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:32 volumio sudo[8019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:32 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:32 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:32 volumio sudo[8027]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:32 volumio sudo[8027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:32 volumio sudo[8019]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:32 volumio sudo[8027]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:32 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:32 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:32 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:32.338+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:32 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:32 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:32.339+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:32 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:32 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:32 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:32 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:32.353+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:32 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:32.354+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:32 volumio sudo[8030]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:32 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:32 volumio sudo[8030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:32 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:32 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:32 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:32 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:32.368+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:32 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:32.369+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:32 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 21 20:53:32 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 21 20:53:32 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 21 20:53:32 volumio volumio[1274]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 21 20:53:32 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:32 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:32 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:32 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:32 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:32 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:32 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:32 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:32.429+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:32 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:32.429+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:32 volumio volumio[1274]: info: Asound.conf file unchanged, so no further update is needed Feb 21 20:53:32 volumio volumio[1274]: info: Output device has changed, restarting MPD Feb 21 20:53:32 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 21 20:53:32 volumio sudo[8030]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio volumio[1274]: info: Output device has changed, restarting Shairport Sync Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:32 volumio sudo[8041]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 20:53:32 volumio sudo[8041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:32 volumio sudo[8041]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio sudo[8043]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 20:53:32 volumio sudo[8043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:32 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 20:53:32 volumio volumio[1274]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:32 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 21 20:53:32 volumio volumio[1274]: info: Preparing to generate the ALSA configuration file Feb 21 20:53:32 volumio sudo[8053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:32 volumio sudo[8053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:32 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 21 20:53:32 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 21 20:53:32 volumio systemd[1]: mpd.service: Consumed 1.816s CPU time. Feb 21 20:53:32 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 21 20:53:32 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 21 20:53:32 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 21 20:53:32 volumio sudo[8056]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:32 volumio sudo[8056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:32 volumio sudo[8064]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:32 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 20:53:32 volumio sudo[8064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 20:53:32 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:32 volumio sudo[8056]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio sudo[8053]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 20:53:32 volumio sudo[8064]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio sudo[8075]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:32 volumio sudo[8075]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:32 volumio sudo[8069]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 20:53:32 volumio sudo[8069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 20:53:32 volumio sudo[8069]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 20:53:32 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 21 20:53:32 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 21 20:53:32 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 21 20:53:32 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:32 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:32 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 21 20:53:32 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:32 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:32 volumio sudo[8075]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:32 volumio volumio[1274]: error: MPD error: Error: write EPIPE Feb 21 20:53:32 volumio volumio[1274]: error: write EPIPE {"code":"EPIPE","errno":-32,"syscall":"write"} Feb 21 20:53:32 volumio volumio[1274]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 21 20:53:32 volumio volumio[1274]: info: Reading ALSA contributions from plugins. Feb 21 20:53:32 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:32 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:32 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:32 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:32 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:32.927+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:32 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:32.927+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:32 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:32 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:32 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:32 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:32 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:32.938+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:32 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:32.939+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:32 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:32 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:32 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:32 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:32 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:32.952+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:32 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:32.952+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:32 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:32 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 21 20:53:32 volumio volumio[1274]: info: CoreStateMachine::getcurrentVolume Feb 21 20:53:32 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 20:53:33 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:33 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:33 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:33 volumio volumio[1274]: info: Executing endpoint qc_getconfig Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 21 20:53:33 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:33 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:33 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:33 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:33 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:33.309+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:33 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:33.309+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:33 volumio volumio[1274]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:33 volumio volumio[1274]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:33 volumio volumio[1274]: info: MPD Permissions set Feb 21 20:53:33 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:33 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 20:53:33 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:33 volumio volumio[1274]: info: Executing endpoint qc_getconfig Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 21 20:53:33 volumio volumio[1274]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:33 volumio volumio[1274]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:33 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:33 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 21 20:53:33 volumio volumio[1274]: info: Executing endpoint qc_getconfig Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 21 20:53:33 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 21 20:53:33 volumio volumio[1274]: info: CoreStateMachine::getcurrentVolume Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:33 volumio sudo[8175]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 21 20:53:33 volumio sudo[8175]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:33 volumio volumio[1274]: info: Executing endpoint qc_getconfig Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 21 20:53:33 volumio qobuz-connect[8085]: 20260221 20:53:33.881 [8085.8085] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 21 20:53:33 volumio qobuz-connect[8085]: 20260221 20:53:33.890 [8085.8085] INFO VolumeManager: [0x1d49258]: Setting new playback volume: 75 Feb 21 20:53:33 volumio qobuz-connect[8085]: 20260221 20:53:33.890 [8085.8085] INFO VolumeManager: [0x1d49258]: Setting new mute state: 0 Feb 21 20:53:33 volumio qobuz-connect[8085]: 20260221 20:53:33.890 [8085.8085] INFO QobuzConnect: [0x1d49c28]: Client initialized! Feb 21 20:53:33 volumio qobuz-connect[8085]: 20260221 20:53:33.890 [8085.8085] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Feb 21 20:53:33 volumio volumio[1274]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:33 volumio volumio[1274]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:33 volumio volumio[1274]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:33 volumio volumio[1274]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:33 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:33 volumio qobuz-connect[8085]: 20260221 20:53:33.910 [8085.8085] INFO LocalConfigManager: [0x1d48b38]: Starting Local Configuration server Feb 21 20:53:33 volumio qobuz-connect[8085]: 20260221 20:53:33.910 [8085.8085] INFO SampleApp: Starting Local configuration server Feb 21 20:53:33 volumio qobuz-connect[8085]: 20260221 20:53:33.910 [8085.8085] INFO SampleApp: Connected to UNIX socket client 0x1d33818 Feb 21 20:53:33 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:33 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:33 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:33 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:33 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:33 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Feb 21 20:53:33 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Feb 21 20:53:33 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:33 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:33.935+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:33 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:33.935+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:33 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 21 20:53:33 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:33 volumio volumio[1274]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 21 20:53:33 volumio volumio[1274]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 21 20:53:33 volumio sudo[8175]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:33 volumio sudo[8190]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 21 20:53:33 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:33 volumio sudo[8190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:34 volumio volumio[1274]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:34 volumio volumio[1274]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:34 volumio qobuz-connect[8085]: 20260221 20:53:34.031 [8085.8085] INFO SampleApp: Playback volume changed: 75 Feb 21 20:53:34 volumio volumio[1274]: info: Starting Shairport Sync Feb 21 20:53:34 volumio sudo[8204]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 20:53:34 volumio sudo[8204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:34 volumio sudo[8190]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:34 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:34 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:34 volumio sudo[8210]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 21 20:53:34 volumio sudo[8210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:34 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 21 20:53:34 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 21 20:53:34 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 20:53:34 volumio systemd[1]: shairport-sync.service: Consumed 1.517s CPU time. Feb 21 20:53:34 volumio sudo[8207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 21 20:53:34 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:34 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:34 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Feb 21 20:53:34 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:34 volumio sudo[8207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:34 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:34.164+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:34 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:34.164+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 21 20:53:34 volumio volumio[1274]: info: CoreStateMachine::getcurrentVolume Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:34 volumio volumio[1274]: info: Asound.conf file unchanged, so no further update is needed Feb 21 20:53:34 volumio volumio[1274]: info: Output device has changed, restarting MPD Feb 21 20:53:34 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 20:53:34 volumio sudo[8204]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:34 volumio volumio[1274]: info: Output device has changed, restarting Shairport Sync Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:34 volumio sudo[8218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 20:53:34 volumio sudo[8218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:34 volumio sudo[8218]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:34 volumio sudo[8221]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 20:53:34 volumio sudo[8221]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:34 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 20:53:34 volumio volumio[1274]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:34 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:34 volumio sudo[8210]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:34 volumio volumio[1274]: info: Preparing to generate the ALSA configuration file Feb 21 20:53:34 volumio sudo[8207]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:34 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 21 20:53:34 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 21 20:53:34 volumio systemd[1]: mpd.service: Consumed 1.461s CPU time. Feb 21 20:53:34 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 21 20:53:34 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 21 20:53:34 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 21 20:53:34 volumio sudo[8244]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:34 volumio sudo[8244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:34 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 20:53:34 volumio sudo[8251]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:34 volumio sudo[8251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:34 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 21 20:53:34 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 21 20:53:34 volumio systemd[1]: vtcs.service: Deactivated successfully. Feb 21 20:53:34 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 21 20:53:34 volumio sudo[8259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:34 volumio sudo[8259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:34 volumio volumio[1274]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 21 20:53:34 volumio volumio[1274]: info: Reading ALSA contributions from plugins. Feb 21 20:53:34 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:34 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:34 volumio sudo[8251]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:34 volumio sudo[8244]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:34 volumio sudo[8259]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:34 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:34 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:34 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:34 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:34 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:34.529+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:34 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:34.530+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:34 volumio sudo[8257]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 20:53:34 volumio sudo[8257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 20:53:34 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 21 20:53:34 volumio volumio[1274]: info: CoreStateMachine::getcurrentVolume Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:34 volumio sudo[8264]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:34 volumio sudo[8264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:34 volumio sudo[8257]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:34 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:34 volumio sudo[8274]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 21 20:53:34 volumio sudo[8274]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:34 volumio volumio[1274]: info: Executing endpoint tc_getconfig Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 21 20:53:34 volumio volumio[1274]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:34 volumio volumio[1274]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:34 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:34 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:34 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:34 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:34 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:34.610+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:34 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:34.610+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:34 volumio kernel: usb 2-2: new SuperSpeed USB device number 2 using xhci_hcd Feb 21 20:53:34 volumio volumio[1274]: info: MPD Permissions set Feb 21 20:53:34 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:34 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:34 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:53:34 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:53:34 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:53:34 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:53:34 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:53:34 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:34 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:53:34 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:53:34 volumio qobuz-connect[8085]: 20260221 20:53:34.660 [8085.8085] INFO SampleApp: Stopping Local configuration server Feb 21 20:53:34 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 21 20:53:34 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:34 volumio volumio[1274]: info: Shairport-Sync Started Feb 21 20:53:34 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 21 20:53:34 volumio sudo[8274]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 21 20:53:34 volumio volumio[1274]: info: CoreStateMachine::getcurrentVolume Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:34 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:34 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:34 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:34 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:34 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:34 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Feb 21 20:53:34 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:34 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:34.820+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:34 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:34.820+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:34 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:34 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:34 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:34 volumio volumio[1274]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:34 volumio volumio[1274]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:34 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:34 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:35 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:35 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:35 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:35 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:35 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Feb 21 20:53:35 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:35 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:35.034+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:35 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:35.035+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:35 volumio volumio[1274]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:35 volumio volumio[1274]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:35 volumio volumio[1274]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:35 volumio volumio[1274]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 21 20:53:35 volumio volumio[1274]: info: CoreStateMachine::getcurrentVolume Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:35 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:35 volumio sudo[8320]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 21 20:53:35 volumio sudo[8320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:35 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:35 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:35 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:35 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:35 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Feb 21 20:53:35 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:35 volumio sudo[8320]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:35 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:35.168+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:35 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:35.168+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:35 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:35 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:35 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:35 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:35 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:35.195+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:35 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:35.196+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 21 20:53:35 volumio volumio[1274]: info: CoreStateMachine::getcurrentVolume Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 21 20:53:35 volumio volumio[1274]: info: CoreStateMachine::getcurrentVolume Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:35 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:35 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:35 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:35 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:35 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:35 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:35 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:35.305+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:35 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:35.306+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:35 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:35 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:35 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:35 volumio volumio[1274]: info: Starting Shairport Sync Feb 21 20:53:35 volumio sudo[8359]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 21 20:53:35 volumio sudo[8359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:35 volumio sudo[8362]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 20:53:35 volumio sudo[8362]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:35 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:35 volumio sudo[8359]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:35 volumio sudo[8366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 21 20:53:35 volumio volumio[1274]: info: Executing endpoint tc_getconfig Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 21 20:53:35 volumio vtcs[8294]: STARTING TidalConnect services, version: 1.6.1 Feb 21 20:53:35 volumio sudo[8366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:35 volumio vtcs[8294]: STARTED TidalConnect services. Feb 21 20:53:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 21 20:53:35 volumio sudo[8364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 21 20:53:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 21 20:53:35 volumio kernel: usb 2-2: USB disconnect, device number 2 Feb 21 20:53:35 volumio sudo[8364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 20:53:35 volumio systemd[1]: shairport-sync.service: Consumed 1.149s CPU time. Feb 21 20:53:35 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:35 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:35 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:35 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:35 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:35.546+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:35 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:35.546+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:35 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:35 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:35 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:35 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:35 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Feb 21 20:53:35 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:35 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:35.573+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:35 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:35.574+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:35 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:35 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 21 20:53:35 volumio volumio[1274]: info: CoreStateMachine::getcurrentVolume Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:35 volumio sudo[8362]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:35 volumio volumio[1274]: info: Asound.conf file unchanged, so no further update is needed Feb 21 20:53:35 volumio volumio[1274]: info: Output device has changed, restarting MPD Feb 21 20:53:35 volumio sudo[8364]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:35 volumio sudo[8366]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:35 volumio volumio[1274]: info: Output device has changed, restarting Shairport Sync Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:35 volumio sudo[8402]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 20:53:35 volumio sudo[8402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:35 volumio sudo[8402]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:35 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 20:53:35 volumio volumio[1274]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:35 volumio sudo[8408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 20:53:35 volumio sudo[8408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:35 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:35 volumio volumio[1274]: info: Preparing to generate the ALSA configuration file Feb 21 20:53:35 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 21 20:53:35 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 21 20:53:35 volumio systemd[1]: mpd.service: Consumed 1.184s CPU time. Feb 21 20:53:35 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 21 20:53:35 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 21 20:53:35 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 21 20:53:35 volumio sudo[8418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:35 volumio sudo[8418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:35 volumio sudo[8422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:35 volumio sudo[8422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:35 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:35 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:35 volumio sudo[8429]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:35 volumio sudo[8429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:35 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:35 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 20:53:35 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:35 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:35 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Feb 21 20:53:35 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 21 20:53:35 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:35.889+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:35 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:35.890+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:35 volumio qobuz-connect[8085]: 20260221 20:53:35.923 [8085.8085] INFO SampleApp: shat down connection on UNIX socket Feb 21 20:53:35 volumio sudo[8429]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:35 volumio volumio[1274]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:35 volumio volumio[1274]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:35 volumio volumio[1274]: info: Shairport-Sync Started Feb 21 20:53:35 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:35 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:35 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:35 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 21 20:53:35 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:35 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:35 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Feb 21 20:53:35 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Feb 21 20:53:35 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:35 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 21 20:53:35 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 21 20:53:35 volumio systemd[1]: vtcs.service: Killing process 8375 (vtcs) with signal SIGKILL. Feb 21 20:53:35 volumio systemd[1]: vtcs.service: Deactivated successfully. Feb 21 20:53:35 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 21 20:53:35 volumio sudo[8264]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:36 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:36.015+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:36 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:36.015+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:36 volumio sudo[8422]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:36 volumio sudo[8431]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 20:53:36 volumio sudo[8431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 20:53:36 volumio sudo[8418]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:36 volumio sudo[8438]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:36 volumio sudo[8438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:36 volumio sudo[8431]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 21 20:53:36 volumio volumio[1274]: info: CoreStateMachine::getcurrentVolume Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 21 20:53:36 volumio volumio[1274]: info: CoreStateMachine::getcurrentVolume Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:36 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:36 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:36 volumio volumio[1274]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 21 20:53:36 volumio volumio[1274]: info: Reading ALSA contributions from plugins. Feb 21 20:53:36 volumio volumio[1274]: info: Executing endpoint tc_connect Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 21 20:53:36 volumio volumio[1274]: info: Connecting to TidalConnect Feb 21 20:53:36 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 21 20:53:36 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 21 20:53:36 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 21 20:53:36 volumio volumio[1274]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:36 volumio volumio[1274]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:36 volumio volumio[1274]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:36 volumio volumio[1274]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:36 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 21 20:53:36 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:36 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:36 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:36 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:36 volumio sudo[8438]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:36 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:36.201+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:36 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:36.202+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:36 volumio volumio[1274]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:36 volumio volumio[1274]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:36 volumio volumio[1274]: info: MPD Permissions set Feb 21 20:53:36 volumio volumio[1274]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 21 20:53:36 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:36 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:36 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 21 20:53:36 volumio volumio[1274]: info: CoreStateMachine::getcurrentVolume Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:36 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:36 volumio volumio[1274]: info: Executing endpoint qc_getconfig Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 21 20:53:36 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:36 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:36 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:36 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:36 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:36.344+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:36 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:36.345+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:36 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:36 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:36 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:36 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:36 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Feb 21 20:53:36 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:36 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:36.380+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:36 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:36.380+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:36 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:36 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:36 volumio volumio[1274]: info: Executing endpoint qc_getconfig Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 21 20:53:36 volumio sudo[8478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 21 20:53:36 volumio sudo[8478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:36 volumio qobuz-connect[8459]: 20260221 20:53:36.465 [8459.8459] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 21 20:53:36 volumio qobuz-connect[8459]: 20260221 20:53:36.469 [8459.8459] INFO VolumeManager: [0x7a8258]: Setting new playback volume: 75 Feb 21 20:53:36 volumio qobuz-connect[8459]: 20260221 20:53:36.469 [8459.8459] INFO VolumeManager: [0x7a8258]: Setting new mute state: 0 Feb 21 20:53:36 volumio qobuz-connect[8459]: 20260221 20:53:36.470 [8459.8459] INFO QobuzConnect: [0x7a8c28]: Client initialized! Feb 21 20:53:36 volumio qobuz-connect[8459]: 20260221 20:53:36.470 [8459.8459] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Feb 21 20:53:36 volumio volumio[1274]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:36 volumio volumio[1274]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:36 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:36 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:36 volumio qobuz-connect[8459]: 20260221 20:53:36.504 [8459.8459] INFO LocalConfigManager: [0x7a7b38]: Starting Local Configuration server Feb 21 20:53:36 volumio qobuz-connect[8459]: 20260221 20:53:36.504 [8459.8459] INFO SampleApp: Starting Local configuration server Feb 21 20:53:36 volumio qobuz-connect[8459]: 20260221 20:53:36.504 [8459.8459] INFO SampleApp: Connected to UNIX socket client 0x792818 Feb 21 20:53:36 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:36 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:36 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:36.521+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:36 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:36.522+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:36 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:36 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:36 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:36 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:36 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Feb 21 20:53:36 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:36 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:36.577+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:36 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:36.578+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:36 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:36 volumio sudo[8478]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:36 volumio qobuz-connect[8459]: 20260221 20:53:36.615 [8459.8459] INFO SampleApp: Playback volume changed: 75 Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:36 volumio sudo[8497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 21 20:53:36 volumio sudo[8497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:36 volumio sudo[8499]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 21 20:53:36 volumio sudo[8499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:36 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:36 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:36 volumio volumio[1274]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 21 20:53:36 volumio volumio[1274]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 21 20:53:36 volumio sudo[8502]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 21 20:53:36 volumio sudo[8502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:36 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:36 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:36 volumio sudo[8499]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:36 volumio sudo[8497]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:36 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:36 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:36 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Feb 21 20:53:36 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:36 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:36.811+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:36 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:36.812+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:36 volumio sudo[8502]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:36 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:36 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:36 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:36 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:36 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:36.886+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:36 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:36.886+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:36 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:36 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:36 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:36 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:36 volumio volumio[1274]: info: CoreStateMachine::updateTrackBlock Feb 21 20:53:36 volumio volumio[1274]: info: CorePlayQueue::getTrackBlock Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioRetrievevolume Feb 21 20:53:36 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:36.909+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:36 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:36.910+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:36 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:36 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:36 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioVolatilePlay Feb 21 20:53:36 volumio volumio[1274]: ------------------------------------ BT MESSAGE: [FUNC] play Feb 21 20:53:36 volumio volumio[1274]: ------------------------------------ BT MESSAGE: sendPlay skipped: activePlayer not bound or invalid Feb 21 20:53:36 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:37 volumio volumio[1274]: info: Executing endpoint tc_getconfig Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 21 20:53:37 volumio vtcs[8489]: STARTING TidalConnect services, version: 1.6.1 Feb 21 20:53:37 volumio vtcs[8489]: STARTED TidalConnect services. Feb 21 20:53:37 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:37 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:37 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:37 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:37 volumio sudo[8513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 21 20:53:37 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:37.071+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:37 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:37.071+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:37 volumio sudo[8513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:37 volumio volumio[1274]: info: TidalConnect service started! Feb 21 20:53:37 volumio volumio[1274]: info: Starting Shairport Sync Feb 21 20:53:37 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:37 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:37 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:37 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:37 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:37 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:37.138+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:37 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:37.139+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:37 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:37 volumio sudo[8513]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:37 volumio volumio[1274]: info: Executing endpoint tc_connect Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 21 20:53:37 volumio volumio[1274]: info: Connecting to TidalConnect Feb 21 20:53:37 volumio volumio[1274]: info: VolumeController:: Volume=100 Mute =false Feb 21 20:53:37 volumio volumio[1274]: info: CoreStateMachine::pushState Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::volumioPushState Feb 21 20:53:37 volumio sudo[8523]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 20:53:37 volumio volumio[1274]: info: MRS: Pushing multiroomSync output update for this device Feb 21 20:53:37 volumio volumio[1274]: info: MRS: Pushing multiroomSync output Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:37 volumio sudo[8523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:37 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:37.180+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" state=STATUS_PAUSED positionMs=43063 volume=100 Feb 21 20:53:37 volumio volumio5-onboarding[1702]: time=2026-02-21T20:53:37.180+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.102:54632 @ 0x1e203f0" id=spotify:track:3uI0EptPACbrdR1Dq20oWu title="Riders On the Storm" Feb 21 20:53:37 volumio volumio[1274]: info: TidalConnect service started! Feb 21 20:53:37 volumio volumio[1274]: info: Asound.conf file unchanged, so no further update is needed Feb 21 20:53:37 volumio volumio[1274]: info: Output device has changed, restarting MPD Feb 21 20:53:37 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 21 20:53:37 volumio volumio[1274]: info: Output device has changed, restarting Shairport Sync Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:37 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 21 20:53:37 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 20:53:37 volumio systemd[1]: shairport-sync.service: Consumed 1.249s CPU time. Feb 21 20:53:37 volumio sudo[8527]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 20:53:37 volumio sudo[8527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:37 volumio sudo[8527]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:37 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 20:53:37 volumio volumio[1274]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio sudo[8530]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 20:53:37 volumio sudo[8530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:37 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:37 volumio vtcs[8489]: [2026-02-21 20:53:37.264] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer Feb 21 20:53:37 volumio vtcs[8489]: [2026-02-21 20:53:37.264] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Feb 21 20:53:37 volumio volumio[1274]: info: Preparing to generate the ALSA configuration file Feb 21 20:53:37 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 20:53:37 volumio sudo[8540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:37 volumio sudo[8540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:37 volumio sudo[8523]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:37 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 21 20:53:37 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 21 20:53:37 volumio systemd[1]: mpd.service: Consumed 1.152s CPU time. Feb 21 20:53:37 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 21 20:53:37 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 21 20:53:37 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 21 20:53:37 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 20:53:37 volumio sudo[8543]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:37 volumio sudo[8543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:37 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 21 20:53:37 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 21 20:53:37 volumio volumio[1274]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 21 20:53:37 volumio systemd[1]: vtcs.service: Deactivated successfully. Feb 21 20:53:37 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 21 20:53:37 volumio volumio[1274]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 21 20:53:37 volumio volumio[1274]: info: Reading ALSA contributions from plugins. Feb 21 20:53:37 volumio sudo[8540]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:37 volumio volumio[1274]: info: MPD Permissions set Feb 21 20:53:37 volumio volumio[1274]: info: TidalConnect service started! Feb 21 20:53:37 volumio volumio[1274]: info: Shairport-Sync Started Feb 21 20:53:37 volumio volumio[1274]: info: TidalConnect service started! Feb 21 20:53:37 volumio sudo[8565]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:37 volumio sudo[8565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio sudo[8565]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:37 volumio sudo[8543]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:37 volumio volumio[1274]: info: Starting Shairport Sync Feb 21 20:53:37 volumio sudo[8574]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:37 volumio sudo[8574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:37 volumio volumio[1274]: info: Asound.conf file unchanged, so no further update is needed Feb 21 20:53:37 volumio volumio[1274]: info: Output device has changed, restarting MPD Feb 21 20:53:37 volumio sudo[8562]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 20:53:37 volumio sudo[8562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 20:53:37 volumio sudo[8562]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:37 volumio sudo[8577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 20:53:37 volumio sudo[8577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:37 volumio volumio[1274]: info: Output device has changed, restarting Shairport Sync Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:37 volumio sudo[8582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 20:53:37 volumio sudo[8582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:37 volumio sudo[8585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 20:53:37 volumio sudo[8585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:37 volumio sudo[8582]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:37 volumio qobuz-connect[8459]: 20260221 20:53:37.587 [8459.8459] INFO SampleApp: Stopping Local configuration server Feb 21 20:53:37 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 21 20:53:37 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 21 20:53:37 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 21 20:53:37 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 20:53:37 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 20:53:37 volumio volumio[1274]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:37 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 20:53:37 volumio sudo[8577]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:37 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 21 20:53:37 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 21 20:53:37 volumio volumio[1274]: info: Preparing to generate the ALSA configuration file Feb 21 20:53:37 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 21 20:53:37 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 21 20:53:37 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 21 20:53:37 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 20:53:37 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 21 20:53:37 volumio sudo[8596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:37 volumio sudo[8596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:37 volumio sudo[8613]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:37 volumio sudo[8613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:37 volumio volumio[1274]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 21 20:53:37 volumio volumio[1274]: info: Reading ALSA contributions from plugins. Feb 21 20:53:37 volumio volumio[1274]: info: MPD Permissions set Feb 21 20:53:37 volumio volumio[1274]: info: TidalConnect service started! Feb 21 20:53:37 volumio volumio[1274]: info: Shairport-Sync Started Feb 21 20:53:37 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:37 volumio sudo[8622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:37 volumio sudo[8622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:37 volumio sudo[8596]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:37 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio sudo[8622]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:37 volumio sudo[8598]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 20:53:37 volumio sudo[8598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 20:53:37 volumio sudo[8598]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:37 volumio volumio[1274]: info: Starting Shairport Sync Feb 21 20:53:37 volumio sudo[8613]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:37 volumio volumio[1274]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:37 volumio volumio[1274]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:37 volumio sudo[8634]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:37 volumio sudo[8634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:37 volumio sudo[8643]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 20:53:37 volumio volumio[1274]: info: Asound.conf file unchanged, so no further update is needed Feb 21 20:53:37 volumio sudo[8643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:37 volumio volumio[1274]: info: Output device has changed, restarting MPD Feb 21 20:53:37 volumio volumio[1274]: info: Output device has changed, restarting Shairport Sync Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:37 volumio sudo[8647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 20:53:37 volumio sudo[8647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:37 volumio sudo[8647]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:37 volumio sudo[8649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 20:53:37 volumio sudo[8649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:37 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 20:53:37 volumio volumio[1274]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 21 20:53:37 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 21 20:53:38 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 21 20:53:38 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 20:53:38 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:38 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 21 20:53:38 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 21 20:53:38 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 21 20:53:38 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 21 20:53:38 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 21 20:53:38 volumio volumio[1274]: info: Preparing to generate the ALSA configuration file Feb 21 20:53:38 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 20:53:38 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 21 20:53:38 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Feb 21 20:53:38 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Feb 21 20:53:38 volumio sudo[8660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:38 volumio systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 20:53:38 volumio sudo[8660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio sudo[8643]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:38 volumio sudo[8664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:38 volumio sudo[8664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio volumio[1274]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 21 20:53:38 volumio volumio[1274]: info: Reading ALSA contributions from plugins. Feb 21 20:53:38 volumio volumio[1274]: info: MPD Permissions set Feb 21 20:53:38 volumio volumio[1274]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Feb 21 20:53:38 volumio volumio[1274]: Job for shairport-sync.service failed because start of the service was attempted too often. Feb 21 20:53:38 volumio volumio[1274]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Feb 21 20:53:38 volumio volumio[1274]: To force a start use "systemctl reset-failed shairport-sync.service" Feb 21 20:53:38 volumio volumio[1274]: followed by "systemctl start shairport-sync.service" again. Feb 21 20:53:38 volumio sudo[8671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:38 volumio sudo[8671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio sudo[8674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 21 20:53:38 volumio sudo[8674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: TidalConnect service started! Feb 21 20:53:38 volumio volumio[1274]: info: Starting Shairport Sync Feb 21 20:53:38 volumio volumio[1274]: info: Asound.conf file unchanged, so no further update is needed Feb 21 20:53:38 volumio volumio[1274]: info: Output device has changed, restarting MPD Feb 21 20:53:38 volumio sudo[8660]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:38 volumio sudo[8671]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:38 volumio sudo[8661]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 20:53:38 volumio sudo[8661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 20:53:38 volumio sudo[8664]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:38 volumio sudo[8661]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:38 volumio sudo[8682]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 20:53:38 volumio sudo[8682]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio sudo[8685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:38 volumio sudo[8685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio sudo[8688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 20:53:38 volumio sudo[8688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio volumio[1274]: info: Output device has changed, restarting Shairport Sync Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:38 volumio sudo[8692]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 20:53:38 volumio sudo[8692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 20:53:38 volumio volumio[1274]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 21 20:53:38 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:38 volumio volumio[1274]: info: Preparing to generate the ALSA configuration file Feb 21 20:53:38 volumio sudo[8674]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:38 volumio sudo[8704]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:38 volumio sudo[8704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Feb 21 20:53:38 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Feb 21 20:53:38 volumio systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 20:53:38 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 21 20:53:38 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 21 20:53:38 volumio sudo[8682]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:38 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 21 20:53:38 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 21 20:53:38 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 21 20:53:38 volumio sudo[8708]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:38 volumio sudo[8708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio volumio[1274]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 21 20:53:38 volumio volumio[1274]: info: Reading ALSA contributions from plugins. Feb 21 20:53:38 volumio volumio[1274]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Feb 21 20:53:38 volumio volumio[1274]: Job for shairport-sync.service failed because start of the service was attempted too often. Feb 21 20:53:38 volumio volumio[1274]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Feb 21 20:53:38 volumio volumio[1274]: To force a start use "systemctl reset-failed shairport-sync.service" Feb 21 20:53:38 volumio volumio[1274]: followed by "systemctl start shairport-sync.service" again. Feb 21 20:53:38 volumio volumio[1274]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Feb 21 20:53:38 volumio volumio[1274]: Job for vtcs.service canceled. Feb 21 20:53:38 volumio volumio[1274]: {"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":""} Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio sudo[8716]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:38 volumio sudo[8716]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: Executing endpoint tc_getconfig Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 21 20:53:38 volumio vtcs[8700]: STARTING TidalConnect services, version: 1.6.1 Feb 21 20:53:38 volumio volumio[1274]: info: Starting Shairport Sync Feb 21 20:53:38 volumio vtcs[8700]: STARTED TidalConnect services. Feb 21 20:53:38 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 20:53:38 volumio volumio[1274]: info: Executing endpoint tc_connect Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 21 20:53:38 volumio volumio[1274]: info: Connecting to TidalConnect Feb 21 20:53:38 volumio volumio[1274]: info: Asound.conf file unchanged, so no further update is needed Feb 21 20:53:38 volumio volumio[1274]: info: Output device has changed, restarting MPD Feb 21 20:53:38 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 21 20:53:38 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 21 20:53:38 volumio sudo[8732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 20:53:38 volumio sudo[8732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio volumio[1274]: info: Output device has changed, restarting Shairport Sync Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:38 volumio systemd[1]: vtcs.service: Deactivated successfully. Feb 21 20:53:38 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 21 20:53:38 volumio qobuz-connect[8459]: 20260221 20:53:38.512 [8459.8459] INFO SampleApp: shat down connection on UNIX socket Feb 21 20:53:38 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 20:53:38 volumio volumio[1274]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 21 20:53:38 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 21 20:53:38 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio sudo[8738]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 20:53:38 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:38 volumio sudo[8735]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 20:53:38 volumio sudo[8735]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio sudo[8738]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio volumio[1274]: info: Preparing to generate the ALSA configuration file Feb 21 20:53:38 volumio sudo[8748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:38 volumio sudo[8748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio sudo[8716]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:38 volumio sudo[8704]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:38 volumio sudo[8735]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:38 volumio sudo[8688]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:38 volumio sudo[8750]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:38 volumio sudo[8750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio sudo[8754]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:38 volumio sudo[8754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio volumio[1274]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 21 20:53:38 volumio volumio[1274]: info: Reading ALSA contributions from plugins. Feb 21 20:53:38 volumio volumio[1274]: info: MPD Permissions set Feb 21 20:53:38 volumio volumio[1274]: info: MPD Permissions set Feb 21 20:53:38 volumio volumio[1274]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 21 20:53:38 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:38 volumio sudo[8761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:38 volumio volumio[1274]: info: TidalConnect service started! Feb 21 20:53:38 volumio sudo[8761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: TidalConnect service started! Feb 21 20:53:38 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 21 20:53:38 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Feb 21 20:53:38 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Feb 21 20:53:38 volumio systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 20:53:38 volumio sudo[8685]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:38 volumio volumio[1274]: info: TidalConnect service started! Feb 21 20:53:38 volumio sudo[8634]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:38 volumio volumio[1274]: info: Starting Shairport Sync Feb 21 20:53:38 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 21 20:53:38 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 21 20:53:38 volumio sudo[8574]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:38 volumio volumio[1274]: info: Asound.conf file unchanged, so no further update is needed Feb 21 20:53:38 volumio volumio[1274]: info: Output device has changed, restarting MPD Feb 21 20:53:38 volumio kernel: usb 2-2: new SuperSpeed USB device number 3 using xhci_hcd Feb 21 20:53:38 volumio volumio[1274]: info: Output device has changed, restarting Shairport Sync Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:38 volumio kernel: usb 2-2: New USB device found, idVendor=0781, idProduct=5583, bcdDevice= 1.00 Feb 21 20:53:38 volumio kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 21 20:53:38 volumio kernel: usb 2-2: Product: Ultra Fit Feb 21 20:53:38 volumio kernel: usb 2-2: Manufacturer: SanDisk Feb 21 20:53:38 volumio kernel: usb 2-2: SerialNumber: 4C530001320113111013 Feb 21 20:53:38 volumio kernel: usb-storage 2-2:1.0: USB Mass Storage device detected Feb 21 20:53:38 volumio kernel: scsi host0: usb-storage 2-2:1.0 Feb 21 20:53:38 volumio sudo[8773]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 20:53:38 volumio sudo[8775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 20:53:38 volumio sudo[8775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio sudo[8773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio sudo[8732]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:38 volumio sudo[8708]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:38 volumio sudo[8771]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 20:53:38 volumio sudo[8771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 20:53:38 volumio volumio[1274]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:38 volumio volumio[1274]: info: Preparing to generate the ALSA configuration file Feb 21 20:53:38 volumio sudo[8761]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:38 volumio sudo[8790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:38 volumio sudo[8790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 21 20:53:38 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 21 20:53:38 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 21 20:53:38 volumio sudo[8791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:38 volumio sudo[8791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio sudo[8794]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:38 volumio sudo[8794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio volumio[1274]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 21 20:53:38 volumio volumio[1274]: info: Reading ALSA contributions from plugins. Feb 21 20:53:38 volumio volumio[1274]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Feb 21 20:53:38 volumio volumio[1274]: Job for shairport-sync.service failed because start of the service was attempted too often. Feb 21 20:53:38 volumio volumio[1274]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Feb 21 20:53:38 volumio volumio[1274]: To force a start use "systemctl reset-failed shairport-sync.service" Feb 21 20:53:38 volumio volumio[1274]: followed by "systemctl start shairport-sync.service" again. Feb 21 20:53:38 volumio sudo[8803]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:38 volumio sudo[8803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:38 volumio volumio[1274]: info: Executing endpoint qc_getconfig Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 21 20:53:38 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 20:53:38 volumio qobuz-connect[8756]: 20260221 20:53:38.940 [8756.8756] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 21 20:53:38 volumio qobuz-connect[8756]: 20260221 20:53:38.946 [8756.8756] INFO VolumeManager: [0x1e0c258]: Setting new playback volume: 75 Feb 21 20:53:38 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 21 20:53:38 volumio systemd[1]: shairport-sync.service: Start request repeated too quickly. Feb 21 20:53:38 volumio systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Feb 21 20:53:38 volumio systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:38 volumio volumio[1274]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 21 20:53:38 volumio volumio[1274]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 21 20:53:38 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 21 20:53:38 volumio volumio[1274]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 21 20:53:38 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:38 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:38 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 21 20:53:38 volumio sudo[8773]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:38 volumio volumio[1274]: info: Starting Shairport Sync Feb 21 20:53:38 volumio volumio[1274]: info: MPD Permissions set Feb 21 20:53:39 volumio volumio[1274]: info: Asound.conf file unchanged, so no further update is needed Feb 21 20:53:39 volumio volumio[1274]: info: Output device has changed, restarting MPD Feb 21 20:53:39 volumio sudo[8815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 20:53:39 volumio sudo[8815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:39 volumio volumio[1274]: info: Output device has changed, restarting Shairport Sync Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:39 volumio sudo[8748]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio sudo[8817]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 20:53:39 volumio sudo[8817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:39 volumio sudo[8754]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio sudo[8817]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio sudo[8808]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 20:53:39 volumio sudo[8808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 20:53:39 volumio sudo[8820]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 20:53:39 volumio sudo[8820]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:39 volumio sudo[8808]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 20:53:39 volumio volumio[1274]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:39 volumio volumio[1274]: info: Preparing to generate the ALSA configuration file Feb 21 20:53:39 volumio sudo[8834]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:39 volumio sudo[8834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:39 volumio sudo[8836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:39 volumio sudo[8836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:39 volumio sudo[8771]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio volumio[1274]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 21 20:53:39 volumio volumio[1274]: info: Reading ALSA contributions from plugins. Feb 21 20:53:39 volumio sudo[8803]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio sudo[8844]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:39 volumio sudo[8844]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:39 volumio volumio[1274]: info: MPD Permissions set Feb 21 20:53:39 volumio volumio[1274]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Feb 21 20:53:39 volumio volumio[1274]: Job for shairport-sync.service failed because start of the service was attempted too often. Feb 21 20:53:39 volumio volumio[1274]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Feb 21 20:53:39 volumio volumio[1274]: To force a start use "systemctl reset-failed shairport-sync.service" Feb 21 20:53:39 volumio volumio[1274]: followed by "systemctl start shairport-sync.service" again. Feb 21 20:53:39 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:39 volumio volumio[1274]: info: TidalConnect service stoped! Feb 21 20:53:39 volumio sudo[8845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:39 volumio sudo[8845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: Starting Shairport Sync Feb 21 20:53:39 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 21 20:53:39 volumio volumio[1274]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:39 volumio volumio[1274]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 21 20:53:39 volumio volumio[1274]: info: Asound.conf file unchanged, so no further update is needed Feb 21 20:53:39 volumio volumio[1274]: info: Output device has changed, restarting MPD Feb 21 20:53:39 volumio sudo[8791]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 20:53:39 volumio sudo[8750]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio sudo[8863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 20:53:39 volumio sudo[8863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:39 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 21 20:53:39 volumio volumio[1274]: info: Output device has changed, restarting Shairport Sync Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:39 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 21 20:53:39 volumio sudo[8866]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 21 20:53:39 volumio sudo[8866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:39 volumio sudo[8868]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 21 20:53:39 volumio sudo[8868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:39 volumio sudo[8866]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio sudo[8815]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio volumio[1274]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 21 20:53:39 volumio volumio[1274]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: QobuzConnect: setDeactiveState invoked Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::volumioGetState Feb 21 20:53:39 volumio sudo[8794]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio sudo[8790]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio sudo[8844]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio sudo[8894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:39 volumio sudo[8894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:39 volumio sudo[8902]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:39 volumio sudo[8902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:39 volumio sudo[8834]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio sudo[8898]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 21 20:53:39 volumio sudo[8898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:39 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 21 20:53:39 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 21 20:53:39 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 21 20:53:39 volumio volumio[1274]: info: MPD Permissions set Feb 21 20:53:39 volumio volumio[1274]: info: Shairport-Sync Started Feb 21 20:53:39 volumio volumio[1274]: (node:1274) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 21 20:53:39 volumio volumio[1274]: (Use `node --trace-warnings ...` to show where the warning was created) Feb 21 20:53:39 volumio volumio[1274]: (node:1274) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 21 20:53:39 volumio volumio[1274]: (node:1274) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 21 20:53:39 volumio volumio[1274]: (node:1274) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 21 20:53:39 volumio volumio[1274]: (node:1274) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 21 20:53:39 volumio sudo[8909]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 21 20:53:39 volumio sudo[8909]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:39 volumio volumio[1274]: info: Executing endpoint qc_getconfig Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 21 20:53:39 volumio sudo[8912]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 21 20:53:39 volumio sudo[8912]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:39 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 21 20:53:39 volumio qobuz-connect[8852]: 20260221 20:53:39.577 [8852.8852] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 21 20:53:39 volumio volumio[1274]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 21 20:53:39 volumio volumio[1274]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio qobuz-connect[8852]: 20260221 20:53:39.583 [8852.8852] INFO VolumeManager: [0x171a258]: Setting new playback volume: 75 Feb 21 20:53:39 volumio qobuz-connect[8852]: 20260221 20:53:39.583 [8852.8852] INFO VolumeManager: [0x171a258]: Setting new mute state: 0 Feb 21 20:53:39 volumio qobuz-connect[8852]: 20260221 20:53:39.583 [8852.8852] INFO QobuzConnect: [0x171ac28]: Client initialized! Feb 21 20:53:39 volumio qobuz-connect[8852]: 20260221 20:53:39.584 [8852.8852] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Feb 21 20:53:39 volumio qobuz-connect[8852]: 20260221 20:53:39.600 [8852.8852] INFO LocalConfigManager: [0x1719b38]: Starting Local Configuration server Feb 21 20:53:39 volumio qobuz-connect[8852]: 20260221 20:53:39.600 [8852.8852] INFO SampleApp: Starting Local configuration server Feb 21 20:53:39 volumio qobuz-connect[8852]: 20260221 20:53:39.600 [8852.8852] INFO SampleApp: Connected to UNIX socket client 0x1704818 Feb 21 20:53:39 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 21 20:53:39 volumio qobuz-connect[8852]: 20260221 20:53:39.605 [8852.8852] INFO SampleApp: Stopping Local configuration server Feb 21 20:53:39 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 21 20:53:39 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 21 20:53:39 volumio kernel: usb 2-2: USB disconnect, device number 3 Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 21 20:53:39 volumio volumio[1274]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 21 20:53:39 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 21 20:53:39 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 20:53:39 volumio volumio[1274]: info: TidalConnect service started! Feb 21 20:53:39 volumio volumio[1274]: info: Starting Shairport Sync Feb 21 20:53:39 volumio sudo[8926]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 21 20:53:39 volumio sudo[8926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:39 volumio sudo[8836]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio sudo[8917]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 21 20:53:39 volumio sudo[8917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 21 20:53:39 volumio sudo[8917]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio volumio[1274]: info: TidalConnect service started! Feb 21 20:53:39 volumio volumio[1274]: info: TidalConnect service started! Feb 21 20:53:39 volumio sudo[8909]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio sudo[8894]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio volumio[1274]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 20:53:39 volumio sudo[8931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 21 20:53:39 volumio sudo[8931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 20:53:39 volumio sudo[8898]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio volumio[1274]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Feb 21 20:53:39 volumio volumio[1274]: Job for vtcs.service canceled. Feb 21 20:53:39 volumio volumio[1274]: at ChildProcess.exithandler (node:child_process:421:12) Feb 21 20:53:39 volumio volumio[1274]: at ChildProcess.emit (node:events:514:28) Feb 21 20:53:39 volumio volumio[1274]: at maybeClose (node:internal/child_process:1105:16) Feb 21 20:53:39 volumio volumio[1274]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) { Feb 21 20:53:39 volumio volumio[1274]: code: 1, Feb 21 20:53:39 volumio volumio[1274]: killed: false, Feb 21 20:53:39 volumio volumio[1274]: signal: null, Feb 21 20:53:39 volumio volumio[1274]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Feb 21 20:53:39 volumio volumio[1274]: stdout: '', Feb 21 20:53:39 volumio volumio[1274]: stderr: 'Job for vtcs.service canceled.\n' Feb 21 20:53:39 volumio volumio[1274]: } Feb 21 20:53:39 volumio volumio[1274]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 20:53:39 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 21 20:53:39 volumio sudo[8863]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 21 20:53:39 volumio sudo[8912]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:39 volumio sudo[8926]: pam_unix(sudo:session): session closed for user root Feb 21 20:53:40 volumio sudo[8965]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-21 20:52' Feb 21 20:53:40 volumio sudo[8965]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"