Aug 25 08:52:00 gateway ntpd[1021]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101
Aug 25 08:52:00 gateway ntpd[1021]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101
Aug 25 08:52:00 gateway ntpd[1021]: DNS: dns_check: DNS error: -11, System error
Aug 25 08:52:00 gateway ntpd[1021]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12
Aug 25 08:52:00 gateway wpa_supplicant[1179]: wlan0: SME: Trying to authenticate with 78:24:af:7b:18:40 (SSID='zwicker' freq=2452 MHz)
Aug 25 08:52:00 gateway kernel: wlan0: authenticate with 78:24:af:7b:18:40 (local address=4c:0f:6e:10:91:09)
Aug 25 08:52:00 gateway kernel: wlan0: send auth to 78:24:af:7b:18:40 (try 1/3)
Aug 25 08:52:00 gateway wpa_supplicant[1179]: wlan0: Trying to associate with 78:24:af:7b:18:40 (SSID='zwicker' freq=2452 MHz)
Aug 25 08:52:00 gateway kernel: wlan0: authenticated
Aug 25 08:52:00 gateway kernel: wlan0: associate with 78:24:af:7b:18:40 (try 1/3)
Aug 25 08:52:00 gateway kernel: wlan0: RX AssocResp from 78:24:af:7b:18:40 (capab=0x1011 status=0 aid=12)
Aug 25 08:52:00 gateway kernel: wlan0: associated
Aug 25 08:52:00 gateway wpa_supplicant[1179]: wlan0: Associated with 78:24:af:7b:18:40
Aug 25 08:52:00 gateway wpa_supplicant[1179]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Aug 25 08:52:00 gateway kernel: cryptd: max_cpu_qlen set to 1000
Aug 25 08:52:00 gateway wpa_supplicant[1179]: wlan0: WPA: Key negotiation completed with 78:24:af:7b:18:40 [PTK=CCMP GTK=CCMP]
Aug 25 08:52:00 gateway wpa_supplicant[1179]: wlan0: CTRL-EVENT-CONNECTED - Connection to 78:24:af:7b:18:40 completed [id=1 id_str=]
Aug 25 08:52:00 gateway dhcpcd[834]: wlan0: carrier acquired
Aug 25 08:52:00 gateway dhcpcd[834]: wlan0: connected to Access Point: zwicker
Aug 25 08:52:00 gateway dhcpcd[834]: wlan0: IAID 6e:10:91:09
Aug 25 08:52:00 gateway kernel: wlan0: deauthenticating from 78:24:af:7b:18:40 by local choice (Reason: 3=DEAUTH_LEAVING)
Aug 25 08:52:00 gateway wpa_supplicant[1179]: wlan0: CTRL-EVENT-DISCONNECTED bssid=78:24:af:7b:18:40 reason=3 locally_generated=1
Aug 25 08:52:00 gateway dhcpcd[834]: wlan0: carrier lost
Aug 25 08:52:00 gateway wpa_supplicant[1179]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all
Aug 25 08:52:00 gateway wireless.js[809]: WIRELESS.JS - INFO: DHCP IP fallback
Aug 25 08:52:00 gateway wireless.js[809]: WIRELESS.JS - INFO: STAGE 2: Starting event-driven WPA state monitor
Aug 25 08:52:00 gateway wireless.js[809]: WIRELESS.JS - INFO: WpaStateMachine: Starting state monitor for wlan0
Aug 25 08:52:00 gateway wpa_supplicant[1179]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Aug 25 08:52:00 gateway wpa_supplicant[1179]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CA
Aug 25 08:52:01 gateway wireless.js[809]: WIRELESS.JS - INFO: WpaStateMachine: State transition: NULL -> SCANNING (duration: 0ms)
Aug 25 08:52:01 gateway ntpd[1021]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101
Aug 25 08:52:01 gateway ntpd[1021]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101
Aug 25 08:52:01 gateway ntpd[1021]: DNS: dns_check: DNS error: -11, System error
Aug 25 08:52:01 gateway ntpd[1021]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12
Aug 25 08:52:01 gateway systemd[1]: systemd-rfkill.service: Deactivated successfully.
Aug 25 08:52:01 gateway wpa_supplicant[1179]: wlan0: SME: Trying to authenticate with 78:24:af:7b:18:40 (SSID='zwicker' freq=2452 MHz)
Aug 25 08:52:01 gateway kernel: wlan0: authenticate with 78:24:af:7b:18:40 (local address=4c:0f:6e:10:91:09)
Aug 25 08:52:01 gateway kernel: wlan0: send auth to 78:24:af:7b:18:40 (try 1/3)
Aug 25 08:52:01 gateway wpa_supplicant[1179]: wlan0: Trying to associate with 78:24:af:7b:18:40 (SSID='zwicker' freq=2452 MHz)
Aug 25 08:52:01 gateway kernel: wlan0: authenticated
Aug 25 08:52:01 gateway kernel: wlan0: associate with 78:24:af:7b:18:40 (try 1/3)
Aug 25 08:52:01 gateway kernel: wlan0: RX AssocResp from 78:24:af:7b:18:40 (capab=0x1011 status=0 aid=12)
Aug 25 08:52:01 gateway wpa_supplicant[1179]: wlan0: Associated with 78:24:af:7b:18:40
Aug 25 08:52:01 gateway wpa_supplicant[1179]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Aug 25 08:52:01 gateway kernel: wlan0: associated
Aug 25 08:52:01 gateway wpa_supplicant[1179]: wlan0: WPA: Key negotiation completed with 78:24:af:7b:18:40 [PTK=CCMP GTK=CCMP]
Aug 25 08:52:01 gateway wpa_supplicant[1179]: wlan0: CTRL-EVENT-CONNECTED - Connection to 78:24:af:7b:18:40 completed [id=1 id_str=]
Aug 25 08:52:01 gateway dhcpcd[834]: wlan0: carrier acquired
Aug 25 08:52:01 gateway dhcpcd[834]: wlan0: IAID 6e:10:91:09
Aug 25 08:52:01 gateway dhcpcd[834]: wlan0: soliciting a DHCP lease
Aug 25 08:52:01 gateway dhcpcd[834]: wlan0: offered 192.168.1.44 from 192.168.1.1
Aug 25 08:52:01 gateway dhcpcd[834]: wlan0: probing address 192.168.1.44/24
Aug 25 08:52:01 gateway dhcpcd[834]: wlan0: soliciting an IPv6 router
Aug 25 08:52:01 gateway wireless.js[809]: WIRELESS.JS - INFO: WpaStateMachine: State transition: SCANNING -> COMPLETED (duration: 506ms)
Aug 25 08:52:01 gateway wireless.js[809]: WIRELESS.JS - INFO: WpaStateMachine: COMPLETED - connection successful
Aug 25 08:52:01 gateway wireless.js[809]: WIRELESS.JS - INFO: STAGE 2: Connection successful - Connected to 78:24:af:7b:18:40
Aug 25 08:52:01 gateway wireless.js[809]: WIRELESS.JS - INFO: Onboard WiFi adapter detected, using standard dhcpcd flow
Aug 25 08:52:01 gateway volumio-remote-updater[808]: [2026-08-25 08:52:01] [info] asio async_connect error: asio.system:111 (Connection refused)
Aug 25 08:52:01 gateway volumio-remote-updater[808]: [2026-08-25 08:52:01] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected)
Aug 25 08:52:01 gateway volumio-remote-updater[808]: [2026-08-25 08:52:01] [error] handle_connect error: Connection refused
Aug 25 08:52:02 gateway sudo[1251]:     root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd wlan0
Aug 25 08:52:02 gateway sudo[1251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 25 08:52:02 gateway dhcpcd[834]: control command: /sbin/dhcpcd wlan0
Aug 25 08:52:02 gateway sudo[1251]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:02 gateway dhcpcd[834]: control_free: No such file or directory
Aug 25 08:52:03 gateway bash[1015]: setdatetime-helper: all HTTPS Date fallbacks failed
Aug 25 08:52:03 gateway systemd[1]: setdatetime-helper.service: Deactivated successfully.
Aug 25 08:52:03 gateway systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Aug 25 08:52:04 gateway wireless.js[809]: WIRELESS.JS - INFO: Start ap
Aug 25 08:52:04 gateway wireless.js[809]: WIRELESS.JS - INFO: Notified systemd about wireless ready
Aug 25 08:52:04 gateway iwconfig[1260]: Error for wireless request "Set Power Management" (8B2C) :
Aug 25 08:52:04 gateway iwconfig[1260]:     SET failed on device wlan0 ; Operation not supported.
Aug 25 08:52:04 gateway systemd[1]: Started wireless.service - Wireless Services.
Aug 25 08:52:04 gateway systemd[1]: Started volumio.service - Volumio Backend Module.
Aug 25 08:52:04 gateway systemd[1]: Started screenshot.service - Process screenshots triggered by PrtSc-button.
Aug 25 08:52:04 gateway systemd[1]: Started soundcard-init.service - Intel SST and HDA soundcard init service.
Aug 25 08:52:04 gateway systemd[1]: Started volumio-kiosk.service - Start Volumio Kiosk.
Aug 25 08:52:04 gateway systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker.
Aug 25 08:52:04 gateway volumio-cpu-tweak[1275]: Setting RT Priority for mpd
Aug 25 08:52:04 gateway volumio-cpu-tweak[1297]: pid 35's current scheduling policy: SCHED_OTHER
Aug 25 08:52:04 gateway volumio-cpu-tweak[1297]: pid 35's current scheduling priority: 0
Aug 25 08:52:04 gateway volumio-cpu-tweak[1275]: Setting MPD Affinity
Aug 25 08:52:04 gateway volumio-cpu-tweak[1302]: pid 3's current affinity mask: f
Aug 25 08:52:04 gateway volumio-cpu-tweak[1275]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance
Aug 25 08:52:04 gateway systemd[1]: volumio_cpu_tweak.service: Deactivated successfully.
Aug 25 08:52:04 gateway soundcard-init.sh[1339]: Card 0 Chip Conexant Generic Name HDA Intel MID
Aug 25 08:52:04 gateway startx[1329]: X.Org X Server 1.21.1.7
Aug 25 08:52:04 gateway startx[1329]: X Protocol Version 11, Revision 0
Aug 25 08:52:04 gateway startx[1329]: Current Operating System: Linux gateway 6.12.57-volumio #2 SMP PREEMPT_DYNAMIC Thu Nov 13 17:08:37 CET 2025 x86_64
Aug 25 08:52:04 gateway startx[1329]: Kernel command line: BOOT_IMAGE=vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 bootdelay=5 biosdevname=0 imgpart=UUID=67e6ea3f-c9d1-4a34-aab0-ec70b7ccf4bd bootpart=UUID=CD77-6AA2 datapart=UUID=c3ac123b-91a3-48c7-b432-71879249345d hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=native net.ifnames=0 use_kmsg=no initrd=volumio.initrd
Aug 25 08:52:04 gateway startx[1329]: xorg-server 2:21.1.7-3+deb12u11 (https://www.debian.org/support)
Aug 25 08:52:04 gateway startx[1329]: Current version of pixman: 0.42.2
Aug 25 08:52:04 gateway startx[1329]:         Before reporting problems, check http://wiki.x.org
Aug 25 08:52:04 gateway startx[1329]:         to make sure that you have the latest version.
Aug 25 08:52:04 gateway startx[1329]: Markers: (--) probed, (**) from config file, (==) default setting,
Aug 25 08:52:04 gateway startx[1329]:         (++) from command line, (!!) notice, (II) informational,
Aug 25 08:52:04 gateway startx[1329]:         (WW) warning, (EE) error, (NI) not implemented, (??) unknown.
Aug 25 08:52:04 gateway startx[1329]: (==) Log file: "/var/log/Xorg.0.log", Time: Tue Aug 25 08:52:04 2026
Aug 25 08:52:04 gateway startx[1329]: (==) Using system config directory "/usr/share/X11/xorg.conf.d"
Aug 25 08:52:04 gateway soundcard-init.sh[1339]: Card 5 Chip USB Mixer Name USB Audio DAC
Aug 25 08:52:04 gateway systemd[1]: soundcard-init.service: Deactivated successfully.
Aug 25 08:52:05 gateway wireless.js[809]: WIRELESS.JS - INFO: trying...
Aug 25 08:52:05 gateway sudo[1371]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Aug 25 08:52:05 gateway sudo[1371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:05 gateway sudo[1371]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:05 gateway sudo[1374]:     root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 25 08:52:05 gateway sudo[1374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 25 08:52:05 gateway sudo[1374]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:05 gateway wireless.js[809]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined
Aug 25 08:52:06 gateway volumio[1261]: info: -------------------------------------------
Aug 25 08:52:06 gateway volumio[1261]: info: -----            Volumio3              ----
Aug 25 08:52:06 gateway volumio[1261]: info: -------------------------------------------
Aug 25 08:52:06 gateway volumio[1261]: info: -----          System startup          ----
Aug 25 08:52:06 gateway volumio[1261]: info: -------------------------------------------
Aug 25 08:52:06 gateway volumio[1261]: info: MYVOLUMIO Environment detected
Aug 25 08:52:06 gateway volumio[1261]: info: Plugin folders cleanup
Aug 25 08:52:06 gateway volumio[1261]: info: Scanning into folder /volumio/app/plugins/
Aug 25 08:52:06 gateway volumio[1261]: info: Scanning category audio_interface
Aug 25 08:52:06 gateway volumio[1261]: info: Scanning category miscellanea
Aug 25 08:52:06 gateway volumio[1261]: info: Scanning category music_service
Aug 25 08:52:06 gateway volumio[1261]: info: Scanning category plugins.json
Aug 25 08:52:06 gateway volumio[1261]: info: Scanning category system_controller
Aug 25 08:52:06 gateway volumio[1261]: info: Scanning category user_interface
Aug 25 08:52:06 gateway volumio[1261]: info: Scanning into folder /data/plugins/
Aug 25 08:52:06 gateway volumio[1261]: info: Scanning category audio_interface
Aug 25 08:52:06 gateway volumio[1261]: info: Scanning category music_service
Aug 25 08:52:06 gateway volumio[1261]: info: Plugin folders cleanup completed
Aug 25 08:52:06 gateway volumio[1261]: info: -------------------------------------------
Aug 25 08:52:06 gateway volumio[1261]: info: -----      Core plugins startup        ----
Aug 25 08:52:06 gateway volumio[1261]: info: -------------------------------------------
Aug 25 08:52:06 gateway volumio[1261]: info: Loading plugins from folder /volumio/app/plugins/
Aug 25 08:52:06 gateway volumio[1261]: info: Adding plugin upnp to MyMusic Plugins
Aug 25 08:52:06 gateway volumio[1261]: info: Adding plugin airplay_emulation to MyMusic Plugins
Aug 25 08:52:06 gateway volumio[1261]: info: Adding plugin upnp_browser to MyMusic Plugins
Aug 25 08:52:06 gateway volumio[1261]: info: Loading plugins from folder /data/plugins/
Aug 25 08:52:06 gateway volumio[1261]: info: Loading plugin "system"...
Aug 25 08:52:06 gateway volumio[1261]: info: Loading plugin "appearance"...
Aug 25 08:52:06 gateway wireless.js[809]: WIRELESS.JS - INFO: trying...
Aug 25 08:52:06 gateway sudo[1421]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Aug 25 08:52:06 gateway sudo[1421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:06 gateway sudo[1421]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:06 gateway sudo[1424]:     root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 25 08:52:06 gateway sudo[1424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 25 08:52:06 gateway sudo[1424]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:06 gateway wireless.js[809]: WIRELESS.JS - INFO: ... wlan0 IPv4 is undefined, ipV6 is undefined
Aug 25 08:52:06 gateway dhcpcd[834]: wlan0: leased 192.168.1.44 for 86400 seconds
Aug 25 08:52:06 gateway avahi-daemon[800]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.44.
Aug 25 08:52:06 gateway dhcpcd[834]: wlan0: adding route to 192.168.1.0/24
Aug 25 08:52:06 gateway avahi-daemon[800]: New relevant interface wlan0.IPv4 for mDNS.
Aug 25 08:52:06 gateway avahi-daemon[800]: Registering new address record for 192.168.1.44 on wlan0.IPv4.
Aug 25 08:52:06 gateway dhcpcd[834]: wlan0: adding default route via 192.168.1.1
Aug 25 08:52:07 gateway volumio[1261]: info: Loading plugin "network"...
Aug 25 08:52:07 gateway volumio[1261]: info: Refreshing Cached IP Addresses
Aug 25 08:52:07 gateway sudo[1495]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 25 08:52:07 gateway sudo[1495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:07 gateway sudo[1497]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 25 08:52:07 gateway sudo[1497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:07 gateway sudo[1495]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:07 gateway sudo[1497]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:07 gateway volumio[1261]: info: Loading plugin "services"...
Aug 25 08:52:07 gateway volumio[1261]: info: Loading plugin "volumio5onboarding"...
Aug 25 08:52:07 gateway sudo[1505]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Aug 25 08:52:07 gateway sudo[1505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:07 gateway volumio[1261]: info: Loading plugin "alsa_controller"...
Aug 25 08:52:07 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 25 08:52:07 gateway volumio[1261]: info: Loading plugin "wizard"...
Aug 25 08:52:07 gateway volumio[1261]: info: Loading plugin "networkfs"...
Aug 25 08:52:07 gateway systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems...
Aug 25 08:52:07 gateway systemd[1]: Started nmbd.service - Samba NMB Daemon.
Aug 25 08:52:07 gateway systemd[1]: welcome.service: Deactivated successfully.
Aug 25 08:52:07 gateway systemd[1]: Stopped welcome.service - Show a welcome message on console.
Aug 25 08:52:07 gateway systemd[1]: Stopping welcome.service - Show a welcome message on console...
Aug 25 08:52:07 gateway systemd[1]: Starting welcome.service - Show a welcome message on console...
Aug 25 08:52:07 gateway welcome[1542]: Resolved ip:[1] 192.168.1.44
Aug 25 08:52:07 gateway systemd[1]: Starting winbind.service - Samba Winbind Daemon...
Aug 25 08:52:07 gateway systemd[1]: e2scrub_all.service: Deactivated successfully.
Aug 25 08:52:07 gateway systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems.
Aug 25 08:52:07 gateway systemd[1]: Finished welcome.service - Show a welcome message on console.
Aug 25 08:52:07 gateway systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0.
Aug 25 08:52:07 gateway volumio[1261]: info: Starting Udev Watcher for removable devices
Aug 25 08:52:07 gateway volumio[1261]: info: Ignoring mount for partition: boot
Aug 25 08:52:07 gateway volumio[1261]: info: Ignoring mount for partition: volumio
Aug 25 08:52:07 gateway volumio[1261]: info: Ignoring mount for partition: volumio_data
Aug 25 08:52:07 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Aug 25 08:52:07 gateway volumio[1261]: info: Loading plugin "volumio_command_line_client"...
Aug 25 08:52:07 gateway volumio[1261]: info: Loading plugin "upnp"...
Aug 25 08:52:07 gateway volumio[1261]: info: [1787662327647] Starting Upmpd Daemon
Aug 25 08:52:07 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Aug 25 08:52:07 gateway volumio[1261]: info: Loading plugin "my_music"...
Aug 25 08:52:07 gateway volumio[1261]: info: Loading plugin "mpd"...
Aug 25 08:52:07 gateway wireless.js[809]: WIRELESS.JS - INFO: trying...
Aug 25 08:52:07 gateway sudo[1568]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Aug 25 08:52:07 gateway sudo[1568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:07 gateway sudo[1568]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:07 gateway sudo[1573]:     root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 25 08:52:07 gateway sudo[1573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 25 08:52:07 gateway sudo[1573]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:07 gateway wireless.js[809]: WIRELESS.JS - INFO: ... wlan0 IPv4 is 192.168.1.44, ipV6 is undefined
Aug 25 08:52:07 gateway wireless.js[809]: WIRELESS.JS - INFO: Connected to SSID: zwicker
Aug 25 08:52:07 gateway wireless.js[809]: WIRELESS.JS - INFO: It's done! AP
Aug 25 08:52:07 gateway wireless.js[809]: WIRELESS.JS - INFO: Restarting avahi-daemon...
Aug 25 08:52:07 gateway sudo[1579]:     root : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart avahi-daemon
Aug 25 08:52:07 gateway sudo[1579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 25 08:52:07 gateway winbindd[1553]: [2026/08/25 08:52:07.846846,  0] ../../source3/winbindd/winbindd.c:1440(main)
Aug 25 08:52:07 gateway winbindd[1553]:   winbindd version 4.17.12-Debian started.
Aug 25 08:52:07 gateway winbindd[1553]:   Copyright Andrew Tridgell and the Samba Team 1992-2022
Aug 25 08:52:07 gateway systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 25 08:52:07 gateway systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 25 08:52:07 gateway systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 08:52:07 gateway systemd[1]: shairport-sync.service: Consumed 1.773s CPU time.
Aug 25 08:52:07 gateway winbindd[1553]: [2026/08/25 08:52:07.864802,  0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache)
Aug 25 08:52:07 gateway winbindd[1553]:   initialize_winbindd_cache: clearing cache and re-creating with version number 2
Aug 25 08:52:07 gateway systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack...
Aug 25 08:52:07 gateway avahi-daemon[800]: Got SIGTERM, quitting.
Aug 25 08:52:07 gateway avahi-daemon[800]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.44.
Aug 25 08:52:07 gateway avahi-daemon[800]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1.
Aug 25 08:52:07 gateway systemd[1]: Started winbind.service - Samba Winbind Daemon.
Aug 25 08:52:07 gateway avahi-daemon[800]: avahi-daemon 0.8 exiting.
Aug 25 08:52:07 gateway systemd[1]: Starting smbd.service - Samba SMB Daemon...
Aug 25 08:52:07 gateway systemd[1]: avahi-daemon.service: Deactivated successfully.
Aug 25 08:52:07 gateway systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack.
Aug 25 08:52:07 gateway systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack...
Aug 25 08:52:07 gateway avahi-daemon[1586]: Process 800 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid)
Aug 25 08:52:07 gateway avahi-daemon[1586]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109).
Aug 25 08:52:07 gateway avahi-daemon[1586]: Successfully dropped root privileges.
Aug 25 08:52:07 gateway avahi-daemon[1586]: avahi-daemon 0.8 starting up.
Aug 25 08:52:07 gateway systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack.
Aug 25 08:52:07 gateway sudo[1579]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:07 gateway avahi-daemon[1586]: Successfully called chroot().
Aug 25 08:52:07 gateway avahi-daemon[1586]: Successfully dropped remaining capabilities.
Aug 25 08:52:07 gateway avahi-daemon[1586]: Loading service file /services/volumio.service.
Aug 25 08:52:07 gateway avahi-daemon[1586]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.44.
Aug 25 08:52:07 gateway avahi-daemon[1586]: New relevant interface wlan0.IPv4 for mDNS.
Aug 25 08:52:07 gateway avahi-daemon[1586]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1.
Aug 25 08:52:07 gateway avahi-daemon[1586]: New relevant interface lo.IPv4 for mDNS.
Aug 25 08:52:07 gateway avahi-daemon[1586]: Network interface enumeration completed.
Aug 25 08:52:07 gateway avahi-daemon[1586]: Registering new address record for 192.168.1.44 on wlan0.IPv4.
Aug 25 08:52:07 gateway avahi-daemon[1586]: Registering new address record for 127.0.0.1 on lo.IPv4.
Aug 25 08:52:07 gateway systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 08:52:07 gateway wireless.js[809]: WIRELESS.JS - INFO: Notified systemd about wireless ready
Aug 25 08:52:08 gateway volumio[1261]: info: Loading plugin "upnp_browser"...
Aug 25 08:52:08 gateway smbd[1605]: [2026/08/25 08:52:08.174013,  0] ../../source3/smbd/server.c:1741(main)
Aug 25 08:52:08 gateway smbd[1605]:   smbd version 4.17.12-Debian started.
Aug 25 08:52:08 gateway smbd[1605]:   Copyright Andrew Tridgell and the Samba Team 1992-2022
Aug 25 08:52:08 gateway ntpd[1021]: IO: Listen normally on 3 wlan0 192.168.1.44:123
Aug 25 08:52:08 gateway ntpd[1021]: IO: new interface(s) found: waking up resolver
Aug 25 08:52:08 gateway ntpd[1021]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101
Aug 25 08:52:08 gateway ntpd[1021]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101
Aug 25 08:52:08 gateway ntpd[1021]: DNS: Pool taking: 216.232.132.18
Aug 25 08:52:08 gateway ntpd[1021]: DNS: Pool taking: 162.159.200.123
Aug 25 08:52:08 gateway ntpd[1021]: DNS: Pool taking: 209.227.132.121
Aug 25 08:52:08 gateway ntpd[1021]: DNS: Pool taking: 147.189.136.126
Aug 25 08:52:08 gateway ntpd[1021]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8
Aug 25 08:52:08 gateway systemd[1]: Started smbd.service - Samba SMB Daemon.
Aug 25 08:52:08 gateway systemd[1]: Reached target multi-user.target - Multi-User System.
Aug 25 08:52:08 gateway systemd[1]: Reached target graphical.target - Graphical Interface.
Aug 25 08:52:08 gateway systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP...
Aug 25 08:52:08 gateway systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully.
Aug 25 08:52:08 gateway systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP.
Aug 25 08:52:08 gateway systemd[1]: Startup finished in 28.890s (kernel) + 13.849s (userspace) = 42.740s.
Aug 25 08:52:08 gateway avahi-daemon[1586]: Server startup complete. Host name is gateway.local. Local service cookie is 1579013714.
Aug 25 08:52:09 gateway volumio[1261]: info: Starting UPNP Browser
Aug 25 08:52:09 gateway volumio[1261]: info: Loading plugin "alarm-clock"...
Aug 25 08:52:09 gateway volumio[1261]: info: Loading plugin "airplay_emulation"...
Aug 25 08:52:09 gateway volumio[1261]: info: Starting Shairport Sync
Aug 25 08:52:09 gateway volumio[1261]: info: Loading plugin "last_100"...
Aug 25 08:52:09 gateway volumio[1261]: info: Loading plugin "webradio"...
Aug 25 08:52:09 gateway volumio[1261]: info: Loading plugin "i2s_dacs"...
Aug 25 08:52:09 gateway volumio[1261]: info: I2S DAC not set, start Auto-detection
Aug 25 08:52:09 gateway volumio[1261]: info: Loading plugin "volumiodiscovery"...
Aug 25 08:52:09 gateway ntpd[1021]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101
Aug 25 08:52:09 gateway volumio[1261]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Aug 25 08:52:09 gateway volumio[1261]: *** WARNING *** Please fix your application to use the native API of Avahi!
Aug 25 08:52:09 gateway volumio[1261]: *** WARNING *** For more information see 
Aug 25 08:52:09 gateway volumio[1261]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Aug 25 08:52:09 gateway volumio[1261]: *** WARNING *** Please fix your application to use the native API of Avahi!
Aug 25 08:52:09 gateway volumio[1261]: *** WARNING *** For more information see 
Aug 25 08:52:09 gateway node[1261]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Aug 25 08:52:09 gateway node[1261]: *** WARNING *** Please fix your application to use the native API of Avahi!
Aug 25 08:52:09 gateway node[1261]: *** WARNING *** For more information see 
Aug 25 08:52:09 gateway node[1261]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Aug 25 08:52:09 gateway node[1261]: *** WARNING *** Please fix your application to use the native API of Avahi!
Aug 25 08:52:09 gateway node[1261]: *** WARNING *** For more information see 
Aug 25 08:52:09 gateway volumio[1261]: info: Applying required configuration parameters for plugin volumiodiscovery
Aug 25 08:52:09 gateway volumio[1261]: info: Discovery: Started advertising with name: gateway
Aug 25 08:52:09 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Aug 25 08:52:09 gateway volumio[1261]: info: Loading plugin "spotify-connect"...
Aug 25 08:52:09 gateway sudo[1505]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:09 gateway volumio-remote-updater[808]: [2026-08-25 08:52:09] [connect] Successful connection
Aug 25 08:52:09 gateway volumio[1261]: info: Loading plugin "outputs"...
Aug 25 08:52:09 gateway volumio[1261]: info: Loading plugin "albumart"...
Aug 25 08:52:09 gateway volumio[1261]: info: Plugin example_plugin is not enabled
Aug 25 08:52:09 gateway volumio[1261]: info: Loading plugin "inputs"...
Aug 25 08:52:09 gateway volumio[1261]: info: Loading plugin "updater_comm"...
Aug 25 08:52:09 gateway ntpd[1021]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101
Aug 25 08:52:09 gateway ntpd[1021]: DNS: Pool taking: 54.39.23.64
Aug 25 08:52:09 gateway ntpd[1021]: DNS: Pool taking: 216.128.178.20
Aug 25 08:52:09 gateway ntpd[1021]: DNS: Pool taking: 162.159.200.1
Aug 25 08:52:09 gateway ntpd[1021]: DNS: Pool taking: 173.206.39.180
Aug 25 08:52:09 gateway ntpd[1021]: DNS: Pool taking: 2607:5300:201:3100::345c
Aug 25 08:52:09 gateway ntpd[1021]: DNS: Pool taking: 2602:fd50:100:108:3491:d3b2:eef8:f324
Aug 25 08:52:09 gateway ntpd[1021]: DNS: Pool taking: 2605:6f08:2000:6::7326:252a
Aug 25 08:52:09 gateway ntpd[1021]: DNS: Pool taking: 2a11:840:61:1b::b4d2:ff02
Aug 25 08:52:09 gateway ntpd[1021]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8
Aug 25 08:52:09 gateway volumio[1647]: Forking 3 albumart workers
Aug 25 08:52:09 gateway volumio[1261]: info: Plugin mpdemulation is not enabled
Aug 25 08:52:09 gateway volumio[1261]: info: Loading plugin "rest_api"...
Aug 25 08:52:09 gateway volumio[1261]: info: Loading plugin "websocket"...
Aug 25 08:52:09 gateway volumio[1261]: info: Starting Socket.io Server version 1.7.4
Aug 25 08:52:09 gateway volumio[1261]: info: Loading plugin "fusiondsp"...
Aug 25 08:52:09 gateway dbus-daemon[801]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.11' (uid=0 pid=1455 comm="/usr/lib/chromium/chromium --show-component-extens")
Aug 25 08:52:09 gateway avahi-daemon[1586]: Service "gateway" (/services/volumio.service) successfully established.
Aug 25 08:52:09 gateway systemd[1]: Starting bluetooth.service - Bluetooth service...
Aug 25 08:52:09 gateway volumio[1261]: info: Applying required configuration parameters for plugin fusiondsp
Aug 25 08:52:09 gateway volumio[1657]: Starting albumart workers
Aug 25 08:52:09 gateway volumio[1659]: Starting albumart workers
Aug 25 08:52:09 gateway volumio[1658]: Starting albumart workers
Aug 25 08:52:10 gateway volumio[1261]: info: Loading plugin "radio_paradise"...
Aug 25 08:52:10 gateway kernel: Bluetooth: Core ver 2.22
Aug 25 08:52:10 gateway kernel: NET: Registered PF_BLUETOOTH protocol family
Aug 25 08:52:10 gateway kernel: Bluetooth: HCI device and connection manager initialized
Aug 25 08:52:10 gateway kernel: Bluetooth: HCI socket layer initialized
Aug 25 08:52:10 gateway kernel: Bluetooth: L2CAP socket layer initialized
Aug 25 08:52:10 gateway kernel: Bluetooth: SCO socket layer initialized
Aug 25 08:52:10 gateway bluetoothd[1692]: Bluetooth daemon 5.83
Aug 25 08:52:10 gateway dbus-daemon[801]: [system] Successfully activated service 'org.bluez'
Aug 25 08:52:10 gateway systemd[1]: Started bluetooth.service - Bluetooth service.
Aug 25 08:52:10 gateway bluetoothd[1692]: Starting SDP server
Aug 25 08:52:10 gateway bluetoothd[1692]: Excluding (cli) sap
Aug 25 08:52:10 gateway bluetoothd[1692]: Excluding (cli) scanparam
Aug 25 08:52:10 gateway bluetoothd[1692]: Excluding (cli) midi
Aug 25 08:52:10 gateway bluetoothd[1692]: Excluding (cli) battery
Aug 25 08:52:10 gateway kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Aug 25 08:52:10 gateway kernel: Bluetooth: BNEP filters: protocol multicast
Aug 25 08:52:10 gateway kernel: Bluetooth: BNEP socket layer initialized
Aug 25 08:52:10 gateway bluetoothd[1692]: Bluetooth management interface 1.23 initialized
Aug 25 08:52:10 gateway dbus-daemon[801]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.14' (uid=0 pid=1692 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b")
Aug 25 08:52:10 gateway ntpd[1021]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101
Aug 25 08:52:10 gateway ntpd[1021]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101
Aug 25 08:52:10 gateway ntpd[1021]: DNS: Pool taking: 23.159.16.194
Aug 25 08:52:10 gateway ntpd[1021]: DNS: Pool taking: 142.4.192.253
Aug 25 08:52:10 gateway ntpd[1021]: DNS: Pool taking: 208.81.1.244
Aug 25 08:52:10 gateway ntpd[1021]: DNS: Pool taking: 216.232.132.19
Aug 25 08:52:10 gateway ntpd[1021]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8
Aug 25 08:52:10 gateway systemd[1]: Starting systemd-hostnamed.service - Hostname Service...
Aug 25 08:52:10 gateway volumio[1261]: info: Applying required configuration parameters for plugin radio_paradise
Aug 25 08:52:10 gateway volumio[1261]: info: [1787662330341] [RadioParadise] API delay: 10
Aug 25 08:52:10 gateway volumio[1261]: info: Loading i18n strings for locale en
Aug 25 08:52:10 gateway volumio[1261]: Updating browse sources language
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 25 08:52:10 gateway dbus-daemon[801]: [system] Successfully activated service 'org.freedesktop.hostname1'
Aug 25 08:52:10 gateway systemd[1]: Started systemd-hostnamed.service - Hostname Service.
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::initPlayerControls
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 25 08:52:10 gateway volumio[1261]: Express server listening on port 3000
Aug 25 08:52:10 gateway volumio[1261]:  [Metrics]  WebUI:  4s 966.73ms 
Aug 25 08:52:10 gateway volumio[1261]: info: Setting Device type: x86
Aug 25 08:52:10 gateway volumio[1261]: info: CoreStateMachine::resetVolumioState
Aug 25 08:52:10 gateway volumio[1261]: info: CoreStateMachine::getcurrentVolume
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::volumioRetrievevolume
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:10 gateway volumio[1261]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
Aug 25 08:52:10 gateway volumio[1261]: info: Completed loading Core Plugins
Aug 25 08:52:10 gateway volumio[1261]: info: Preparing to generate the ALSA configuration file
Aug 25 08:52:10 gateway volumio[1261]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Aug 25 08:52:10 gateway volumio[1261]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Aug 25 08:52:10 gateway volumio[1261]: info: Reading ALSA contributions from plugins.
Aug 25 08:52:10 gateway volumio[1261]: info: Volumio Network Manager: Network status updated: 2
Aug 25 08:52:10 gateway volumio[1261]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision
Aug 25 08:52:10 gateway volumio-remote-updater[808]: [2026-08-25 08:52:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787662329 101
Aug 25 08:52:10 gateway volumio[1261]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1
Aug 25 08:52:10 gateway volumio[1261]: info: Reloading queue from file
Aug 25 08:52:10 gateway volumio[1261]: info: VolumeController:: Volume=50 Mute =false
Aug 25 08:52:10 gateway volumio[1261]: info: CoreStateMachine::pushState
Aug 25 08:52:10 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::volumioPushState
Aug 25 08:52:10 gateway volumio[1261]: info: CoreStateMachine::updateTrackBlock
Aug 25 08:52:10 gateway volumio[1261]: info: CorePlayQueue::getTrackBlock
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::volumioRetrievevolume
Aug 25 08:52:10 gateway volumio[1261]: info: CoreStateMachine::setRepeat true single undefined
Aug 25 08:52:10 gateway volumio[1261]: info: CoreStateMachine::pushState
Aug 25 08:52:10 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::volumioPushState
Aug 25 08:52:10 gateway volumio[1261]: info: CoreStateMachine::setRandom false
Aug 25 08:52:10 gateway volumio[1261]: info: CoreStateMachine::pushState
Aug 25 08:52:10 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::volumioPushState
Aug 25 08:52:10 gateway volumio[1261]: info: Asound.conf file unchanged, so no further update is needed
Aug 25 08:52:10 gateway volumio[1261]: info: Output device has changed, restarting MPD
Aug 25 08:52:10 gateway sudo[1813]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 25 08:52:10 gateway volumio[1261]: info: Output device has changed, restarting Shairport Sync
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:10 gateway sudo[1813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:10 gateway sudo[1813]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:10 gateway sudo[1815]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 25 08:52:10 gateway sudo[1815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:10 gateway volumio[1261]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 25 08:52:10 gateway volumio[1261]: info: ___________ START PLUGINS ___________
Aug 25 08:52:10 gateway volumio[1261]: info: ControllerMpd::onStart: Initializing MPD
Aug 25 08:52:10 gateway volumio[1261]: info: Creating MPD Configuration file
Aug 25 08:52:10 gateway sudo[1824]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service
Aug 25 08:52:10 gateway sudo[1824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 25 08:52:10 gateway volumio[1261]: info: [1787662330824] CoreMusicLibrary::Adding element Media Servers
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 25 08:52:10 gateway sudo[1827]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 25 08:52:10 gateway sudo[1827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:10 gateway volumio[1261]: info: UPNP Browser: Client initialized successfully
Aug 25 08:52:10 gateway sudo[1828]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 25 08:52:10 gateway sudo[1828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:10 gateway sudo[1828]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:10 gateway systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 25 08:52:10 gateway systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 25 08:52:10 gateway systemd[1]: mpd.service: Deactivated successfully.
Aug 25 08:52:10 gateway systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 25 08:52:10 gateway volumio[1261]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 25 08:52:10 gateway systemd[1]: mpd.socket: Deactivated successfully.
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:10 gateway systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 25 08:52:10 gateway systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 25 08:52:10 gateway systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 25 08:52:10 gateway systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 25 08:52:10 gateway systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server.
Aug 25 08:52:10 gateway volumio[1261]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 25 08:52:10 gateway volumio[1261]: info: [1787662330902] CoreMusicLibrary::Adding element Last_100
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 25 08:52:10 gateway volumio[1261]: info: [1787662330903] CoreMusicLibrary::Adding element Webradio
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 25 08:52:10 gateway sudo[1824]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:10 gateway volumio[1261]: info: Initializing BBC Radios
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 25 08:52:10 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:10 gateway sudo[1845]:     root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 25 08:52:10 gateway sudo[1845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 25 08:52:10 gateway sudo[1873]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Aug 25 08:52:10 gateway sudo[1845]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:10 gateway volumio[1261]: info: Loading i18n strings for locale en
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: FusionDsp -  mixtype--------------------- Hardware
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 25 08:52:11 gateway volumio[1261]: info: [1787662331018] CoreMusicLibrary::Adding element Radio Paradise
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 25 08:52:11 gateway volumio[1261]: Cannot find translation for source Radio Paradise
Aug 25 08:52:11 gateway volumio[1261]: info: Volumio Calling Home
Aug 25 08:52:11 gateway volumio[1261]: info: Preparing to generate the ALSA configuration file
Aug 25 08:52:11 gateway ntpd[1021]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101
Aug 25 08:52:11 gateway volumio[1261]: info: Discovery: adding 3f613908-beee-45a8-b940-743b82765aaa
Aug 25 08:52:11 gateway volumio[1261]: info: Discovery: Found device gateway
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:11 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:11 gateway volumio[1261]: info: Discovery: this is already registered,  3f613908-beee-45a8-b940-743b82765aaa
Aug 25 08:52:11 gateway volumio[1261]: info: Discovery: Found device gateway
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:11 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:11 gateway volumio[1261]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Aug 25 08:52:11 gateway volumio[1261]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Aug 25 08:52:11 gateway volumio[1261]: info: Reading ALSA contributions from plugins.
Aug 25 08:52:11 gateway volumio[1261]: info: [go-librespot] time="2026-08-25T08:52:11-04:00" level=info msg="running go-librespot 0.9.0"
Aug 25 08:52:11 gateway volumio[1261]: time="2026-08-25T08:52:11-04:00" level=info msg="api server listening on 127.0.0.1:9878"
Aug 25 08:52:11 gateway volumio[1261]: info: MPD Permissions set
Aug 25 08:52:11 gateway volumio[1261]: info: MPD Permissions set
Aug 25 08:52:11 gateway volumio[1261]: info: VolumeController:: Volume=50 Mute =false
Aug 25 08:52:11 gateway volumio[1261]: info: CoreStateMachine::pushState
Aug 25 08:52:11 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::volumioPushState
Aug 25 08:52:11 gateway ntpd[1021]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101
Aug 25 08:52:11 gateway ntpd[1021]: DNS: Pool taking: 206.108.0.131
Aug 25 08:52:11 gateway ntpd[1021]: DNS: Pool taking: 174.142.148.226
Aug 25 08:52:11 gateway ntpd[1021]: DNS: Pool taking: 199.182.221.110
Aug 25 08:52:11 gateway ntpd[1021]: DNS: Pool taking: 216.232.132.63
Aug 25 08:52:11 gateway ntpd[1021]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8
Aug 25 08:52:11 gateway volumio[1261]: info: Volumio called home
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio5-onboarding[1848]: time=2026-08-25T08:52:11.361-04:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2
Aug 25 08:52:11 gateway volumio[1261]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
Aug 25 08:52:11 gateway volumio[1261]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4
Aug 25 08:52:11 gateway volumio5-onboarding[1848]: time=2026-08-25T08:52:11.488-04:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist"
Aug 25 08:52:11 gateway volumio5-onboarding[1848]: time=2026-08-25T08:52:11.489-04:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist"
Aug 25 08:52:11 gateway volumio5-onboarding[1848]: time=2026-08-25T08:52:11.490-04:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist"
Aug 25 08:52:11 gateway volumio5-onboarding[1848]: time=2026-08-25T08:52:11.490-04:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist"
Aug 25 08:52:11 gateway volumio5-onboarding[1848]: time=2026-08-25T08:52:11.491-04:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist"
Aug 25 08:52:11 gateway volumio5-onboarding[1848]: time=2026-08-25T08:52:11.491-04:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled"
Aug 25 08:52:11 gateway volumio[1261]: info: Starting Shairport Sync
Aug 25 08:52:11 gateway volumio[1261]: info: Starting Shairport Sync
Aug 25 08:52:11 gateway volumio[1261]: info: Starting Shairport Sync
Aug 25 08:52:11 gateway sudo[1958]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 25 08:52:11 gateway sudo[1956]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 25 08:52:11 gateway sudo[1958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:11 gateway sudo[1956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:11 gateway volumio[1261]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4
Aug 25 08:52:11 gateway volumio[1261]: info: Received Get System Info
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 25 08:52:11 gateway volumio[1261]: info: Discovery: Getting this device information
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:11 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 25 08:52:11 gateway sudo[1960]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 25 08:52:11 gateway sudo[1960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:11 gateway volumio5-onboarding[1848]: time=2026-08-25T08:52:11.549-04:00 level=INFO msg="system info for a5f049a44529a0f5afd794d1d39b28b9" deviceName=gateway deviceVariant=volumio deviceModel= softwareVersion=4.119
Aug 25 08:52:11 gateway systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 25 08:52:11 gateway systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 25 08:52:11 gateway systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 08:52:11 gateway systemd[1]: shairport-sync.service: Consumed 1.450s CPU time.
Aug 25 08:52:11 gateway volumio[1261]: info: Asound.conf file unchanged, so no further update is needed
Aug 25 08:52:11 gateway volumio[1261]: info: Output device has changed, restarting MPD
Aug 25 08:52:11 gateway volumio[1261]: info: Output device has changed, restarting Shairport Sync
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:11 gateway sudo[1974]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 25 08:52:11 gateway sudo[1974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:11 gateway systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 08:52:11 gateway volumio5-onboarding[1848]: time=2026-08-25T08:52:11.599-04:00 level=INFO msg="bootstrapping state" hasInternet=true
Aug 25 08:52:11 gateway sudo[1973]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 25 08:52:11 gateway sudo[1958]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:11 gateway sudo[1956]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:11 gateway systemd[1]: mpd.service: Deactivated successfully.
Aug 25 08:52:11 gateway systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 25 08:52:11 gateway sudo[1973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:11 gateway systemd[1]: mpd.socket: Deactivated successfully.
Aug 25 08:52:11 gateway systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 25 08:52:11 gateway systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 25 08:52:11 gateway systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 25 08:52:11 gateway sudo[1973]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:11 gateway volumio[1261]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 25 08:52:11 gateway systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 25 08:52:11 gateway systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 25 08:52:11 gateway volumio[1261]: info: Received Get System Info
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 25 08:52:11 gateway volumio[1261]: info: Discovery: Getting this device information
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:11 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 25 08:52:11 gateway systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 25 08:52:11 gateway systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 08:52:11 gateway systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 08:52:11 gateway sudo[1960]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:11 gateway volumio[1261]: info: MPD Permissions set
Aug 25 08:52:11 gateway volumio[1261]: info: Shairport-Sync Started
Aug 25 08:52:11 gateway volumio[1261]: Error adding Membership: Error: addMembership EINVAL
Aug 25 08:52:11 gateway volumio[1261]: info: Shairport-Sync Started
Aug 25 08:52:11 gateway volumio-remote-updater[808]: Test mode disabled
Aug 25 08:52:11 gateway volumio-remote-updater[808]: Alpha mode enabled
Aug 25 08:52:11 gateway volumio-remote-updater[808]: Alpha legacy test mode disabled
Aug 25 08:52:11 gateway volumio[1261]: info: Shairport-Sync Started
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:11 gateway sudo[1987]:     root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 25 08:52:11 gateway sudo[1987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 25 08:52:11 gateway sudo[2019]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
Aug 25 08:52:11 gateway sudo[1987]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:11 gateway volumio[1261]: info: [go-librespot] time="2026-08-25T08:52:11-04:00" level=info msg="zeroconf server listening on port 43713"
Aug 25 08:52:11 gateway volumio[1261]: time="2026-08-25T08:52:11-04:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration"
Aug 25 08:52:11 gateway volumio[1261]: time="2026-08-25T08:52:11-04:00" level=info msg="loading previously persisted zeroconf credentials" username="jo****n1"
Aug 25 08:52:11 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:11 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:11 gateway volumio[1261]: info: Starting Shairport Sync
Aug 25 08:52:11 gateway sudo[2029]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 25 08:52:11 gateway sudo[2029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:11 gateway systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 25 08:52:11 gateway systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 25 08:52:11 gateway systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 08:52:11 gateway systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 08:52:11 gateway sudo[2029]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:11 gateway volumio[1261]: info: Shairport-Sync Started
Aug 25 08:52:12 gateway volumio[1261]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nFix for missing PWM backlight module
\nFix for crash loop on Onboarding plugin
\nFix for USB boot on CM4/CM5 with EMMC
\n
\nNEW ADDITIONS
\n\nBump RPi/CM4/CM5 kernel to 6.12.75
\nBump Vivaldi to 7.5 for Kiosk Mode
\n\n","title":"Update v4.194","updateavailable":true}
Aug 25 08:52:12 gateway systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1.
Aug 25 08:52:12 gateway systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Aug 25 08:52:12 gateway systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Aug 25 08:52:12 gateway upmpdcli[2059]: Could not open config: /tmp/upmpdcli.conf
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Aug 25 08:52:12 gateway systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 08:52:12 gateway systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 25 08:52:12 gateway volumio[1261]: info: Received Get System Info
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 25 08:52:12 gateway volumio[1261]: info: Discovery: Getting this device information
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:12 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Aug 25 08:52:12 gateway volumio5-onboarding[1848]: time=2026-08-25T08:52:12.124-04:00 level=INFO msg="enabling local network discovery"
Aug 25 08:52:12 gateway volumio[1261]: info: [SpotifyConnect] event stream CONNECTED (ws://localhost:9878/events)
Aug 25 08:52:12 gateway volumio[1261]: info: [go-librespot] time="2026-08-25T08:52:12-04:00" level=info msg="authenticated AP" username="jo****n1"
Aug 25 08:52:12 gateway volumio[1261]: info: [go-librespot] time="2026-08-25T08:52:12-04:00" level=info msg="authenticated Login5" username="jo****n1"
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::servicePushState
Aug 25 08:52:12 gateway volumio[1261]: info: CoreStateMachine::pushState
Aug 25 08:52:12 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::volumioPushState
Aug 25 08:52:12 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:12 gateway volumio[1261]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received spotify-connect
Aug 25 08:52:12 gateway volumio[1261]: info: [SpotifyConnect] event: playback_ready
Aug 25 08:52:13 gateway volumio[1261]: info: camilladsp service started and running in background, instance 1
Aug 25 08:52:13 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:13 gateway volumio[1261]: info: FusionDsp -  AAAAAAAAAAAAAA->  44100 48000 <-AAAAAAAAAAAAA
Aug 25 08:52:13 gateway volumio[1261]: info: FusionDsp loaded
Aug 25 08:52:13 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 25 08:52:13 gateway volumio[1261]: info: FusionDsp -  Reporting Fusion DSP Enabled
Aug 25 08:52:13 gateway volumio[1261]: info: Adding Signal Path Element [object Object]
Aug 25 08:52:13 gateway volumio[1261]: info: Adding fusiondspeq DSP Signal Path Element
Aug 25 08:52:13 gateway volumio[1261]: info: FusionDsp -  ---- installed callbackRead
Aug 25 08:52:13 gateway volumio[1261]: info: [SpotifyConnect] event: volume
Aug 25 08:52:13 gateway volumio[1261]: info: VolumeController::SetAlsaVolume50
Aug 25 08:52:13 gateway volumio[1261]: info: CoreStateMachine::pushState
Aug 25 08:52:13 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:13 gateway sudo[2079]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Aug 25 08:52:13 gateway sudo[2079]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:13 gateway volumio5-onboarding[1848]: time=2026-08-25T08:52:13.098-04:00 level=INFO msg="service successfully established" component=discovery/localnet
Aug 25 08:52:13 gateway volumio[1261]: info: CoreCommandRouter::volumioPushState
Aug 25 08:52:13 gateway volumio[1261]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Aug 25 08:52:13 gateway volumio[1261]: info: FusionDsp - Volumio is not playing
Aug 25 08:52:13 gateway volumio[1261]: info: FusionDsp - Clipped samples monitor stopped
Aug 25 08:52:13 gateway volumio[1261]: error: FusionDsp - Reload WebSocket error: [object Object]
Aug 25 08:52:13 gateway systemd[1]: Started fusiondsp.service - FusionDsp Daemon.
Aug 25 08:52:13 gateway mpd[2025]: 2026-08-25T08:52:13 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Aug 25 08:52:13 gateway sudo[2079]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:13 gateway systemd[1]: Started mpd.service - Music Player Daemon.
Aug 25 08:52:13 gateway sudo[1827]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:13 gateway sudo[1815]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:13 gateway sudo[1974]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:13 gateway volumio[1261]: info: Completed starting Core Plugins
Aug 25 08:52:13 gateway volumio[1261]: info: -------------------------------------------
Aug 25 08:52:13 gateway volumio[1261]: info: -----    MyVolumio plugins startup     ----
Aug 25 08:52:13 gateway volumio[1261]: info: -------------------------------------------
Aug 25 08:52:13 gateway volumio[1261]: info: [MyVolumio PluginManager] Fetching plans data....
Aug 25 08:52:13 gateway volumio[1261]: info: MPD running with PID2025
Aug 25 08:52:13 gateway volumio[1261]:  ,establishing connection
Aug 25 08:52:13 gateway volumio[1261]: error: MPD error: The expression evaluated to a falsy value:
Aug 25 08:52:13 gateway volumio[1261]:   assert.ok(self.idling)
Aug 25 08:52:13 gateway volumio[1261]: error: The expression evaluated to a falsy value:
Aug 25 08:52:13 gateway volumio[1261]:   assert.ok(self.idling)
Aug 25 08:52:13 gateway volumio[1261]: error: MPD error: The expression evaluated to a falsy value:
Aug 25 08:52:13 gateway volumio[1261]:   assert.ok(self.idling)
Aug 25 08:52:13 gateway volumio[1261]: error: The expression evaluated to a falsy value:
Aug 25 08:52:13 gateway volumio[1261]:   assert.ok(self.idling)
Aug 25 08:52:13 gateway volumio[1261]: error: MPD error: The expression evaluated to a falsy value:
Aug 25 08:52:13 gateway volumio[1261]:   assert.ok(self.idling)
Aug 25 08:52:13 gateway volumio[1261]: error: The expression evaluated to a falsy value:
Aug 25 08:52:13 gateway volumio[1261]:   assert.ok(self.idling)
Aug 25 08:52:13 gateway volumio[1261]: error: updateQueue error: null
Aug 25 08:52:13 gateway volumio[1261]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
Aug 25 08:52:14 gateway volumio[1261]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Aug 25 08:52:12 gateway ntpd[1021]: CLOCK: time stepped by -1.717711
Aug 25 08:52:12 gateway systemd-journald[576]: Time jumped backwards, rotating.
Aug 25 08:52:12 gateway ntpd[1021]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::volumioGetVisibleSources
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:12 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Aug 25 08:52:12 gateway volumio[1261]: info: Received Get System Info
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 25 08:52:12 gateway volumio[1261]: info: Discovery: Getting this device information
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:12 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:12 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:12 gateway volumio[1261]: info: Listing playlists
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Aug 25 08:52:12 gateway volumio[1261]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 25 08:52:13 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:13 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:14 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 25 08:52:14 gateway volumio[1261]: info: Received Get System Info
Aug 25 08:52:14 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 25 08:52:14 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 25 08:52:14 gateway volumio[1261]: info: Discovery: Getting this device information
Aug 25 08:52:14 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:14 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:14 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 25 08:52:14 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Aug 25 08:52:14 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
Aug 25 08:52:15 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 25 08:52:15 gateway volumio[1261]: info: Received Get System Info
Aug 25 08:52:15 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 25 08:52:15 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 25 08:52:15 gateway volumio[1261]: info: Discovery: Getting this device information
Aug 25 08:52:15 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:15 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:15 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 25 08:52:15 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
Aug 25 08:52:15 gateway sudo[2114]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 25 08:52:15 gateway sudo[2114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:15 gateway sudo[2116]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 25 08:52:15 gateway sudo[2116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:15 gateway sudo[2114]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:15 gateway sudo[2116]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:15 gateway sudo[2120]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Aug 25 08:52:15 gateway sudo[2120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:16 gateway volumio[1261]: error: Failed LSINFO: Error:  [50@0] {lsinfo} No such directory
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Aug 25 08:52:19 gateway volumio[1261]: info: Adding plugin bluetooth to MyMusic Plugins
Aug 25 08:52:19 gateway volumio[1261]: info: Adding plugin multiroom to MyMusic Plugins
Aug 25 08:52:19 gateway volumio[1261]: info: Adding plugin metavolumio to MyMusic Plugins
Aug 25 08:52:19 gateway volumio[1261]: info: Adding plugin cd_controller to MyMusic Plugins
Aug 25 08:52:19 gateway volumio[1261]: info: Adding plugin qobuzconnect to MyMusic Plugins
Aug 25 08:52:19 gateway volumio[1261]: info: Adding plugin smart_inputs to MyMusic Plugins
Aug 25 08:52:19 gateway volumio[1261]: info: Adding plugin tidalconnect to MyMusic Plugins
Aug 25 08:52:19 gateway volumio[1261]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Aug 25 08:52:20 gateway volumio[1261]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Aug 25 08:52:20 gateway volumio[1261]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Aug 25 08:52:20 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:20 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:20 gateway volumio[1261]: info: Starting MyVolumio Remote Streaming Endpoints
Aug 25 08:52:20 gateway volumio[1261]: info: MyVolumio login type: Token
Aug 25 08:52:20 gateway volumio[1261]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Aug 25 08:52:20 gateway volumio[1261]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Aug 25 08:52:20 gateway volumio[1261]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Aug 25 08:52:20 gateway volumio[1261]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Aug 25 08:52:20 gateway volumio[1261]: info: Streaming services startup
Aug 25 08:52:20 gateway volumio[1261]: info: Starting Streaming Daemon
Aug 25 08:52:20 gateway volumio[1261]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Aug 25 08:52:20 gateway sudo[2129]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Aug 25 08:52:20 gateway sudo[2129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:20 gateway sudo[2129]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:20 gateway volumio[1261]: error: Cannot start Volumio Streaming Daemon
Aug 25 08:52:20 gateway volumio[1261]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Aug 25 08:52:20 gateway volumio[1261]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Aug 25 08:52:21 gateway volumio[1261]: error: MyVolumio Custom Token format not valid, refreshing it
Aug 25 08:52:21 gateway volumio[1261]: info: MyVolumio login type: Token
Aug 25 08:52:22 gateway volumio[1261]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Aug 25 08:52:22 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Aug 25 08:52:22 gateway volumio[1261]: info: MyVolumio token set successfully
Aug 25 08:52:22 gateway volumio[1261]: info: MYVOLUMIO: Adding device
Aug 25 08:52:22 gateway volumio[1261]: info: MYVOLUMIO: Evaluating Server
Aug 25 08:52:22 gateway volumio[1261]: info: MyVolumio Plan changed: premium
Aug 25 08:52:22 gateway volumio[1261]: info: [MyVolumio PluginManager] Subscribed plan changed to premium
Aug 25 08:52:22 gateway volumio[1261]: info: Removing browser output: myVolumio user plan is not superstar
Aug 25 08:52:22 gateway volumio[1261]: info: Removing audio output:
Aug 25 08:52:22 gateway volumio[1261]: info: MYVOLUMIO: Adding device
Aug 25 08:52:22 gateway volumio[1261]: info: MYVOLUMIO: Evaluating Server
Aug 25 08:52:22 gateway volumio[1261]: info: Remote config written successfully
Aug 25 08:52:22 gateway volumio[1261]: info: Starting Tunnel 1
Aug 25 08:52:22 gateway volumio[1261]: info: Starting Tunnel Connection Checker
Aug 25 08:52:22 gateway volumio[1261]: info: Completed starting MyVolumio Plugin
Aug 25 08:52:22 gateway volumio[1261]: info: MYVolumio Device enabled
Aug 25 08:52:22 gateway volumio[1261]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...
Aug 25 08:52:22 gateway volumio[1261]: info: MyVolumio status changed
Aug 25 08:52:22 gateway volumio[1261]: info: Streaming services startup
Aug 25 08:52:22 gateway volumio[1261]: info: Starting Streaming Daemon
Aug 25 08:52:23 gateway sudo[2176]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Aug 25 08:52:23 gateway sudo[2176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:23 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Aug 25 08:52:23 gateway volumio[1261]: error: [MyVolumio PluginManager] Cache data is invalid!
Aug 25 08:52:23 gateway sudo[2176]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:23 gateway volumio[1261]: error: Cannot start Volumio Streaming Daemon
Aug 25 08:52:23 gateway volumio[1261]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Aug 25 08:52:23 gateway volumio[1261]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Aug 25 08:52:23 gateway volumio[1261]: info: Setting Geolocation for MyVolumio to us3
Aug 25 08:52:23 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:23 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:23 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:23 gateway volumio[1261]: info: Setting Geolocation for MyVolumio to us3
Aug 25 08:52:23 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:23 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:23 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:23 gateway volumio[1261]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"...
Aug 25 08:52:23 gateway volumio[1261]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth
Aug 25 08:52:23 gateway volumio[1261]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth
Aug 25 08:52:23 gateway volumio[1261]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart
Aug 25 08:52:23 gateway volumio[1261]: info: [MyVolumio PluginManager] Loading plugin "multiroom"...
Aug 25 08:52:23 gateway volumio[1261]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom
Aug 25 08:52:23 gateway sudo[2182]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Aug 25 08:52:23 gateway sudo[2182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:23 gateway sudo[2182]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:23 gateway volumio[1261]: info: MRS: MultiRoom plugin initialized
Aug 25 08:52:23 gateway volumio[1261]: info: MRS: STOPPING SNAPCLIENT
Aug 25 08:52:23 gateway volumio[1261]: info: MRS: Snap server stop
Aug 25 08:52:23 gateway volumio[1261]: info: MRS: STOPPING volumioStreaming
Aug 25 08:52:23 gateway sudo[2199]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Aug 25 08:52:23 gateway sudo[2199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:23 gateway sudo[2201]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Aug 25 08:52:23 gateway sudo[2201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:23 gateway volumio[1261]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"...
Aug 25 08:52:23 gateway sudo[2204]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Aug 25 08:52:23 gateway sudo[2204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:23 gateway sudo[2207]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Aug 25 08:52:23 gateway sudo[2207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:23 gateway sudo[2207]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:23 gateway volumio[1261]: info: [MyVolumio PluginManager] Loading plugin "manifestui"...
Aug 25 08:52:23 gateway volumio[1261]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"...
Aug 25 08:52:23 gateway sudo[2199]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:23 gateway volumio[1261]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...
Aug 25 08:52:23 gateway sudo[2201]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:23 gateway volumio[1261]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...
Aug 25 08:52:23 gateway sudo[2204]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:24 gateway volumio[1261]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio
Aug 25 08:52:24 gateway volumio[1261]: info: [MyVolumio PluginManager] Loading plugin "tidal"...
Aug 25 08:52:24 gateway volumio[1261]: info: [MyVolumio PluginManager] Loading plugin "qobuz"...
Aug 25 08:52:24 gateway volumio[1261]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...
Aug 25 08:52:24 gateway volumio[1261]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"...
Aug 25 08:52:24 gateway volumio[1261]: info: Preparing to generate the ALSA configuration file
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Aug 25 08:52:24 gateway volumio[1261]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Aug 25 08:52:24 gateway volumio[1261]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Aug 25 08:52:24 gateway volumio[1261]: info: Reading ALSA contributions from plugins.
Aug 25 08:52:24 gateway volumio[1261]: info: Successfully Added MyVolumio device
Aug 25 08:52:24 gateway volumio[1261]: info: MRS: Removed streaming files
Aug 25 08:52:24 gateway volumio[1261]: info: MRS: volumioStreaming STOPPED
Aug 25 08:52:24 gateway volumio[1261]: info: MRS: SNAPSERVER STOPPED
Aug 25 08:52:24 gateway volumio[1261]: info: MRS: SNAPCLIENT STOPPED
Aug 25 08:52:24 gateway volumio[1261]: info: Asound.conf file unchanged, so no further update is needed
Aug 25 08:52:24 gateway volumio[1261]: info: Output device has changed, restarting MPD
Aug 25 08:52:24 gateway volumio[1261]: info: Output device has changed, restarting Shairport Sync
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:24 gateway sudo[2212]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 25 08:52:24 gateway sudo[2212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:24 gateway sudo[2212]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:24 gateway sudo[2214]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 25 08:52:24 gateway sudo[2214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:24 gateway volumio[1261]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 25 08:52:24 gateway volumio[1261]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth
Aug 25 08:52:24 gateway systemd[1]: systemd-fsckd.service: Deactivated successfully.
Aug 25 08:52:24 gateway volumio[1261]: ------------------------------------ BT MESSAGE: [FUNC] onStart
Aug 25 08:52:24 gateway volumio[1261]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service
Aug 25 08:52:24 gateway systemd[1]: Stopping mpd.service - Music Player Daemon...
Aug 25 08:52:24 gateway volumio[1261]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp
Aug 25 08:52:24 gateway volumio[1261]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/
Aug 25 08:52:24 gateway volumio[1261]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready.
Aug 25 08:52:24 gateway volumio[1261]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus
Aug 25 08:52:24 gateway volumio[1261]: info: MRS: Pushing multiroomSync output for this device
Aug 25 08:52:24 gateway volumio[1261]: info: MRS: Pushing multiroomSync output
Aug 25 08:52:24 gateway volumio[1261]: info: Adding audio output:
Aug 25 08:52:24 gateway volumio[1261]: info: Adding audio output:
Aug 25 08:52:24 gateway volumio[1261]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio
Aug 25 08:52:24 gateway volumio[1261]: info: Adding METAVOLUMIO REST API Endpoints
Aug 25 08:52:24 gateway volumio[1261]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
Aug 25 08:52:24 gateway volumio[1261]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio
Aug 25 08:52:24 gateway volumio[1261]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio
Aug 25 08:52:24 gateway volumio[1261]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio
Aug 25 08:52:24 gateway volumio[1261]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui
Aug 25 08:52:24 gateway volumio[1261]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller
Aug 25 08:52:24 gateway volumio[1261]: info: Preparing CD Folders
Aug 25 08:52:24 gateway volumio[1261]: info: Adding CD REST API Endpoints
Aug 25 08:52:24 gateway volumio[1261]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller
Aug 25 08:52:24 gateway volumio[1261]: info: Starting UDEV Watcher for CD
Aug 25 08:52:24 gateway volumio[1261]: info: Detecting CD presence with UDEV
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices
Aug 25 08:52:24 gateway systemd[1]: mpd.service: Deactivated successfully.
Aug 25 08:52:24 gateway systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 25 08:52:24 gateway systemd[1]: mpd.service: Consumed 1.131s CPU time.
Aug 25 08:52:24 gateway systemd[1]: mpd.socket: Deactivated successfully.
Aug 25 08:52:24 gateway systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 25 08:52:24 gateway systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 25 08:52:24 gateway systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 25 08:52:24 gateway systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 25 08:52:24 gateway systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Aug 25 08:52:24 gateway sudo[2233]:     root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 25 08:52:24 gateway sudo[2233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 25 08:52:24 gateway sudo[2233]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:24 gateway volumio[1261]: info: [cd-plugin] Set CD speed to 1X
Aug 25 08:52:24 gateway volumio[1261]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs
Aug 25 08:52:24 gateway volumio[1261]: info: Adding inputs REST Endpoints
Aug 25 08:52:24 gateway volumio[1261]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs
Aug 25 08:52:24 gateway volumio[1261]: info: Scanning Audio Inputs
Aug 25 08:52:24 gateway volumio[1261]: info: Checking against Known Cards name
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Aug 25 08:52:24 gateway volumio[1261]: info: [1787662344438] CoreMusicLibrary::Adding element HDA Intel MID
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 25 08:52:24 gateway volumio[1261]: Cannot find translation for source Radio Paradise
Aug 25 08:52:24 gateway volumio[1261]: Cannot find translation for source HDA Intel MID
Aug 25 08:52:24 gateway volumio[1261]: info: Checking against Known Cards name
Aug 25 08:52:24 gateway volumio[1261]: info: Adding Server instance for streaming
Aug 25 08:52:24 gateway volumio[1261]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio
Aug 25 08:52:24 gateway volumio[1261]: error: Hi Res Audio Failed Login: Missing Login Data
Aug 25 08:52:24 gateway volumio[1261]: info: Adding HIGHRESAUDIO REST API Endpoints
Aug 25 08:52:24 gateway volumio[1261]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Aug 25 08:52:24 gateway volumio[1261]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal
Aug 25 08:52:24 gateway volumio[1261]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz
Aug 25 08:52:24 gateway volumio[1261]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect
Aug 25 08:52:24 gateway volumio[1261]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect
Aug 25 08:52:24 gateway volumio[1261]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect
Aug 25 08:52:24 gateway sudo[2256]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Aug 25 08:52:24 gateway sudo[2256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: QobuzConnect: Starting Qobuz Connect socket and service
Aug 25 08:52:24 gateway volumio[1261]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections
Aug 25 08:52:24 gateway volumio[1261]: info: Adding TIDAL REST API Endpoints
Aug 25 08:52:24 gateway volumio[1261]: info: Stopping AccessToken refresher cron for QOBUZ
Aug 25 08:52:24 gateway sudo[2263]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service
Aug 25 08:52:24 gateway sudo[2263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:24 gateway sudo[2256]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:24 gateway sudo[2263]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:24 gateway volumio[1261]: info: AccessToken refresher cron started for QOBUZ
Aug 25 08:52:24 gateway volumio[1261]: info: Adding QOBUZ REST API Endpoints
Aug 25 08:52:24 gateway volumio[1261]: info: Updating MyVolumio device info
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: Updating MyVolumio device info
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:24 gateway sudo[2266]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service
Aug 25 08:52:24 gateway sudo[2266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:24 gateway volumio[1261]: ------------------------------------ BT MESSAGE: Failed to power on adapter:
Aug 25 08:52:24 gateway volumio[1261]: info: MPD Permissions set
Aug 25 08:52:24 gateway sudo[2270]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service
Aug 25 08:52:24 gateway sudo[2270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:24 gateway systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service.
Aug 25 08:52:24 gateway sudo[2266]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway sudo[2270]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:24 gateway volumiobt[2276]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Aug 25 08:52:24 gateway sudo[2280]:  volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 25 08:52:24 gateway sudo[2280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:24 gateway sudo[2280]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully
Aug 25 08:52:24 gateway sudo[2285]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 25 08:52:24 gateway sudo[2285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:24 gateway sudo[2285]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:24 gateway volumio[1261]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart
Aug 25 08:52:24 gateway volumio[1261]: info: Starting Shairport Sync
Aug 25 08:52:24 gateway volumiobt[2287]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 25 08:52:24 gateway volumio[1261]: info: Preparing to generate the ALSA configuration file
Aug 25 08:52:24 gateway sudo[2289]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 25 08:52:24 gateway sudo[2289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:24 gateway volumio[1261]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Aug 25 08:52:24 gateway volumio[1261]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Aug 25 08:52:24 gateway volumio[1261]: info: Reading ALSA contributions from plugins.
Aug 25 08:52:24 gateway volumio[1261]: info: Executing endpoint qc_getconfig
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Aug 25 08:52:24 gateway systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 25 08:52:24 gateway systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 25 08:52:24 gateway systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 08:52:24 gateway systemd[1]: shairport-sync.service: Consumed 1.499s CPU time.
Aug 25 08:52:24 gateway qobuz-connect[2268]: 20260825 08:52:24.700 [2268.2268] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Aug 25 08:52:24 gateway volumio[1261]: info: Successfully Updated MyVolumio device
Aug 25 08:52:24 gateway volumio[1261]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object]
Aug 25 08:52:24 gateway volumio[1261]: info: QobuzConnect: QOBUZ Connect daemon connected
Aug 25 08:52:24 gateway volumiobt[2297]: No default controller available
Aug 25 08:52:24 gateway volumio[1261]: info: Asound.conf file written
Aug 25 08:52:24 gateway systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 08:52:24 gateway sudo[2289]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:24 gateway qobuz-connect[2268]: 20260825 08:52:24.755 [2268.2268] INFO VolumeManager: [0x560bca543a20]: Setting new playback volume: 75
Aug 25 08:52:24 gateway qobuz-connect[2268]: 20260825 08:52:24.755 [2268.2268] INFO VolumeManager: [0x560bca543a20]: Setting new mute state: 0
Aug 25 08:52:24 gateway qobuz-connect[2268]: 20260825 08:52:24.755 [2268.2268] INFO AudioStreamManager: [0x560bca543580]: Setting new audio download buffer size: 1048576
Aug 25 08:52:24 gateway qobuz-connect[2268]: 20260825 08:52:24.755 [2268.2268] INFO QobuzConnect: [0x560bca544a30]: Client initialized!
Aug 25 08:52:24 gateway qobuz-connect[2268]: 20260825 08:52:24.755 [2268.2268] INFO SampleApp: Starting Avahi advertising, name: gateway, service name: _qobuz-connect._tcp
Aug 25 08:52:24 gateway sudo[2301]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Aug 25 08:52:24 gateway sudo[2301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:24 gateway qobuz-connect[2268]: 20260825 08:52:24.759 [2268.2268] INFO LocalConfigManager: [0x560bca543060]: Starting Local Configuration server
Aug 25 08:52:24 gateway qobuz-connect[2268]: 20260825 08:52:24.759 [2268.2268] INFO SampleApp: Starting Local configuration server
Aug 25 08:52:24 gateway qobuz-connect[2268]: 20260825 08:52:24.760 [2268.2268] INFO SampleApp: Connected to UNIX socket client 0x560bca519990
Aug 25 08:52:24 gateway sudo[2301]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:24 gateway volumio[1261]: info: Output device has changed, restarting MPD
Aug 25 08:52:24 gateway volumio[1261]: info: Output device has changed, restarting Shairport Sync
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:24 gateway sudo[2323]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 25 08:52:24 gateway sudo[2323]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:24 gateway sudo[2325]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 25 08:52:24 gateway sudo[2325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:24 gateway sudo[2323]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:24 gateway volumio[1261]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 25 08:52:24 gateway volumio[1261]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: QobuzConnect: setDeactiveState invoked
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:24 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:24 gateway volumio[1261]: info: Preparing to generate the ALSA configuration file
Aug 25 08:52:24 gateway systemd[1]: mpd.service: Deactivated successfully.
Aug 25 08:52:24 gateway systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 25 08:52:24 gateway qobuz-connect[2268]: 20260825 08:52:24.849 [2268.2268] INFO SampleApp: Playback volume changed: 75
Aug 25 08:52:24 gateway systemd[1]: mpd.socket: Deactivated successfully.
Aug 25 08:52:24 gateway systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 25 08:52:24 gateway systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Aug 25 08:52:24 gateway volumio[1261]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Aug 25 08:52:24 gateway volumio[1261]: info: Reading ALSA contributions from plugins.
Aug 25 08:52:24 gateway sudo[2340]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:24 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:24 gateway sudo[2340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:24 gateway volumio[1261]: info: MPD Permissions set
Aug 25 08:52:24 gateway volumio[1261]: info: Shairport-Sync Started
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 25 08:52:24 gateway systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Aug 25 08:52:24 gateway sudo[2340]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: Starting Shairport Sync
Aug 25 08:52:24 gateway volumio[1261]: info: Asound.conf file unchanged, so no further update is needed
Aug 25 08:52:24 gateway volumio[1261]: info: Output device has changed, restarting MPD
Aug 25 08:52:24 gateway sudo[2353]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 25 08:52:24 gateway sudo[2353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:24 gateway sudo[2351]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service
Aug 25 08:52:24 gateway sudo[2351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:24 gateway sudo[2358]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 25 08:52:24 gateway sudo[2358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:24 gateway volumio[1261]: info: Output device has changed, restarting Shairport Sync
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:24 gateway sudo[2358]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:24 gateway systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service...
Aug 25 08:52:24 gateway qobuz-connect[2268]: 20260825 08:52:24.951 [2268.2268] INFO SampleApp: Stopping Local configuration server
Aug 25 08:52:24 gateway systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 25 08:52:24 gateway sudo[2347]:     root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 25 08:52:24 gateway sudo[2347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 25 08:52:24 gateway sudo[2359]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 25 08:52:24 gateway sudo[2347]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:24 gateway sudo[2359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:24 gateway systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 25 08:52:24 gateway systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 08:52:24 gateway volumio[1261]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 25 08:52:24 gateway volumio[1261]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: QobuzConnect: setDeactiveState invoked
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:24 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: MPD Permissions set
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:24 gateway sudo[2375]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service
Aug 25 08:52:24 gateway sudo[2375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:24 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Aug 25 08:52:25 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:25 gateway volumio[1261]: info: Starting Shairport Sync
Aug 25 08:52:25 gateway sudo[2375]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:25 gateway systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 08:52:25 gateway sudo[2353]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:25 gateway volumio[1261]: info: Shairport-Sync Started
Aug 25 08:52:25 gateway sudo[2388]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 25 08:52:25 gateway sudo[2388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:25 gateway sudo[2387]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service
Aug 25 08:52:25 gateway systemd[1]: mpd.service: Deactivated successfully.
Aug 25 08:52:25 gateway systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 25 08:52:25 gateway sudo[2387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:25 gateway systemd[1]: mpd.socket: Deactivated successfully.
Aug 25 08:52:25 gateway systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 25 08:52:25 gateway systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 25 08:52:25 gateway volumio[1261]: info: Successfully Updated MyVolumio device
Aug 25 08:52:25 gateway dhcpcd[790]: timed out
Aug 25 08:52:25 gateway sh[772]: timed out
Aug 25 08:52:25 gateway dhcpcd[790]: dhcpcd exited
Aug 25 08:52:25 gateway sh[772]: dhcpcd exited
Aug 25 08:52:25 gateway sh[711]: ifup: failed to bring up eth0
Aug 25 08:52:25 gateway systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 25 08:52:25 gateway systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 25 08:52:25 gateway systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 25 08:52:25 gateway systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 08:52:25 gateway systemd[1]: ifup@eth0.service: Failed with result 'exit-code'.
Aug 25 08:52:25 gateway systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 25 08:52:25 gateway systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 08:52:25 gateway systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 08:52:25 gateway sudo[2388]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:25 gateway volumio[1261]: info: Shairport-Sync Started
Aug 25 08:52:25 gateway sudo[2412]:     root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 25 08:52:25 gateway sudo[2412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 25 08:52:25 gateway sudo[2412]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:25 gateway volumio[1261]: info: Successfully Added MyVolumio device
Aug 25 08:52:25 gateway systemd[1]: setdatetime-helper.service: Deactivated successfully.
Aug 25 08:52:25 gateway systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Aug 25 08:52:25 gateway systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2.
Aug 25 08:52:25 gateway systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Aug 25 08:52:25 gateway systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Aug 25 08:52:25 gateway sudo[2120]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:25 gateway volumio[1261]: info: Upmpdcli Daemon Started
Aug 25 08:52:25 gateway mpd[2429]: 2026-08-25T08:52:25 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Aug 25 08:52:25 gateway systemd[1]: Started mpd.service - Music Player Daemon.
Aug 25 08:52:25 gateway sudo[2325]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:25 gateway sudo[2359]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:25 gateway sudo[2214]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:25 gateway volumio[1261]: error: MPD error: The expression evaluated to a falsy value:
Aug 25 08:52:25 gateway volumio[1261]:   assert.ok(self.idling)
Aug 25 08:52:25 gateway volumio[1261]: error: The expression evaluated to a falsy value:
Aug 25 08:52:25 gateway volumio[1261]:   assert.ok(self.idling)
Aug 25 08:52:25 gateway volumio[1261]: error: MPD error: The expression evaluated to a falsy value:
Aug 25 08:52:25 gateway volumio[1261]:   assert.ok(self.idling)
Aug 25 08:52:25 gateway volumio[1261]: error: The expression evaluated to a falsy value:
Aug 25 08:52:25 gateway volumio[1261]:   assert.ok(self.idling)
Aug 25 08:52:25 gateway volumio[1261]: error: updateQueue error: null
Aug 25 08:52:25 gateway volumiobt[2454]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 25 08:52:25 gateway volumiobt[2455]: [83B blob data]
Aug 25 08:52:25 gateway volumiobt[2455]: No default controller available
Aug 25 08:52:25 gateway volumiobt[2455]: [bluetoothctl]> pairable on
Aug 25 08:52:25 gateway volumiobt[2455]: No default controller available
Aug 25 08:52:25 gateway volumiobt[2455]: [bluetoothctl]> 
Aug 25 08:52:25 gateway volumiobt[2456]: INFO [BTSTART] Registering Bluetooth agent...
Aug 25 08:52:25 gateway volumiobt[2468]: No agent is registered
Aug 25 08:52:25 gateway volumiobt[2473]: INFO [BTSTART] Agent registered successfully.
Aug 25 08:52:25 gateway volumiobt[2474]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 25 08:52:26 gateway volumiobt[2475]: 2026-08-25 08:52:26 a2dp-agent [INFO] Connecting to system D-Bus
Aug 25 08:52:26 gateway volumiobt[2475]: 2026-08-25 08:52:26 a2dp-agent [INFO] Connected to system D-Bus
Aug 25 08:52:26 gateway volumiobt[2475]: 2026-08-25 08:52:26 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 25 08:52:26 gateway volumiobt[2475]: 2026-08-25 08:52:26 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 25 08:52:26 gateway volumiobt[2475]: Traceback (most recent call last):
Aug 25 08:52:26 gateway volumiobt[2475]:   File "/bin/bt/a2dp-agent", line 225, in main
Aug 25 08:52:26 gateway volumiobt[2475]:     asyncio.run(_run())
Aug 25 08:52:26 gateway volumiobt[2475]:   File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 25 08:52:26 gateway volumiobt[2475]:     return runner.run(main)
Aug 25 08:52:26 gateway volumiobt[2475]:            ^^^^^^^^^^^^^^^^
Aug 25 08:52:26 gateway volumiobt[2475]:   File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 25 08:52:26 gateway volumiobt[2475]:     return self._loop.run_until_complete(task)
Aug 25 08:52:26 gateway volumiobt[2475]:            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:26 gateway volumiobt[2475]:   File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 25 08:52:26 gateway volumiobt[2475]:     return future.result()
Aug 25 08:52:26 gateway volumiobt[2475]:            ^^^^^^^^^^^^^^^
Aug 25 08:52:26 gateway volumiobt[2475]:   File "/bin/bt/a2dp-agent", line 175, in _run
Aug 25 08:52:26 gateway volumiobt[2475]:     adapter_path = await find_adapter(bus)
Aug 25 08:52:26 gateway volumiobt[2475]:                    ^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:26 gateway volumiobt[2475]:   File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 25 08:52:26 gateway volumiobt[2475]:     raise Exception("Bluetooth adapter not found")
Aug 25 08:52:26 gateway volumiobt[2475]: Exception: Bluetooth adapter not found
Aug 25 08:52:26 gateway volumiobt[2475]: Traceback (most recent call last):
Aug 25 08:52:26 gateway volumiobt[2475]:   File "/bin/bt/a2dp-agent", line 234, in 
Aug 25 08:52:26 gateway volumiobt[2475]:     main()
Aug 25 08:52:26 gateway volumiobt[2475]:   File "/bin/bt/a2dp-agent", line 225, in main
Aug 25 08:52:26 gateway volumiobt[2475]:     asyncio.run(_run())
Aug 25 08:52:26 gateway volumiobt[2475]:   File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 25 08:52:26 gateway volumiobt[2475]:     return runner.run(main)
Aug 25 08:52:26 gateway volumiobt[2475]:            ^^^^^^^^^^^^^^^^
Aug 25 08:52:26 gateway volumiobt[2475]:   File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 25 08:52:26 gateway volumiobt[2475]:     return self._loop.run_until_complete(task)
Aug 25 08:52:26 gateway volumiobt[2475]:            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:26 gateway volumiobt[2475]:   File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 25 08:52:26 gateway volumiobt[2475]:     return future.result()
Aug 25 08:52:26 gateway volumiobt[2475]:            ^^^^^^^^^^^^^^^
Aug 25 08:52:26 gateway volumiobt[2475]:   File "/bin/bt/a2dp-agent", line 175, in _run
Aug 25 08:52:26 gateway volumiobt[2475]:     adapter_path = await find_adapter(bus)
Aug 25 08:52:26 gateway volumiobt[2475]:                    ^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:26 gateway volumiobt[2475]:   File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 25 08:52:26 gateway volumiobt[2475]:     raise Exception("Bluetooth adapter not found")
Aug 25 08:52:26 gateway volumiobt[2475]: Exception: Bluetooth adapter not found
Aug 25 08:52:26 gateway systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 08:52:26 gateway systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 25 08:52:26 gateway systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 1.
Aug 25 08:52:26 gateway systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 25 08:52:26 gateway systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 25 08:52:26 gateway volumiobt[2478]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 25 08:52:26 gateway sudo[2479]:  volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 25 08:52:26 gateway sudo[2479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:26 gateway sudo[2479]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:26 gateway sudo[2481]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 25 08:52:26 gateway sudo[2481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:26 gateway sudo[2481]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:26 gateway volumiobt[2483]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 25 08:52:26 gateway volumiobt[2486]: No default controller available
Aug 25 08:52:26 gateway qobuz-connect[2268]: 20260825 08:52:26.765 [2268.2268] INFO SampleApp: shat down connection on UNIX socket
Aug 25 08:52:26 gateway volumio[1261]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected
Aug 25 08:52:26 gateway volumio[1261]: info: QobuzConnect: setDeactiveState invoked
Aug 25 08:52:26 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:26 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:26 gateway systemd[1]: qobuz-connect.service: Deactivated successfully.
Aug 25 08:52:26 gateway systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service.
Aug 25 08:52:26 gateway systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service.
Aug 25 08:52:26 gateway sudo[2351]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:26 gateway sudo[2387]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:26 gateway volumio[1261]: info: Executing endpoint qc_getconfig
Aug 25 08:52:26 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Aug 25 08:52:26 gateway qobuz-connect[2488]: 20260825 08:52:26.828 [2488.2488] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Aug 25 08:52:26 gateway volumio[1261]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object]
Aug 25 08:52:26 gateway volumio[1261]: info: QobuzConnect: QOBUZ Connect daemon connected
Aug 25 08:52:26 gateway qobuz-connect[2488]: 20260825 08:52:26.830 [2488.2488] INFO VolumeManager: [0x555f43187a20]: Setting new playback volume: 75
Aug 25 08:52:26 gateway qobuz-connect[2488]: 20260825 08:52:26.830 [2488.2488] INFO VolumeManager: [0x555f43187a20]: Setting new mute state: 0
Aug 25 08:52:26 gateway qobuz-connect[2488]: 20260825 08:52:26.830 [2488.2488] INFO AudioStreamManager: [0x555f43187580]: Setting new audio download buffer size: 1048576
Aug 25 08:52:26 gateway qobuz-connect[2488]: 20260825 08:52:26.830 [2488.2488] INFO QobuzConnect: [0x555f43188a30]: Client initialized!
Aug 25 08:52:26 gateway qobuz-connect[2488]: 20260825 08:52:26.830 [2488.2488] INFO SampleApp: Starting Avahi advertising, name: gateway, service name: _qobuz-connect._tcp
Aug 25 08:52:26 gateway qobuz-connect[2488]: 20260825 08:52:26.834 [2488.2488] INFO LocalConfigManager: [0x555f43187060]: Starting Local Configuration server
Aug 25 08:52:26 gateway qobuz-connect[2488]: 20260825 08:52:26.834 [2488.2488] INFO SampleApp: Starting Local configuration server
Aug 25 08:52:26 gateway qobuz-connect[2488]: 20260825 08:52:26.834 [2488.2488] INFO SampleApp: Connected to UNIX socket client 0x555f4315d990
Aug 25 08:52:26 gateway qobuz-connect[2488]: 20260825 08:52:26.978 [2488.2488] INFO SampleApp: Playback volume changed: 75
Aug 25 08:52:26 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:26 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:27 gateway volumiobt[2495]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 25 08:52:27 gateway volumiobt[2496]: [83B blob data]
Aug 25 08:52:27 gateway volumiobt[2496]: No default controller available
Aug 25 08:52:27 gateway volumiobt[2496]: [bluetoothctl]> pairable on
Aug 25 08:52:27 gateway volumiobt[2496]: No default controller available
Aug 25 08:52:27 gateway volumiobt[2496]: [113B blob data]
Aug 25 08:52:27 gateway volumiobt[2496]: [bluetoothctl]> 
Aug 25 08:52:27 gateway volumiobt[2497]: INFO [BTSTART] Registering Bluetooth agent...
Aug 25 08:52:27 gateway volumiobt[2499]: No agent is registered
Aug 25 08:52:27 gateway volumiobt[2500]: INFO [BTSTART] Agent registered successfully.
Aug 25 08:52:27 gateway volumiobt[2501]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 25 08:52:27 gateway volumio[1261]: info: TidalConnect service stoped!
Aug 25 08:52:27 gateway volumio[1261]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Aug 25 08:52:27 gateway volumio[1261]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect
Aug 25 08:52:27 gateway sudo[2512]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Aug 25 08:52:27 gateway sudo[2512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:27 gateway volumiobt[2502]: 2026-08-25 08:52:27 a2dp-agent [INFO] Connecting to system D-Bus
Aug 25 08:52:27 gateway volumiobt[2502]: 2026-08-25 08:52:27 a2dp-agent [INFO] Connected to system D-Bus
Aug 25 08:52:27 gateway volumiobt[2502]: 2026-08-25 08:52:27 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 25 08:52:27 gateway systemd[1]: Started vtcs.service - Volumio Tidal Connect Service.
Aug 25 08:52:27 gateway volumiobt[2502]: 2026-08-25 08:52:27 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 25 08:52:27 gateway volumiobt[2502]: Traceback (most recent call last):
Aug 25 08:52:27 gateway volumiobt[2502]:   File "/bin/bt/a2dp-agent", line 225, in main
Aug 25 08:52:27 gateway volumiobt[2502]:     asyncio.run(_run())
Aug 25 08:52:27 gateway volumiobt[2502]:   File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 25 08:52:27 gateway volumiobt[2502]:     return runner.run(main)
Aug 25 08:52:27 gateway volumiobt[2502]:            ^^^^^^^^^^^^^^^^
Aug 25 08:52:27 gateway volumiobt[2502]:   File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 25 08:52:27 gateway volumiobt[2502]:     return self._loop.run_until_complete(task)
Aug 25 08:52:27 gateway volumiobt[2502]:            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:27 gateway volumiobt[2502]:   File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 25 08:52:27 gateway volumiobt[2502]:     return future.result()
Aug 25 08:52:27 gateway volumiobt[2502]:            ^^^^^^^^^^^^^^^
Aug 25 08:52:27 gateway volumiobt[2502]:   File "/bin/bt/a2dp-agent", line 175, in _run
Aug 25 08:52:27 gateway volumiobt[2502]:     adapter_path = await find_adapter(bus)
Aug 25 08:52:27 gateway volumiobt[2502]:                    ^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:27 gateway volumiobt[2502]:   File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 25 08:52:27 gateway volumiobt[2502]:     raise Exception("Bluetooth adapter not found")
Aug 25 08:52:27 gateway volumiobt[2502]: Exception: Bluetooth adapter not found
Aug 25 08:52:27 gateway volumiobt[2502]: Traceback (most recent call last):
Aug 25 08:52:27 gateway volumiobt[2502]:   File "/bin/bt/a2dp-agent", line 234, in 
Aug 25 08:52:27 gateway volumiobt[2502]:     main()
Aug 25 08:52:27 gateway volumiobt[2502]:   File "/bin/bt/a2dp-agent", line 225, in main
Aug 25 08:52:27 gateway volumiobt[2502]:     asyncio.run(_run())
Aug 25 08:52:27 gateway volumiobt[2502]:   File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 25 08:52:27 gateway volumiobt[2502]:     return runner.run(main)
Aug 25 08:52:27 gateway volumiobt[2502]:            ^^^^^^^^^^^^^^^^
Aug 25 08:52:27 gateway volumiobt[2502]:   File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 25 08:52:27 gateway volumiobt[2502]:     return self._loop.run_until_complete(task)
Aug 25 08:52:27 gateway volumiobt[2502]:            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:27 gateway volumiobt[2502]:   File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 25 08:52:27 gateway volumiobt[2502]:     return future.result()
Aug 25 08:52:27 gateway volumiobt[2502]:            ^^^^^^^^^^^^^^^
Aug 25 08:52:27 gateway volumiobt[2502]:   File "/bin/bt/a2dp-agent", line 175, in _run
Aug 25 08:52:27 gateway sudo[2512]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:27 gateway volumiobt[2502]:     adapter_path = await find_adapter(bus)
Aug 25 08:52:27 gateway volumiobt[2502]:                    ^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:27 gateway volumiobt[2502]:   File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 25 08:52:27 gateway volumiobt[2502]:     raise Exception("Bluetooth adapter not found")
Aug 25 08:52:27 gateway volumiobt[2502]: Exception: Bluetooth adapter not found
Aug 25 08:52:27 gateway systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 08:52:27 gateway systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 25 08:52:27 gateway volumio[1261]: info: Executing endpoint tc_getconfig
Aug 25 08:52:27 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Aug 25 08:52:27 gateway vtcs[2515]: STARTING TidalConnect services, version: 1.6.1
Aug 25 08:52:27 gateway vtcs[2515]: STARTED TidalConnect services.
Aug 25 08:52:27 gateway volumio[1261]: info: Executing endpoint tc_connect
Aug 25 08:52:27 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Aug 25 08:52:27 gateway volumio[1261]: info: Connecting to TidalConnect
Aug 25 08:52:27 gateway volumio[1261]: info: CoreCommandRouter::servicePushState
Aug 25 08:52:27 gateway volumio[1261]: info: CoreStateMachine::pushState
Aug 25 08:52:27 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:27 gateway volumio[1261]: info: CoreCommandRouter::volumioPushState
Aug 25 08:52:27 gateway volumio[1261]: info: MRS: Pushing multiroomSync output update for this device
Aug 25 08:52:27 gateway volumio[1261]: info: MRS: Pushing multiroomSync output
Aug 25 08:52:27 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:27 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:27 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:27 gateway volumio[1261]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect
Aug 25 08:52:27 gateway volumio[1261]: info: CoreCommandRouter::servicePushState
Aug 25 08:52:27 gateway volumio[1261]: info: CoreStateMachine::pushState
Aug 25 08:52:27 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:27 gateway volumio[1261]: info: CoreCommandRouter::volumioPushState
Aug 25 08:52:27 gateway volumio[1261]: info: MRS: Pushing multiroomSync output update for this device
Aug 25 08:52:27 gateway volumio[1261]: info: MRS: Pushing multiroomSync output
Aug 25 08:52:27 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:27 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:27 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:27 gateway volumio[1261]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect
Aug 25 08:52:27 gateway volumio[1261]: info: FusionDsp - Volumio is not playing
Aug 25 08:52:27 gateway volumio[1261]: info: FusionDsp - Clipped samples monitor stopped
Aug 25 08:52:27 gateway volumio[1261]: info: FusionDsp - Volumio is not playing
Aug 25 08:52:27 gateway volumio[1261]: info: FusionDsp - Clipped samples monitor stopped
Aug 25 08:52:27 gateway systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 2.
Aug 25 08:52:27 gateway systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 25 08:52:27 gateway systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 25 08:52:27 gateway volumiobt[2532]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 25 08:52:27 gateway sudo[2533]:  volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 25 08:52:27 gateway sudo[2533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:27 gateway sudo[2533]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:27 gateway sudo[2535]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 25 08:52:27 gateway sudo[2535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:27 gateway sudo[2535]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:27 gateway volumiobt[2537]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 25 08:52:27 gateway volumiobt[2540]: No default controller available
Aug 25 08:52:27 gateway sudo[2548]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
Aug 25 08:52:27 gateway sudo[2548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:27 gateway systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 25 08:52:27 gateway systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Aug 25 08:52:27 gateway systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel.
Aug 25 08:52:27 gateway sudo[2548]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:27 gateway volumio[1261]: info: Remote SSH Started
Aug 25 08:52:27 gateway autossh[2551]: port set to 0, monitoring disabled
Aug 25 08:52:27 gateway autossh[2551]: starting ssh (count 1)
Aug 25 08:52:27 gateway autossh[2551]: ssh child pid is 2554
Aug 25 08:52:27 gateway volumio[1261]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7
Aug 25 08:52:28 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:28 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:28 gateway volumiossh-tunnel[2554]: Warning: Permanently added '[us3.myvolumio.org]:2222' (RSA) to the list of known hosts.
Aug 25 08:52:28 gateway volumiobt[2556]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 25 08:52:28 gateway volumiobt[2557]: [83B blob data]
Aug 25 08:52:28 gateway volumiobt[2557]: No default controller available
Aug 25 08:52:28 gateway volumiobt[2557]: [bluetoothctl]> pairable on
Aug 25 08:52:28 gateway volumiobt[2557]: No default controller available
Aug 25 08:52:28 gateway volumiobt[2557]: [113B blob data]
Aug 25 08:52:28 gateway volumiobt[2557]: [bluetoothctl]> 
Aug 25 08:52:28 gateway volumiobt[2558]: INFO [BTSTART] Registering Bluetooth agent...
Aug 25 08:52:28 gateway volumiobt[2560]: No agent is registered
Aug 25 08:52:28 gateway volumiobt[2561]: INFO [BTSTART] Agent registered successfully.
Aug 25 08:52:28 gateway volumiobt[2562]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 25 08:52:29 gateway volumiobt[2563]: 2026-08-25 08:52:29 a2dp-agent [INFO] Connecting to system D-Bus
Aug 25 08:52:29 gateway volumiobt[2563]: 2026-08-25 08:52:29 a2dp-agent [INFO] Connected to system D-Bus
Aug 25 08:52:29 gateway volumiobt[2563]: 2026-08-25 08:52:29 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 25 08:52:29 gateway volumiobt[2563]: 2026-08-25 08:52:29 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 25 08:52:29 gateway volumiobt[2563]: Traceback (most recent call last):
Aug 25 08:52:29 gateway volumiobt[2563]:   File "/bin/bt/a2dp-agent", line 225, in main
Aug 25 08:52:29 gateway volumiobt[2563]:     asyncio.run(_run())
Aug 25 08:52:29 gateway volumiobt[2563]:   File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 25 08:52:29 gateway volumiobt[2563]:     return runner.run(main)
Aug 25 08:52:29 gateway volumiobt[2563]:            ^^^^^^^^^^^^^^^^
Aug 25 08:52:29 gateway volumiobt[2563]:   File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 25 08:52:29 gateway volumiobt[2563]:     return self._loop.run_until_complete(task)
Aug 25 08:52:29 gateway volumiobt[2563]:            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:29 gateway volumiobt[2563]:   File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 25 08:52:29 gateway volumiobt[2563]:     return future.result()
Aug 25 08:52:29 gateway volumiobt[2563]:            ^^^^^^^^^^^^^^^
Aug 25 08:52:29 gateway volumiobt[2563]:   File "/bin/bt/a2dp-agent", line 175, in _run
Aug 25 08:52:29 gateway volumiobt[2563]:     adapter_path = await find_adapter(bus)
Aug 25 08:52:29 gateway volumiobt[2563]:                    ^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:29 gateway volumiobt[2563]:   File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 25 08:52:29 gateway volumiobt[2563]:     raise Exception("Bluetooth adapter not found")
Aug 25 08:52:29 gateway volumiobt[2563]: Exception: Bluetooth adapter not found
Aug 25 08:52:29 gateway volumiobt[2563]: Traceback (most recent call last):
Aug 25 08:52:29 gateway volumiobt[2563]:   File "/bin/bt/a2dp-agent", line 234, in 
Aug 25 08:52:29 gateway volumiobt[2563]:     main()
Aug 25 08:52:29 gateway volumiobt[2563]:   File "/bin/bt/a2dp-agent", line 225, in main
Aug 25 08:52:29 gateway volumiobt[2563]:     asyncio.run(_run())
Aug 25 08:52:29 gateway volumiobt[2563]:   File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 25 08:52:29 gateway volumiobt[2563]:     return runner.run(main)
Aug 25 08:52:29 gateway volumiobt[2563]:            ^^^^^^^^^^^^^^^^
Aug 25 08:52:29 gateway volumiobt[2563]:   File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 25 08:52:29 gateway volumiobt[2563]:     return self._loop.run_until_complete(task)
Aug 25 08:52:29 gateway volumiobt[2563]:            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:29 gateway volumiobt[2563]:   File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 25 08:52:29 gateway volumiobt[2563]:     return future.result()
Aug 25 08:52:29 gateway volumiobt[2563]:            ^^^^^^^^^^^^^^^
Aug 25 08:52:29 gateway volumiobt[2563]:   File "/bin/bt/a2dp-agent", line 175, in _run
Aug 25 08:52:29 gateway volumiobt[2563]:     adapter_path = await find_adapter(bus)
Aug 25 08:52:29 gateway volumiobt[2563]:                    ^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:29 gateway volumiobt[2563]:   File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 25 08:52:29 gateway volumiobt[2563]:     raise Exception("Bluetooth adapter not found")
Aug 25 08:52:29 gateway volumiobt[2563]: Exception: Bluetooth adapter not found
Aug 25 08:52:29 gateway systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 08:52:29 gateway systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 25 08:52:29 gateway volumio[1261]: info: MRS: Getting audio outputs on start
Aug 25 08:52:29 gateway volumio[1261]: info: MRS: Requesting all other devices output
Aug 25 08:52:29 gateway systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 3.
Aug 25 08:52:29 gateway systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 25 08:52:29 gateway systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 25 08:52:29 gateway volumiobt[2566]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 25 08:52:29 gateway sudo[2567]:  volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 25 08:52:29 gateway sudo[2567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:29 gateway sudo[2567]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:29 gateway sudo[2569]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 25 08:52:29 gateway sudo[2569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:29 gateway sudo[2569]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:29 gateway volumiobt[2571]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 25 08:52:29 gateway volumiobt[2574]: No default controller available
Aug 25 08:52:29 gateway volumio[1261]: info: Executing endpoint metavolumio
Aug 25 08:52:29 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
Aug 25 08:52:30 gateway volumiobt[2577]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 25 08:52:30 gateway volumiobt[2578]: [83B blob data]
Aug 25 08:52:30 gateway volumiobt[2578]: No default controller available
Aug 25 08:52:30 gateway volumiobt[2578]: [bluetoothctl]> pairable on
Aug 25 08:52:30 gateway volumiobt[2578]: No default controller available
Aug 25 08:52:30 gateway volumiobt[2578]: [bluetoothctl]> 
Aug 25 08:52:30 gateway volumiobt[2579]: INFO [BTSTART] Registering Bluetooth agent...
Aug 25 08:52:30 gateway volumiobt[2581]: No agent is registered
Aug 25 08:52:30 gateway volumiobt[2582]: INFO [BTSTART] Agent registered successfully.
Aug 25 08:52:30 gateway volumiobt[2583]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 25 08:52:30 gateway volumiobt[2584]: 2026-08-25 08:52:30 a2dp-agent [INFO] Connecting to system D-Bus
Aug 25 08:52:30 gateway volumiobt[2584]: 2026-08-25 08:52:30 a2dp-agent [INFO] Connected to system D-Bus
Aug 25 08:52:30 gateway volumiobt[2584]: 2026-08-25 08:52:30 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 25 08:52:30 gateway volumiobt[2584]: 2026-08-25 08:52:30 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 25 08:52:30 gateway volumiobt[2584]: Traceback (most recent call last):
Aug 25 08:52:30 gateway volumiobt[2584]:   File "/bin/bt/a2dp-agent", line 225, in main
Aug 25 08:52:30 gateway volumiobt[2584]:     asyncio.run(_run())
Aug 25 08:52:30 gateway volumiobt[2584]:   File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 25 08:52:30 gateway volumiobt[2584]:     return runner.run(main)
Aug 25 08:52:30 gateway volumiobt[2584]:            ^^^^^^^^^^^^^^^^
Aug 25 08:52:30 gateway volumiobt[2584]:   File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 25 08:52:30 gateway volumiobt[2584]:     return self._loop.run_until_complete(task)
Aug 25 08:52:30 gateway volumiobt[2584]:            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:30 gateway volumiobt[2584]:   File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 25 08:52:30 gateway volumiobt[2584]:     return future.result()
Aug 25 08:52:30 gateway volumiobt[2584]:            ^^^^^^^^^^^^^^^
Aug 25 08:52:30 gateway volumiobt[2584]:   File "/bin/bt/a2dp-agent", line 175, in _run
Aug 25 08:52:30 gateway volumiobt[2584]:     adapter_path = await find_adapter(bus)
Aug 25 08:52:30 gateway volumiobt[2584]:                    ^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:30 gateway volumiobt[2584]:   File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 25 08:52:30 gateway volumiobt[2584]:     raise Exception("Bluetooth adapter not found")
Aug 25 08:52:30 gateway volumiobt[2584]: Exception: Bluetooth adapter not found
Aug 25 08:52:30 gateway volumiobt[2584]: Traceback (most recent call last):
Aug 25 08:52:30 gateway volumiobt[2584]:   File "/bin/bt/a2dp-agent", line 234, in 
Aug 25 08:52:30 gateway volumiobt[2584]:     main()
Aug 25 08:52:30 gateway volumiobt[2584]:   File "/bin/bt/a2dp-agent", line 225, in main
Aug 25 08:52:30 gateway volumiobt[2584]:     asyncio.run(_run())
Aug 25 08:52:30 gateway volumiobt[2584]:   File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 25 08:52:30 gateway volumiobt[2584]:     return runner.run(main)
Aug 25 08:52:30 gateway volumiobt[2584]:            ^^^^^^^^^^^^^^^^
Aug 25 08:52:30 gateway volumiobt[2584]:   File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 25 08:52:30 gateway volumiobt[2584]:     return self._loop.run_until_complete(task)
Aug 25 08:52:30 gateway volumiobt[2584]:            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:30 gateway volumiobt[2584]:   File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 25 08:52:30 gateway volumiobt[2584]:     return future.result()
Aug 25 08:52:30 gateway volumiobt[2584]:            ^^^^^^^^^^^^^^^
Aug 25 08:52:30 gateway volumiobt[2584]:   File "/bin/bt/a2dp-agent", line 175, in _run
Aug 25 08:52:30 gateway volumiobt[2584]:     adapter_path = await find_adapter(bus)
Aug 25 08:52:30 gateway volumiobt[2584]:                    ^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:30 gateway volumiobt[2584]:   File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 25 08:52:30 gateway volumiobt[2584]:     raise Exception("Bluetooth adapter not found")
Aug 25 08:52:30 gateway volumiobt[2584]: Exception: Bluetooth adapter not found
Aug 25 08:52:30 gateway systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 08:52:30 gateway systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 25 08:52:30 gateway volumio[1261]: info: TidalConnect service started!
Aug 25 08:52:30 gateway volumio[1261]:  [Metrics]  CommandRouter:  26s 280.07ms 
Aug 25 08:52:30 gateway volumio[1261]: info: CoreCommandRouter::volumiosetStartupVolume
Aug 25 08:52:30 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:30 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:30 gateway volumio[1261]: info: CoreCommandRouter::Close All Modals sent
Aug 25 08:52:30 gateway volumio[1261]: info: CoreCommandRouter::Close All Modals sent
Aug 25 08:52:30 gateway volumio[1261]: info: FusionDsp -  ---- read samplerate, raw: 44100,S32_LE,2,32
Aug 25 08:52:30 gateway volumio[1261]: info: FusionDsp -  ---- read samplerate from file: 44100
Aug 25 08:52:30 gateway volumio[1261]: info: camilladsp stopping service pid 2074...
Aug 25 08:52:30 gateway systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 4.
Aug 25 08:52:30 gateway systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 25 08:52:30 gateway systemd[1]: Started volumiobt.service - Volumio Bluetooth Module.
Aug 25 08:52:30 gateway volumiobt[2600]: INFO [BTSTART] Ensuring Bluetooth directory exists...
Aug 25 08:52:30 gateway sudo[2601]:  volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth
Aug 25 08:52:30 gateway sudo[2601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:30 gateway sudo[2601]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:30 gateway sudo[2603]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth
Aug 25 08:52:30 gateway sudo[2603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:52:30 gateway sudo[2603]: pam_unix(sudo:session): session closed for user root
Aug 25 08:52:30 gateway volumiobt[2605]: INFO [BTSTART] Powering on Bluetooth if needed...
Aug 25 08:52:30 gateway volumio[1261]: grep: /proc/2074/cmdline: binary file matches
Aug 25 08:52:30 gateway volumio[1261]: grep: /proc/2074/cmdline: binary file matches
Aug 25 08:52:30 gateway volumio[1261]: info: camilladsp service terminated, instance 1
Aug 25 08:52:30 gateway volumio[1261]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Aug 25 08:52:30 gateway volumio[1261]: info: camilladsp service started and running in background, instance 1
Aug 25 08:52:30 gateway volumiobt[2609]: No default controller available
Aug 25 08:52:30 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Aug 25 08:52:30 gateway volumio[1261]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Aug 25 08:52:30 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Aug 25 08:52:30 gateway volumio[1261]: info: Received Get System Version
Aug 25 08:52:30 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 25 08:52:30 gateway volumio[1261]: info: Received Get System Info
Aug 25 08:52:30 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 25 08:52:30 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 25 08:52:30 gateway volumio[1261]: info: Discovery: Getting this device information
Aug 25 08:52:30 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:30 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:30 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 25 08:52:31 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Aug 25 08:52:31 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Aug 25 08:52:31 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Aug 25 08:52:31 gateway volumiobt[2623]: INFO [BTSTART] Making Bluetooth discoverable and pairable...
Aug 25 08:52:31 gateway volumiobt[2624]: [83B blob data]
Aug 25 08:52:31 gateway volumiobt[2624]: No default controller available
Aug 25 08:52:31 gateway volumiobt[2624]: [bluetoothctl]> pairable on
Aug 25 08:52:31 gateway volumiobt[2624]: No default controller available
Aug 25 08:52:31 gateway volumiobt[2624]: [bluetoothctl]> 
Aug 25 08:52:31 gateway volumiobt[2625]: INFO [BTSTART] Registering Bluetooth agent...
Aug 25 08:52:31 gateway volumiobt[2627]: No agent is registered
Aug 25 08:52:31 gateway volumiobt[2628]: INFO [BTSTART] Agent registered successfully.
Aug 25 08:52:31 gateway volumiobt[2629]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)...
Aug 25 08:52:32 gateway volumiobt[2630]: 2026-08-25 08:52:32 a2dp-agent [INFO] Connecting to system D-Bus
Aug 25 08:52:32 gateway volumiobt[2630]: 2026-08-25 08:52:32 a2dp-agent [INFO] Connected to system D-Bus
Aug 25 08:52:32 gateway volumiobt[2630]: 2026-08-25 08:52:32 a2dp-agent [ERROR] No Bluetooth adapter found: Bluetooth adapter not found
Aug 25 08:52:32 gateway volumiobt[2630]: 2026-08-25 08:52:32 a2dp-agent [ERROR] Agent crashed: Bluetooth adapter not found
Aug 25 08:52:32 gateway volumiobt[2630]: Traceback (most recent call last):
Aug 25 08:52:32 gateway volumiobt[2630]:   File "/bin/bt/a2dp-agent", line 225, in main
Aug 25 08:52:32 gateway volumiobt[2630]:     asyncio.run(_run())
Aug 25 08:52:32 gateway volumiobt[2630]:   File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 25 08:52:32 gateway volumiobt[2630]:     return runner.run(main)
Aug 25 08:52:32 gateway volumiobt[2630]:            ^^^^^^^^^^^^^^^^
Aug 25 08:52:32 gateway volumiobt[2630]:   File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 25 08:52:32 gateway volumiobt[2630]:     return self._loop.run_until_complete(task)
Aug 25 08:52:32 gateway volumiobt[2630]:            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:32 gateway volumiobt[2630]:   File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 25 08:52:32 gateway volumiobt[2630]:     return future.result()
Aug 25 08:52:32 gateway volumiobt[2630]:            ^^^^^^^^^^^^^^^
Aug 25 08:52:32 gateway volumiobt[2630]:   File "/bin/bt/a2dp-agent", line 175, in _run
Aug 25 08:52:32 gateway volumiobt[2630]:     adapter_path = await find_adapter(bus)
Aug 25 08:52:32 gateway volumiobt[2630]:                    ^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:32 gateway volumiobt[2630]:   File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 25 08:52:32 gateway volumiobt[2630]:     raise Exception("Bluetooth adapter not found")
Aug 25 08:52:32 gateway volumiobt[2630]: Exception: Bluetooth adapter not found
Aug 25 08:52:32 gateway volumiobt[2630]: Traceback (most recent call last):
Aug 25 08:52:32 gateway volumiobt[2630]:   File "/bin/bt/a2dp-agent", line 234, in 
Aug 25 08:52:32 gateway volumiobt[2630]:     main()
Aug 25 08:52:32 gateway volumiobt[2630]:   File "/bin/bt/a2dp-agent", line 225, in main
Aug 25 08:52:32 gateway volumiobt[2630]:     asyncio.run(_run())
Aug 25 08:52:32 gateway volumiobt[2630]:   File "/usr/lib/python3.11/asyncio/runners.py", line 190, in run
Aug 25 08:52:32 gateway volumiobt[2630]:     return runner.run(main)
Aug 25 08:52:32 gateway volumiobt[2630]:            ^^^^^^^^^^^^^^^^
Aug 25 08:52:32 gateway volumiobt[2630]:   File "/usr/lib/python3.11/asyncio/runners.py", line 118, in run
Aug 25 08:52:32 gateway volumiobt[2630]:     return self._loop.run_until_complete(task)
Aug 25 08:52:32 gateway volumiobt[2630]:            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:32 gateway volumiobt[2630]:   File "/usr/lib/python3.11/asyncio/base_events.py", line 653, in run_until_complete
Aug 25 08:52:32 gateway volumiobt[2630]:     return future.result()
Aug 25 08:52:32 gateway volumiobt[2630]:            ^^^^^^^^^^^^^^^
Aug 25 08:52:32 gateway volumiobt[2630]:   File "/bin/bt/a2dp-agent", line 175, in _run
Aug 25 08:52:32 gateway volumiobt[2630]:     adapter_path = await find_adapter(bus)
Aug 25 08:52:32 gateway volumiobt[2630]:                    ^^^^^^^^^^^^^^^^^^^^^^^
Aug 25 08:52:32 gateway volumiobt[2630]:   File "/usr/bin/bt/bluezutils.py", line 73, in find_adapter
Aug 25 08:52:32 gateway volumiobt[2630]:     raise Exception("Bluetooth adapter not found")
Aug 25 08:52:32 gateway volumiobt[2630]: Exception: Bluetooth adapter not found
Aug 25 08:52:32 gateway systemd[1]: volumiobt.service: Main process exited, code=exited, status=1/FAILURE
Aug 25 08:52:32 gateway systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 25 08:52:32 gateway systemd[1]: volumiobt.service: Scheduled restart job, restart counter is at 5.
Aug 25 08:52:32 gateway systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module.
Aug 25 08:52:32 gateway systemd[1]: volumiobt.service: Start request repeated too quickly.
Aug 25 08:52:32 gateway systemd[1]: volumiobt.service: Failed with result 'exit-code'.
Aug 25 08:52:32 gateway systemd[1]: Failed to start volumiobt.service - Volumio Bluetooth Module.
Aug 25 08:52:34 gateway upmpdcli[2633]: writing RSA key
Aug 25 08:52:35 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Aug 25 08:52:35 gateway volumio[1261]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Aug 25 08:52:35 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:52:35 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:52:37 gateway volumio[1261]: info: BOOT COMPLETED
Aug 25 08:52:38 gateway systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Aug 25 08:52:39 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 25 08:52:39 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:52:39 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Aug 25 08:52:39 gateway volumio-remote-updater[808]: Test mode disabled
Aug 25 08:52:39 gateway volumio-remote-updater[808]: Alpha mode enabled
Aug 25 08:52:39 gateway volumio-remote-updater[808]: Alpha legacy test mode disabled
Aug 25 08:52:39 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Aug 25 08:52:39 gateway volumio[1261]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nFix for missing PWM backlight module
\nFix for crash loop on Onboarding plugin
\nFix for USB boot on CM4/CM5 with EMMC
\n
\nNEW ADDITIONS
\n\nBump RPi/CM4/CM5 kernel to 6.12.75
\nBump Vivaldi to 7.5 for Kiosk Mode
\n\n","title":"Update v4.194","updateavailable":true}
Aug 25 08:52:39 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Aug 25 08:52:40 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:40 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:52:40 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Aug 25 08:52:40 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Aug 25 08:52:40 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Aug 25 08:52:40 gateway volumio[1261]: info: CoreCommandRouter::volumioGetBrowseSources
Aug 25 08:52:40 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 25 08:52:54 gateway kernel: logitech-hidpp-device 0003:046D:400E.0006: HID++ 2.0 device connected.
Aug 25 08:53:01 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 25 08:53:01 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Aug 25 08:53:01 gateway volumio[1261]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 25 08:53:07 gateway volumio[1261]: info: [go-librespot] time="2026-08-25T08:53:07-04:00" level=error msg="websocket connection errored" error="failed to get reader: received close frame: status = StatusNoStatusRcvd and reason = \"\""
Aug 25 08:53:07 gateway volumio[1261]: warn: [SpotifyConnect] event stream closed, retrying…
Aug 25 08:53:07 gateway volumio[1261]: warn: [SpotifyConnect] daemon exited (0)
Aug 25 08:53:07 gateway volumio[1261]: info: Disabling plugin spotify-connect
Aug 25 08:53:07 gateway volumio[1261]: info: Preparing to generate the ALSA configuration file
Aug 25 08:53:07 gateway volumio[1261]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Aug 25 08:53:07 gateway volumio[1261]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Aug 25 08:53:07 gateway volumio[1261]: info: Reading ALSA contributions from plugins.
Aug 25 08:53:07 gateway volumio[1261]: info: Asound.conf file unchanged, so no further update is needed
Aug 25 08:53:07 gateway volumio[1261]: info: Output device has changed, restarting MPD
Aug 25 08:53:07 gateway volumio[1261]: info: Output device has changed, restarting Shairport Sync
Aug 25 08:53:07 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:53:07 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:53:07 gateway sudo[2754]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Aug 25 08:53:07 gateway sudo[2754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:53:07 gateway sudo[2754]: pam_unix(sudo:session): session closed for user root
Aug 25 08:53:07 gateway volumio[1261]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 25 08:53:07 gateway volumio[1261]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Aug 25 08:53:07 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:53:07 gateway volumio[1261]: info: QobuzConnect: setDeactiveState invoked
Aug 25 08:53:07 gateway sudo[2757]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Aug 25 08:53:07 gateway sudo[2757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:53:07 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:53:07 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:53:07 gateway vtcs[2515]: [2026-08-25 08:53:07.634] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE
Aug 25 08:53:07 gateway vtcs[2515]: [2026-08-25 08:53:07.638] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer
Aug 25 08:53:07 gateway vtcs[2515]: [2026-08-25 08:53:07.638] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected
Aug 25 08:53:07 gateway volumio[1261]: info: Done.
Aug 25 08:53:07 gateway systemd[1]: Stopping mpd.service - Music Player Daemon...
Aug 25 08:53:07 gateway sudo[2766]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Aug 25 08:53:07 gateway sudo[2766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:53:07 gateway systemd[1]: mpd.service: Deactivated successfully.
Aug 25 08:53:07 gateway systemd[1]: Stopped mpd.service - Music Player Daemon.
Aug 25 08:53:07 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:53:07 gateway sudo[2770]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Aug 25 08:53:07 gateway sudo[2770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:53:07 gateway systemd[1]: mpd.socket: Deactivated successfully.
Aug 25 08:53:07 gateway systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Aug 25 08:53:07 gateway systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Aug 25 08:53:07 gateway volumio[1261]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 25 08:53:07 gateway volumio[1261]: info: MPD Permissions set
Aug 25 08:53:07 gateway sudo[2777]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service
Aug 25 08:53:07 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:53:07 gateway sudo[2777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:53:07 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:53:07 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:53:07 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:53:07 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:53:07 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:53:07 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:53:07 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:53:07 gateway systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Aug 25 08:53:07 gateway systemd[1]: Starting mpd.service - Music Player Daemon...
Aug 25 08:53:07 gateway systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service...
Aug 25 08:53:07 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 25 08:53:07 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Aug 25 08:53:07 gateway systemd[1]: vtcs.service: Deactivated successfully.
Aug 25 08:53:07 gateway systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service.
Aug 25 08:53:07 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 25 08:53:07 gateway sudo[2766]: pam_unix(sudo:session): session closed for user root
Aug 25 08:53:07 gateway sudo[2777]: pam_unix(sudo:session): session closed for user root
Aug 25 08:53:07 gateway sudo[2789]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service
Aug 25 08:53:07 gateway sudo[2789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:53:07 gateway sudo[2770]: pam_unix(sudo:session): session closed for user root
Aug 25 08:53:07 gateway qobuz-connect[2488]: 20260825 08:53:07.761 [2488.2488] INFO SampleApp: Stopping Local configuration server
Aug 25 08:53:07 gateway systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service...
Aug 25 08:53:07 gateway sudo[2781]:     root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Aug 25 08:53:07 gateway sudo[2781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Aug 25 08:53:07 gateway sudo[2781]: pam_unix(sudo:session): session closed for user root
Aug 25 08:53:07 gateway volumio[1261]: info: Starting Shairport Sync
Aug 25 08:53:07 gateway sudo[2795]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 25 08:53:07 gateway sudo[2795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Aug 25 08:53:07 gateway systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Aug 25 08:53:07 gateway systemd[1]: shairport-sync.service: Deactivated successfully.
Aug 25 08:53:07 gateway systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 08:53:07 gateway systemd[1]: shairport-sync.service: Consumed 1.732s CPU time.
Aug 25 08:53:07 gateway systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Aug 25 08:53:07 gateway sudo[2795]: pam_unix(sudo:session): session closed for user root
Aug 25 08:53:07 gateway volumio[1261]: info: Shairport-Sync Started
Aug 25 08:53:08 gateway mpd[2793]: 2026-08-25T08:53:08 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Aug 25 08:53:08 gateway systemd[1]: Started mpd.service - Music Player Daemon.
Aug 25 08:53:08 gateway sudo[2757]: pam_unix(sudo:session): session closed for user root
Aug 25 08:53:08 gateway volumio[1261]: error: updateQueue error: null
Aug 25 08:53:08 gateway qobuz-connect[2488]: 20260825 08:53:08.886 [2488.2488] INFO SampleApp: shat down connection on UNIX socket
Aug 25 08:53:08 gateway volumio[1261]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected
Aug 25 08:53:08 gateway volumio[1261]: info: QobuzConnect: setDeactiveState invoked
Aug 25 08:53:08 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:53:08 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:53:08 gateway systemd[1]: qobuz-connect.service: Deactivated successfully.
Aug 25 08:53:08 gateway systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service.
Aug 25 08:53:08 gateway systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service.
Aug 25 08:53:08 gateway sudo[2789]: pam_unix(sudo:session): session closed for user root
Aug 25 08:53:08 gateway volumio[1261]: info: Executing endpoint qc_getconfig
Aug 25 08:53:08 gateway volumio[1261]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Aug 25 08:53:08 gateway qobuz-connect[2815]: 20260825 08:53:08.939 [2815.2815] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Aug 25 08:53:08 gateway volumio[1261]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object]
Aug 25 08:53:08 gateway volumio[1261]: info: QobuzConnect: QOBUZ Connect daemon connected
Aug 25 08:53:08 gateway qobuz-connect[2815]: 20260825 08:53:08.942 [2815.2815] INFO VolumeManager: [0x559d067c0a20]: Setting new playback volume: 75
Aug 25 08:53:08 gateway qobuz-connect[2815]: 20260825 08:53:08.942 [2815.2815] INFO VolumeManager: [0x559d067c0a20]: Setting new mute state: 0
Aug 25 08:53:08 gateway qobuz-connect[2815]: 20260825 08:53:08.942 [2815.2815] INFO AudioStreamManager: [0x559d067c0580]: Setting new audio download buffer size: 1048576
Aug 25 08:53:08 gateway qobuz-connect[2815]: 20260825 08:53:08.942 [2815.2815] INFO QobuzConnect: [0x559d067c1a30]: Client initialized!
Aug 25 08:53:08 gateway qobuz-connect[2815]: 20260825 08:53:08.942 [2815.2815] INFO SampleApp: Starting Avahi advertising, name: gateway, service name: _qobuz-connect._tcp
Aug 25 08:53:08 gateway qobuz-connect[2815]: 20260825 08:53:08.947 [2815.2815] INFO LocalConfigManager: [0x559d067c0060]: Starting Local Configuration server
Aug 25 08:53:08 gateway qobuz-connect[2815]: 20260825 08:53:08.947 [2815.2815] INFO SampleApp: Starting Local configuration server
Aug 25 08:53:08 gateway qobuz-connect[2815]: 20260825 08:53:08.947 [2815.2815] INFO SampleApp: Connected to UNIX socket client 0x559d06796990
Aug 25 08:53:09 gateway qobuz-connect[2815]: 20260825 08:53:09.089 [2815.2815] INFO SampleApp: Playback volume changed: 75
Aug 25 08:53:09 gateway volumio[1261]: info: CoreCommandRouter::volumioGetState
Aug 25 08:53:09 gateway volumio[1261]: info: CorePlayQueue::getTrack 0
Aug 25 08:53:10 gateway volumio[1261]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 25 08:53:10 gateway volumio[1261]: Error: Unable to resolve or reject the same promise twice
Aug 25 08:53:10 gateway volumio[1261]:     at Promise.resolve (/data/plugins/music_service/spotify-connect/node_modules/kew/kew.js:140:43)
Aug 25 08:53:10 gateway volumio[1261]:     at Timeout._onTimeout (/data/plugins/music_service/spotify-connect/index.js:162:13)
Aug 25 08:53:10 gateway volumio[1261]:     at listOnTimeout (node:internal/timers:573:17)
Aug 25 08:53:10 gateway volumio[1261]:     at process.processTimers (node:internal/timers:514:7)
Aug 25 08:53:10 gateway volumio[1261]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 25 08:53:10 gateway sudo[2884]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-25 08:52'
Aug 25 08:53:10 gateway sudo[2884]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Debian GNU/Linux 12 (bookworm)"
NAME="Debian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026"
VOLUMIO_VERSION="4.119"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"