Sat Apr 4 21:28:43 BST 2026 Description="'volumio doesn'\''t mount usb stick '" # cat /proc/version --------------- Linux version 6.6.62-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #1816 SMP Mon Nov 18 15:30:49 GMT 2024 # cat /etc/os-release --------------- PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86" # ifconfig --------------- eth0: flags=4163 mtu 1500 inet 192.168.0.101 netmask 255.255.255.0 broadcast 192.168.0.255 ether b8:27:eb:89:7c:3f txqueuelen 1000 (Ethernet) RX packets 1264 bytes 642010 (626.9 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 1260 bytes 217754 (212.6 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 lo: flags=73 mtu 65536 inet 127.0.0.1 netmask 255.0.0.0 loop txqueuelen 1000 (Local Loopback) RX packets 605 bytes 310461 (303.1 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 605 bytes 310461 (303.1 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 wlan0: flags=4163 mtu 1500 inet 192.168.211.1 netmask 255.255.255.0 broadcast 192.168.211.255 ether b8:27:eb:dc:29:6a txqueuelen 1000 (Ethernet) RX packets 0 bytes 0 (0.0 B) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 91 bytes 18539 (18.1 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 # sudo iwconfig --------------- lo no wireless extensions. eth0 no wireless extensions. wlan0 IEEE 802.11 Mode:Master Tx-Power=31 dBm Retry short limit:7 RTS thr:off Fragment thr:off Power Management:off # aplay -l --------------- xcb_connection_has_error() returned true **** List of PLAYBACK Hardware Devices **** card 0: b1 [bcm2835 HDMI 1], device 0: bcm2835 HDMI 1 [bcm2835 HDMI 1] Subdevices: 4/4 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 card 0: b1 [bcm2835 HDMI 1], device 1: bcm2835 HDMI 1 [bcm2835 HDMI 1] Subdevices: 1/1 Subdevice #0: subdevice #0 card 1: Headphones [bcm2835 Headphones], device 0: bcm2835 Headphones [bcm2835 Headphones] Subdevices: 4/4 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 card 2: sndrpihifiberry [snd_rpi_hifiberry_dacplus], device 0: HiFiBerry DAC+ Pro HiFi pcm512x-hifi-0 [HiFiBerry DAC+ Pro HiFi pcm512x-hifi-0] Subdevices: 0/1 Subdevice #0: subdevice #0 # lsusb --------------- Bus 001 Device 004: ID 0781:5591 SanDisk Corp. Ultra Flair Bus 001 Device 003: ID 0424:ec00 Standard Microsystems Corp. SMSC9512/9514 Fast Ethernet Adapter Bus 001 Device 002: ID 0424:9514 Standard Microsystems Corp. SMC9514 Hub Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub # lsusb -t --------------- /: Bus 01.Port 1: Dev 1, Class=root_hub, Driver=dwc_otg/1p, 480M |__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/5p, 480M |__ Port 1: Dev 3, If 0, Class=Vendor Specific Class, Driver=smsc95xx, 480M |__ Port 5: Dev 4, If 0, Class=Mass Storage, Driver=usb-storage, 480M # lsusb -v --------------- Couldn't open device, some information will be missing Couldn't open device, some information will be missing Bus 001 Device 004: ID 0781:5591 SanDisk Corp. Ultra Flair Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 2.10 bDeviceClass 0 bDeviceSubClass 0 bDeviceProtocol 0 bMaxPacketSize0 64 idVendor 0x0781 SanDisk Corp. idProduct 0x5591 Ultra Flair bcdDevice 1.00 iManufacturer 1 iProduct 2 iSerial 3 bNumConfigurations 1 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x0020 bNumInterfaces 1 bConfigurationValue 1 iConfiguration 0 bmAttributes 0x80 (Bus Powered) MaxPower 224mA Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 2 bInterfaceClass 8 Mass Storage bInterfaceSubClass 6 SCSI bInterfaceProtocol 80 Bulk-Only iInterface 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 2 Transfer Type Bulk Synch Type None Usage Type Data wMaxPacketSize 0x0200 1x 512 bytes bInterval 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x02 EP 2 OUT bmAttributes 2 Transfer Type Bulk Synch Type None Usage Type Data wMaxPacketSize 0x0200 1x 512 bytes bInterval 0 Bus 001 Device 003: ID 0424:ec00 Standard Microsystems Corp. SMSC9512/9514 Fast Ethernet Adapter Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 2.00 bDeviceClass 255 Vendor Specific Class bDeviceSubClass 0 bDeviceProtocol 1 bMaxPacketSize0 64 idVendor 0x0424 Standard Microsystems Corp. idProduct 0xec00 SMSC9512/9514 Fast Ethernet Adapter bcdDevice 2.00 iManufacturer 0 iProduct 0 iSerial 0 bNumConfigurations 1 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x0027 bNumInterfaces 1 bConfigurationValue 1 iConfiguration 0 bmAttributes 0xe0 Self Powered Remote Wakeup MaxPower 2mA Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 3 bInterfaceClass 255 Vendor Specific Class bInterfaceSubClass 0 bInterfaceProtocol 255 iInterface 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 2 Transfer Type Bulk Synch Type None Usage Type Data wMaxPacketSize 0x0200 1x 512 bytes bInterval 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x02 EP 2 OUT bmAttributes 2 Transfer Type Bulk Synch Type None Usage Type Data wMaxPacketSize 0x0200 1x 512 bytes bInterval 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x83 EP 3 IN bmAttributes 3 Transfer Type InterCouldn't open device, some information will be missing Couldn't open device, some information will be missing rupt Synch Type None Usage Type Data wMaxPacketSize 0x0010 1x 16 bytes bInterval 4 Bus 001 Device 002: ID 0424:9514 Standard Microsystems Corp. SMC9514 Hub Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 2.00 bDeviceClass 9 Hub bDeviceSubClass 0 bDeviceProtocol 2 TT per port bMaxPacketSize0 64 idVendor 0x0424 Standard Microsystems Corp. idProduct 0x9514 SMC9514 Hub bcdDevice 2.00 iManufacturer 0 iProduct 0 iSerial 0 bNumConfigurations 1 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x0029 bNumInterfaces 1 bConfigurationValue 1 iConfiguration 0 bmAttributes 0xe0 Self Powered Remote Wakeup MaxPower 2mA Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 1 bInterfaceClass 9 Hub bInterfaceSubClass 0 bInterfaceProtocol 1 Single TT iInterface 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 3 Transfer Type Interrupt Synch Type None Usage Type Data wMaxPacketSize 0x0001 1x 1 bytes bInterval 12 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 1 bNumEndpoints 1 bInterfaceClass 9 Hub bInterfaceSubClass 0 bInterfaceProtocol 2 TT per port iInterface 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 3 Transfer Type Interrupt Synch Type None Usage Type Data wMaxPacketSize 0x0001 1x 1 bytes bInterval 12 Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 2.00 bDeviceClass 9 Hub bDeviceSubClass 0 bDeviceProtocol 1 Single TT bMaxPacketSize0 64 idVendor 0x1d6b Linux Foundation idProduct 0x0002 2.0 root hub bcdDevice 6.06 iManufacturer 3 iProduct 2 iSerial 1 bNumConfigurations 1 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x0019 bNumInterfaces 1 bConfigurationValue 1 iConfiguration 0 bmAttributes 0xe0 Self Powered Remote Wakeup MaxPower 0mA Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 1 bInterfaceClass 9 Hub bInterfaceSubClass 0 bInterfaceProtocol 0 Full speed (or root) hub iInterface 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 3 Transfer Type Interrupt Synch Type None Usage Type Data wMaxPacketSize 0x0004 1x 4 bytes bInterval 12 # sudo i2cdetect -y 1 --------------- 0 1 2 3 4 5 6 7 8 9 a b c d e f 00: -- -- -- -- -- -- -- -- -- -- -- -- -- 10: -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- 20: -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- 30: -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- 40: -- -- -- -- -- -- -- -- -- -- -- -- -- UU -- -- 50: -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- 60: -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- -- 70: -- -- -- -- -- -- -- -- # sudo i2cdetect -y 10 --------------- Error: Could not open file `/dev/i2c-10' or `/dev/i2c/10': No such file or directory # sudo i2cdetect -y 11 --------------- Error: Could not open file `/dev/i2c-11' or `/dev/i2c/11': No such file or directory # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 7 21:27 ? 00:00:06 /sbin/init splash nodebug root 2 0 0 21:27 ? 00:00:00 [kthreadd] root 3 2 0 21:27 ? 00:00:00 [pool_workqueue_release] root 4 2 0 21:27 ? 00:00:00 [kworker/R-rcu_g] root 5 2 0 21:27 ? 00:00:00 [kworker/R-rcu_p] root 6 2 0 21:27 ? 00:00:00 [kworker/R-slub_] root 7 2 0 21:27 ? 00:00:00 [kworker/R-netns] root 8 2 0 21:27 ? 00:00:00 [kworker/0:0-events] root 9 2 0 21:27 ? 00:00:00 [kworker/0:0H-events_highpri] root 10 2 0 21:27 ? 00:00:00 [kworker/0:1-cgroup_destroy] root 11 2 0 21:27 ? 00:00:00 [kworker/u8:0-ext4-rsv-conversion] root 12 2 0 21:27 ? 00:00:00 [kworker/R-mm_pe] root 13 2 0 21:27 ? 00:00:00 [rcu_tasks_rude_kthread] root 14 2 0 21:27 ? 00:00:00 [rcu_tasks_trace_kthread] root 15 2 0 21:27 ? 00:00:00 [ksoftirqd/0] root 16 2 0 21:27 ? 00:00:00 [rcu_sched] root 17 2 0 21:27 ? 00:00:00 [migration/0] root 18 2 0 21:27 ? 00:00:00 [cpuhp/0] root 19 2 0 21:27 ? 00:00:00 [cpuhp/1] root 20 2 0 21:27 ? 00:00:00 [migration/1] root 21 2 0 21:27 ? 00:00:00 [ksoftirqd/1] root 22 2 0 21:27 ? 00:00:00 [kworker/1:0-events] root 23 2 0 21:27 ? 00:00:00 [kworker/1:0H-events_highpri] root 24 2 0 21:27 ? 00:00:00 [cpuhp/2] root 25 2 0 21:27 ? 00:00:00 [migration/2] root 26 2 0 21:27 ? 00:00:00 [ksoftirqd/2] root 27 2 0 21:27 ? 00:00:00 [kworker/2:0-rcu_gp] root 28 2 0 21:27 ? 00:00:00 [kworker/2:0H-events_highpri] root 29 2 0 21:27 ? 00:00:00 [cpuhp/3] root 30 2 0 21:27 ? 00:00:00 [migration/3] root 31 2 0 21:27 ? 00:00:00 [ksoftirqd/3] root 32 2 0 21:27 ? 00:00:00 [kworker/3:0-events] root 33 2 0 21:27 ? 00:00:00 [kworker/3:0H-events_highpri] root 34 2 0 21:27 ? 00:00:00 [kworker/u9:0-events_unbound] root 35 2 0 21:27 ? 00:00:00 [kworker/u10:0-events_unbound] root 36 2 0 21:27 ? 00:00:00 [kworker/u11:0-events_unbound] root 37 2 0 21:27 ? 00:00:00 [kworker/u12:0-events_unbound] root 38 2 0 21:27 ? 00:00:00 [kdevtmpfs] root 39 2 0 21:27 ? 00:00:00 [kworker/R-inet_] root 40 2 1 21:27 ? 00:00:01 [kworker/u9:1-events_unbound] root 41 2 0 21:27 ? 00:00:00 [kauditd] root 42 2 0 21:27 ? 00:00:00 [khungtaskd] root 43 2 0 21:27 ? 00:00:00 [oom_reaper] root 44 2 0 21:27 ? 00:00:00 [kworker/R-write] root 45 2 0 21:27 ? 00:00:00 [kcompactd0] root 46 2 0 21:27 ? 00:00:00 [kworker/R-kinte] root 47 2 0 21:27 ? 00:00:00 [kworker/R-kbloc] root 48 2 0 21:27 ? 00:00:00 [kworker/R-blkcg] root 49 2 0 21:27 ? 00:00:00 [watchdogd] root 50 2 0 21:27 ? 00:00:00 [kworker/u10:1-loop0] root 51 2 0 21:27 ? 00:00:00 [kworker/1:1-events] root 52 2 0 21:27 ? 00:00:00 [kworker/1:1H-kblockd] root 53 2 0 21:27 ? 00:00:00 [kworker/R-rpcio] root 54 2 0 21:27 ? 00:00:00 [kworker/R-xprti] root 55 2 0 21:27 ? 00:00:00 [kworker/u10:2] root 56 2 0 21:27 ? 00:00:00 [kworker/2:1-cgroup_destroy] root 57 2 0 21:27 ? 00:00:00 [kworker/3:1-cgroup_destroy] root 58 2 0 21:27 ? 00:00:00 [kswapd0] root 59 2 0 21:27 ? 00:00:00 [kworker/R-nfsio] root 60 2 0 21:27 ? 00:00:00 [kworker/1:2-events_freezable_power_] root 61 2 0 21:27 ? 00:00:00 [hwrng] root 62 2 0 21:27 ? 00:00:00 [kworker/R-iscsi] root 63 2 0 21:27 ? 00:00:00 [kworker/R-dwc_o] root 64 2 0 21:27 ? 00:00:00 [kworker/R-DWC N] root 65 2 0 21:27 ? 00:00:00 [vchiq-slot/0] root 66 2 0 21:27 ? 00:00:00 [vchiq-recy/0] root 67 2 0 21:27 ? 00:00:00 [vchiq-sync/0] root 68 2 0 21:27 ? 00:00:00 [kworker/u13:0-hci0] root 69 2 0 21:27 ? 00:00:00 [kworker/u14:0] root 70 2 0 21:27 ? 00:00:00 [kworker/u15:0] root 71 2 0 21:27 ? 00:00:00 [kworker/u16:0] root 72 2 0 21:27 ? 00:00:00 [kworker/u17:0] root 73 2 0 21:27 ? 00:00:00 [kworker/1:3-events] root 97 2 0 21:27 ? 00:00:00 [kworker/0:1H-kblockd] root 98 2 0 21:27 ? 00:00:00 [kworker/1:4-events] root 99 2 0 21:27 ? 00:00:00 [kworker/1:5-events] root 102 2 0 21:27 ? 00:00:00 [kworker/R-mmc_c] root 105 2 0 21:27 ? 00:00:00 [kworker/3:1H-kblockd] root 106 2 0 21:27 ? 00:00:00 [kworker/2:1H-kblockd] root 116 2 0 21:27 ? 00:00:00 [kworker/2:2-mm_percpu_wq] root 117 2 0 21:27 ? 00:00:00 [kworker/3:2-events] root 118 2 0 21:27 ? 00:00:00 [kworker/u12:1-loop0] root 119 2 0 21:27 ? 00:00:00 [kworker/u12:2-loop0] root 120 2 0 21:27 ? 00:00:00 [kworker/u12:3-events_unbound] root 132 2 0 21:27 ? 00:00:00 [kworker/u11:1-loop0] root 133 2 0 21:27 ? 00:00:00 [kworker/2:3-events] root 175 2 0 21:27 ? 00:00:00 [kworker/0:2-mm_percpu_wq] root 176 2 0 21:27 ? 00:00:00 [kworker/u9:2-loop0] root 192 2 0 21:27 ? 00:00:00 [irq/199-usb-001:003:01] root 193 2 0 21:27 ? 00:00:00 [kworker/R-uas] root 203 2 0 21:27 ? 00:00:00 [scsi_eh_0] root 204 2 0 21:27 ? 00:00:00 [kworker/R-scsi_] root 205 2 0 21:27 ? 00:00:00 [usb-storage] root 219 2 0 21:27 ? 00:00:00 [jbd2/mmcblk0p2-8] root 220 2 0 21:27 ? 00:00:00 [kworker/R-ext4-] root 256 2 0 21:27 ? 00:00:00 [kworker/u11:2-writeback] root 264 2 0 21:27 ? 00:00:00 [jbd2/mmcblk0p3-8] root 265 2 0 21:27 ? 00:00:00 [kworker/R-ext4-] root 298 2 0 21:27 ? 00:00:00 [kworker/R-mld] root 299 2 0 21:27 ? 00:00:00 [kworker/R-ipv6_] root 327 1 1 21:27 ? 00:00:01 /lib/systemd/systemd-journald root 340 2 0 21:27 ? 00:00:00 [kworker/3:3-events] root 348 2 0 21:27 ? 00:00:00 [kworker/3:4] root 351 1 1 21:27 ? 00:00:01 /lib/systemd/systemd-udevd root 374 2 0 21:27 ? 00:00:00 [vchiq-keep/0] root 375 2 0 21:27 ? 00:00:00 [SMIO] root 385 2 0 21:27 ? 00:00:00 [kworker/R-mmal-] root 392 2 0 21:27 ? 00:00:00 [kworker/R-mmal-] root 394 2 0 21:27 ? 00:00:00 [kworker/R-mmal-] root 395 2 0 21:27 ? 00:00:00 [kworker/R-mmal-] root 396 2 0 21:27 ? 00:00:00 [kworker/R-mmal-] root 398 2 0 21:27 ? 00:00:00 [kworker/R-mmal-] root 401 2 0 21:27 ? 00:00:00 [kworker/R-mmal-] root 428 2 0 21:27 ? 00:00:00 [kworker/u9:3-loop0] root 445 2 0 21:27 ? 00:00:00 [kworker/u13:1-hci0] root 446 2 0 21:27 ? 00:00:00 [kworker/u13:2-brcmf_wq/mmc1:0001:1] root 456 2 0 21:27 ? 00:00:00 [kworker/R-cfg80] root 492 2 0 21:27 ? 00:00:00 [kworker/R-brcmf] root 494 2 0 21:27 ? 00:00:00 [brcmf_wdog/mmc1:0001:1] root 515 2 0 21:27 ? 00:00:00 [kworker/u13:3-brcmf_wq/mmc1:0001:1] _rpc 591 1 0 21:27 ? 00:00:00 /sbin/rpcbind -f -w root 597 1 2 21:27 ? 00:00:02 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 root 612 2 0 21:27 ? 00:00:00 [kworker/u11:3-events_unbound] nobody 625 1 0 21:27 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* volumio 627 1 0 21:27 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 629 1 0 21:27 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon root 633 1 0 21:27 ? 00:00:00 /bin/bash /bin/volumiologrotate avahi 637 1 0 21:27 ? 00:00:00 avahi-daemon: running [volumio-1.local] root 654 1 0 21:27 ? 00:00:00 /lib/systemd/systemd-logind message+ 657 1 1 21:27 ? 00:00:01 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 667 1 0 21:27 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant avahi 713 637 0 21:27 ? 00:00:00 avahi-daemon: chroot helper root 754 1 1 21:27 ? 00:00:01 /usr/sbin/nmbd --foreground --no-process-group root 773 2 0 21:27 ? 00:00:00 [kworker/u8:1] root 777 2 0 21:27 ? 00:00:00 [kworker/0:3-events] root 783 2 0 21:27 ? 00:00:00 [kworker/u9:4-loop0] root 788 1 0 21:27 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux root 794 1 0 21:27 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap,hostname,scanparam,battery root 801 1 0 21:27 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I ntp 803 1 0 21:27 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 root 811 1 0 21:27 ? 00:00:00 /usr/sbin/sshd -D root 823 2 0 21:27 ? 00:00:00 [kworker/u10:3-loop0] root 831 2 0 21:27 ? 00:00:00 [kworker/R-crypt] root 843 754 0 21:27 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 920 1 0 21:27 ? 00:00:00 /sbin/dhcpcd eth0 root 951 1 0 21:27 ? 00:00:00 /usr/sbin/hostapd -B -P /run/hostapd.pid -B /etc/hostapd/hostapd.conf dnsmasq 954 1 0 21:27 ? 00:00:00 /usr/sbin/dnsmasq -x /run/dnsmasq/dnsmasq.pid -u dnsmasq -7 /etc/dnsmasq.d,.dpkg-dist,.dpkg-old,.dpkg-new --local-service volumio 960 1 65 21:27 ? 00:00:40 /usr/bin/node /volumio/index.js root 979 1 1 21:27 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 982 979 0 21:27 ? 00:00:00 winbindd: domain child [VOLUMIO-1] root 984 1 1 21:27 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 986 984 0 21:27 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 987 984 0 21:27 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 988 979 0 21:27 ? 00:00:00 winbindd: idmap child root 989 979 0 21:27 ? 00:00:00 winbindd: domain child [BUILTIN] root 990 984 0 21:27 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 1014 811 0 21:27 ? 00:00:00 sshd: volumio [priv] volumio 1017 1 0 21:27 ? 00:00:00 /lib/systemd/systemd --user volumio 1021 1017 0 21:27 ? 00:00:00 (sd-pam) volumio 1023 960 0 21:27 ? 00:00:00 /bin/bash /volumio/app/plugins/system_controller/network/network_monitor.sh volumio 1064 1014 0 21:27 ? 00:00:00 sshd: volumio volumio 1069 960 2 21:27 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1076 1069 6 21:27 ? 00:00:03 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1077 1069 7 21:27 ? 00:00:03 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1078 1069 7 21:27 ? 00:00:03 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1245 1 0 21:28 ? 00:00:00 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf volumio 1264 1245 99 21:28 ? 00:00:34 openssl genrsa -out /tmp/upmpdclicache/ohcreds/credkey.pem 4096 volumio 1281 960 10 21:28 ? 00:00:02 /usr/bin/node /myvolumio/plugins/music_service/streaming_services/streamingProxy.js volumio 1387 1 0 21:28 ? 00:00:00 /bin/sh /tmp/sshtunnel.sh volumio 1390 1387 0 21:28 ? 00:00:00 /usr/lib/autossh/autossh -M 0 -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R ZWBfBWa2w4P2HqS4oR4uqDev5fA2-74c5df7bf7f77a44a49186f2f6f4d875.eu3.myvolumio.org:8091:localhost:3000 eu3.myvolumio.org volumio 1394 1390 1 21:28 ? 00:00:00 /usr/bin/ssh -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R ZWBfBWa2w4P2HqS4oR4uqDev5fA2-74c5df7bf7f77a44a49186f2f6f4d875.eu3.myvolumio.org:8091:localhost:3000 eu3.myvolumio.org mpd 1425 1 26 21:28 ? 00:00:02 /usr/bin/mpd --no-daemon volumio 1429 1 2 21:28 ? 00:00:00 /usr/local/bin/qobuz-connect-daemon -m Volumio -n Volumio-1 -s 000000006f897c3f -o volumio -p 8001 root 1453 1 16 21:28 ? 00:00:01 /usr/bin/volumio5-onboarding volumio 1454 1023 0 21:28 ? 00:00:00 sleep 10 shairpo+ 1467 1 1 21:28 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf volumio 1475 1 0 21:28 ? 00:00:00 /bin/sh /bin/bt/btstart.sh start volumio 1492 1 0 21:28 ? 00:00:00 /bin/sh -c /usr/bin/vtcs volumio 1507 1492 9 21:28 ? 00:00:00 /usr/bin/vtcs root 1533 633 0 21:28 ? 00:00:00 sleep 60 volumio 1539 1 3 21:28 ? 00:00:00 /usr/bin/pulseaudio --high-priority=true rtkit 1540 1 1 21:28 ? 00:00:00 /usr/lib/rtkit/rtkit-daemon root 1552 1 5 21:28 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug root 1569 2 0 21:28 ? 00:00:00 [krfcommd] volumio 1570 1475 19 21:28 ? 00:00:01 /usr/bin/python /bin/bt/simple-agent -c NoInputNoOutput volumio 1571 960 0 21:28 ? 00:00:00 /bin/sh -c /usr/bin/aplay -D volumio /volumio/app/startup.wav volumio 1572 1571 2 21:28 ? 00:00:00 /usr/bin/aplay -D volumio /volumio/app/startup.wav volumio 1573 960 1 21:28 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'volumio doesn'\''t mount usb stick ' volumio 1574 1573 73 21:28 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js volumio doesn't mount usb stick volumio 1611 1574 0 21:28 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 1612 1611 0 21:28 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on udev 389M 0 389M 0% /dev tmpfs 87M 9.0M 79M 11% /run /dev/mmcblk0p2 2.5G 1.2G 1.2G 50% /imgpart /dev/loop0 498M 498M 0 100% /static overlay 12G 415M 11G 4% / tmpfs 435M 0 435M 0% /dev/shm tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 435M 0 435M 0% /sys/fs/cgroup tmpfs 435M 0 435M 0% /var/spool/cups tmpfs 20M 28K 20M 1% /var/log tmpfs 435M 84K 435M 1% /tmp tmpfs 435M 0 435M 0% /var/spool/cups/tmp /dev/mmcblk0p1 92M 82M 9.8M 90% /boot tmpfs 87M 0 87M 0% /run/user/1000 # mount --------------- sysfs on /sys type sysfs (rw,nosuid,nodev,noexec,relatime) proc on /proc type proc (rw,relatime) udev on /dev type devtmpfs (rw,nosuid,relatime,size=397972k,nr_inodes=99493,mode=755) devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000) tmpfs on /run type tmpfs (rw,nosuid,nodev,noexec,relatime,size=89064k,mode=755) /dev/mmcblk0p2 on /imgpart type ext4 (rw,relatime) /dev/loop0 on /static type squashfs (ro,relatime,errors=continue) overlay on / type overlay (rw,relatime,lowerdir=/mnt/static,upperdir=/mnt/ext/dyn,workdir=/mnt/ext/work) securityfs on /sys/kernel/security type securityfs (rw,nosuid,nodev,noexec,relatime) tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev,noexec) tmpfs on /run/lock type tmpfs (rw,nosuid,nodev,noexec,relatime,size=5120k) tmpfs on /sys/fs/cgroup type tmpfs (ro,nosuid,nodev,noexec,mode=755) cgroup2 on /sys/fs/cgroup/unified type cgroup2 (rw,nosuid,nodev,noexec,relatime,nsdelegate) cgroup on /sys/fs/cgroup/systemd type cgroup (rw,nosuid,nodev,noexec,relatime,xattr,name=systemd) pstore on /sys/fs/pstore type pstore (rw,nosuid,nodev,noexec,relatime) bpf on /sys/fs/bpf type bpf (rw,nosuid,nodev,noexec,relatime,mode=700) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) cgroup on /sys/fs/cgroup/net_cls,net_prio type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls,net_prio) cgroup on /sys/fs/cgroup/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=25,pgrp=1,timeout=0,minproto=5,maxproto=5,direct) configfs on /sys/kernel/config type configfs (rw,relatime) debugfs on /sys/kernel/debug type debugfs (rw,relatime) fusectl on /sys/fs/fuse/connections type fusectl (rw,relatime) sunrpc on /run/rpc_pipefs type rpc_pipefs (rw,relatime) mqueue on /dev/mqueue type mqueue (rw,relatime) tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755) tmpfs on /var/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4) tmpfs on /tmp type tmpfs (rw,noatime,mode=755) tmpfs on /var/spool/cups/tmp type tmpfs (rw,noatime,mode=755) /dev/mmcblk0p1 on /boot type vfat (rw,nosuid,nodev,noexec,relatime,fmask=0111,dmask=0000,allow_utime=0022,codepage=437,iocharset=ascii,shortname=mixed,utf8,errors=remount-ro,user) tmpfs on /run/user/1000 type tmpfs (rw,nosuid,nodev,relatime,size=89060k,mode=700,uid=1000,gid=1000) # netstat -natp --------------- (Not all processes could be identified, non-owned process info will not be shown, you would have to be root to see it all.) Active Internet connections (servers and established) Proto Recv-Q Send-Q Local Address Foreign Address State PID/Program name tcp 0 0 0.0.0.0:4713 0.0.0.0:* LISTEN 1539/pulseaudio tcp 0 0 0.0.0.0:111 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:53 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:49152 0.0.0.0:* LISTEN 1245/upmpdcli tcp 0 0 0.0.0.0:22 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:139 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:445 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:5000 0.0.0.0:* LISTEN - tcp 0 0 192.168.0.101:52240 172.67.141.32:443 ESTABLISHED - tcp 0 0 127.0.0.1:56788 127.0.0.1:6599 TIME_WAIT - tcp 0 0 127.0.0.1:56816 127.0.0.1:6599 ESTABLISHED 1245/upmpdcli tcp 0 0 192.168.0.101:44714 34.120.206.254:443 ESTABLISHED 960/node tcp 0 0 127.0.0.1:39742 127.0.0.1:3000 ESTABLISHED 627/volumio-remote- tcp 0 0 127.0.0.1:40970 127.0.0.1:3000 ESTABLISHED - tcp 0 0 192.168.0.101:51520 149.210.234.235:80 TIME_WAIT - tcp 0 0 127.0.0.1:41080 127.0.0.1:3000 TIME_WAIT - tcp 0 0 192.168.0.101:32834 84.247.14.230:80 ESTABLISHED 960/node tcp 0 0 127.0.0.1:56802 127.0.0.1:6599 ESTABLISHED 1245/upmpdcli tcp 0 0 127.0.0.1:39714 127.0.0.1:3000 ESTABLISHED 960/node tcp 0 0 127.0.0.1:40992 127.0.0.1:3000 ESTABLISHED 960/node tcp 0 0 192.168.0.101:43988 34.120.160.131:443 ESTABLISHED - tcp 0 0 127.0.0.1:41064 127.0.0.1:3000 TIME_WAIT - tcp 0 0 127.0.0.1:41016 127.0.0.1:3000 ESTABLISHED 960/node tcp 0 0 192.168.0.101:37352 136.144.190.104:80 TIME_WAIT - tcp 0 0 192.168.0.101:22 192.168.0.243:58608 ESTABLISHED - tcp 0 0 127.0.0.1:40960 127.0.0.1:3000 TIME_WAIT - tcp 0 0 192.168.0.101:42340 167.71.136.233:2222 ESTABLISHED 1394/ssh tcp 0 0 192.168.0.101:44568 149.210.234.235:80 TIME_WAIT - tcp 0 0 192.168.0.101:44490 192.178.223.95:443 ESTABLISHED - tcp 0 0 192.168.0.101:51510 149.210.234.235:80 TIME_WAIT - tcp6 0 0 :::4713 :::* LISTEN 1539/pulseaudio tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::53 :::* LISTEN - tcp6 0 0 :::22 :::* LISTEN - tcp6 0 0 :::7331 :::* LISTEN - tcp6 0 0 :::3245 :::* LISTEN 1281/node tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::8001 :::* LISTEN - tcp6 0 0 :::2019 :::* LISTEN 1507/vtcs tcp6 0 0 :::6599 :::* LISTEN 960/node tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 960/node tcp6 0 0 :::3001 :::* LISTEN 1069/node tcp6 0 0 :::3005 :::* LISTEN 960/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 127.0.0.1:6600 127.0.0.1:46686 TIME_WAIT - tcp6 0 0 192.168.0.101:3000 192.168.0.243:55302 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:41010 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:41000 TIME_WAIT - tcp6 0 0 127.0.0.1:6599 127.0.0.1:56816 ESTABLISHED 960/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:41028 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:41016 ESTABLISHED 960/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:39742 ESTABLISHED 960/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:40970 ESTABLISHED 960/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:40992 ESTABLISHED 960/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:56802 ESTABLISHED 960/node tcp6 0 0 192.168.0.101:3000 192.168.0.243:38494 TIME_WAIT - tcp6 0 0 192.168.0.101:3000 192.168.0.243:45614 TIME_WAIT - tcp6 0 0 192.168.0.101:3000 192.168.0.243:45620 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:39720 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:41048 TIME_WAIT - tcp6 0 0 192.168.0.101:3000 192.168.0.243:55306 TIME_WAIT - tcp6 0 0 127.0.0.1:6600 127.0.0.1:46714 TIME_WAIT - tcp6 0 0 127.0.0.1:6600 127.0.0.1:46702 TIME_WAIT - tcp6 0 0 192.168.0.101:3000 192.168.0.243:39182 FIN_WAIT2 - tcp6 0 0 127.0.0.1:3000 127.0.0.1:41030 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:40976 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:41046 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:39714 ESTABLISHED 960/node tcp6 0 0 192.168.0.101:3000 192.168.0.243:39196 FIN_WAIT2 - tcp6 0 0 127.0.0.1:3000 127.0.0.1:39728 TIME_WAIT - tcp6 0 0 192.168.0.101:3000 192.168.0.243:45694 ESTABLISHED 960/node tcp6 0 0 192.168.0.101:3000 192.168.0.243:45598 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:39756 TIME_WAIT - tcp6 0 0 192.168.0.101:3000 192.168.0.243:55310 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:39702 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:40968 TIME_WAIT - # cat /boot/config.txt --------------- ### DO NOT EDIT THIS FILE ### ### APPLY CUSTOM PARAMETERS TO userconfig.txt ### initramfs volumio.initrd gpu_mem=128 gpu_mem_256=32 gpu_mem_512=32 gpu_mem_1024=128 max_usb_current=1 [pi5] usb_max_current_enable=1 [all] include volumioconfig.txt include userconfig.txt #### Volumio i2s setting below: do not alter #### dtoverlay=hifiberry-dacplus # cat /boot/userconfig.txt --------------- # Add your custom config.txt options to this file, which will be preserved during updates # cat /etc/asound.conf --------------- pcm.!default { type empty slave.pcm "volumio" } pcm.volumio { type empty slave.pcm "volumioMultiRoomServer" } # This PCM switches output to the ALSA target named by the file /tmp/multiroom/server/switch.target pcm.volumioMultiRoomServer { type volumioswitch target_file "/tmp/multiroom/server/switch.target" control_fifo "/tmp/multiroom/server/switch.fifo" rate_min 44100 rate_max 352800 } # This PCM switches output to the ALSA target named by the file /tmp/multiroom/client/switch.target pcm.volumioMultiRoomClient { type volumioswitch target_file "/tmp/multiroom/client/switch.target" control_fifo "/tmp/multiroom/client/switch.fifo" } # This PCM performs any necessary rate/format/channel conversion before sending on to the Server FIFO pcm.volumioMultiRoom { type plug slave { pcm "volumioMultiRoomFIFO" rate 48000 format "S16_LE" channels 2 } rate_converter "speexrate_medium" } # Write to the FIFO watched by the Server pcm.volumioMultiRoomFIFO { type volumiofifo fifo "/tmp/multiroom/server/fifo" clear_on_drop false lead_in_frames 16384 } # This pcm is used by pcm.volumioMultiRoomServer and pcm.volumioMultiRoomClient to play audio on the local output pcm.volumioLocalPlayback { type empty slave.pcm "postMultiRoom" } # This pcm is used by pcm.volumioMultiRoomClient to discard audio and allow for faster switching pcm.volumioDiscard { type null } pcm.postMultiRoom { type empty slave.pcm "volumioOutput" } # There is always a plug before the hardware to be safe pcm.volumioOutput { type plug slave.pcm "volumioHw" } pcm.volumioHw { type hw card "sndrpihifiberry" } # cat /var/log/mpd.log --------------- Apr 04 21:28 : zeroconf: No global port, disabling zeroconf Apr 04 21:28 : exception: No such directory Apr 04 21:28 : zeroconf: No global port, disabling zeroconf # cat /tmp/camilladsp.log --------------- cat: /tmp/camilladsp.log: No such file or directory # cat /tmp/cec-client.log --------------- cat: /tmp/cec-client.log: No such file or directory # volumio plugin list --------------- This command will list installed plugins on your device ----------------------- Installed Plugins: [ { prettyName: 'Mixcloud', name: 'mixcloud', category: 'music_service', version: '1.0.1', icon: 'fa-mixcloud', isManuallyInstalled: false, enabled: true, active: true } ] ------------------------------------------ # cat /data/configuration/plugins.json --------------- { "music_service": { "airplay_emulation": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "inputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpd": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "webradio": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "example_plugin": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "last_100": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp_browser": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "streaming_services": { "status": { "type": "string", "value": "STARTED" } }, "cd_controller": { "status": { "type": "string", "value": "STARTED" } }, "smart_inputs": { "status": { "type": "string", "value": "STARTED" } }, "hi_res_audio": { "status": { "type": "string", "value": "STARTED" } }, "tidal": { "status": { "type": "string", "value": "STARTED" } }, "qobuz": { "status": { "type": "string", "value": "STARTED" } }, "tidalconnect": { "status": { "type": "string", "value": "STARTED" } }, "qobuzconnect": { "status": { "type": "string", "value": "STARTED" } }, "mixcloud": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "audio_interface": { "outputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "alsa_controller": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "bluetooth": { "status": { "type": "string", "value": "STARTED" } }, "multiroom": { "status": { "type": "string", "value": "STARTED" } } }, "miscellanea": { "alarm-clock": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "albumart": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "appearance": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "wizard": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "my_music": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "metavolumio": { "status": { "type": "string", "value": "STARTED" } }, "manifestui": { "status": { "type": "string", "value": "STARTED" } } }, "user_interface": { "websocket": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpdemulation": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STARTED" } }, "rest_api": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "system_controller": { "updater_comm": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "network": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "networkfs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "services": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "system": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "i2s_dacs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volumiodiscovery": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volumio_command_line_client": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "my_volumio": { "status": { "type": "string", "value": "STARTED" } }, "volumio5onboarding": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } } } # cat /etc/mpd.conf --------------- # Volumio MPD Configuration File # Files and directories ####################################################### music_directory "/var/lib/mpd/music" playlist_directory "/var/lib/mpd/playlists" db_file "/var/lib/mpd/tag_cache" log_file "/var/log/mpd.log" #pid_file "/var/run/mpd/pid" #state_file "/var/lib/mpd/state" #sticker_file "/var/lib/mpd/sticker.sql" ############################################################################### # General music daemon options ################################################ user "mpd" group "audio" bind_to_address "any" #port "6600" log_level "default" #save_absolute_paths_in_playlists "no" #metadata_to_use "artist,album,title,track,name,genre,date,composer,performer,disc" auto_update "no" #auto_update_depth "3" ############################################################################### # Symbolic link behavior ###################################################### follow_outside_symlinks "yes" follow_inside_symlinks "yes" ############################################################################### # Input ####################################################################### # input { plugin "curl" # proxy "proxy.isp.com:8080" # proxy_user "user" # proxy_password "password" } ############################################################################### # Decoder ################################################################ decoder { plugin "faad" enabled "no" } ############################################################################### # Audio Output ################################################################ resampler { plugin "soxr" quality "high" threads "1" } audio_output { type "alsa" name "alsa" device "volumio" dop "no" format "*:*:2" } audio_output { type "fifo" enabled "no" name "multiroom" path "/tmp/snapfifo" format "44100:16:2" } #replaygain "album" #replaygain_preamp "0" volume_normalization "no" ############################################################################### # MPD Internal Buffering ###################################################### audio_buffer_size "2048" ############################################################################### # Resource Limitations ######################################################## #connection_timeout "60" max_connections "20" max_playlist_length "81920" max_command_list_size "81920" max_output_buffer_size "81920" ############################################################################### # Character Encoding ########################################################## filesystem_charset "UTF-8" ############################################################################### # volumio endpointstest --------------- TESTING REMOTE ENDPOINTS https://google.com, 1032 ms: OK https://www.googleapis.com, 855 ms: OK https://securetoken.googleapis.com, 863 ms: OK https://myvolumio.firebaseio.com, 956 ms: OK https://functions.volumio.cloud, 865 ms: OK https://oauth-performer.dfs.volumio.org, 813 ms: OK https://browsing-performer.dfs.volumio.org, 804 ms: OK http://cddb.volumio.org, 1420 ms: OK https://functions.volumio.cloud, 844 ms: OK http://pushupdates.volumio.org, 593 ms: OK http://plugins.volumio.org, 1255 ms: OK https://database.volumio.cloud, 886 ms: OK https://radio-directory.firebaseapp.com, 870 ms: OK ---------- REMOTE ENDPOINTS TEST OK, all Endpoints are reachable ---------- # cat /data/updatesHistoryFile --------------- --- Thu Jun 19 2025 11:14:32 GMT+0000 (Coordinated Universal Time) {"systemversion":"3.816","builddate":"Tue 10 Jun 2025 04:52:53 PM CEST","variant":"volumio","hardware":"pi","os":"10"} ------ Thu Sep 18 2025 04:49:11 GMT+0100 (British Summer Time) {"systemversion":"3.832","builddate":"Sat 26 Jul 2025 10:25:36 AM CEST","variant":"volumio","hardware":"pi","os":"10"} ------ Fri Nov 28 2025 17:30:09 GMT+0000 (Greenwich Mean Time) {"systemversion":"3.874","builddate":"Wed 12 Nov 2025 06:58:02 PM CET","variant":"volumio","hardware":"pi","os":"10"} ------ Fri Dec 19 2025 05:28:16 GMT+0000 (Greenwich Mean Time) {"systemversion":"3.886","builddate":"Thu 11 Dec 2025 05:43:24 PM CET","variant":"volumio","hardware":"pi","os":"10"} ------ Wed Feb 11 2026 05:25:06 GMT+0000 (Greenwich Mean Time) {"systemversion":"3.908","builddate":"Thu 05 Feb 2026 01:46:18 PM CET","variant":"volumio","hardware":"pi","os":"10"} ------ Thu Mar 05 2026 03:15:54 GMT+0000 (Greenwich Mean Time) {"systemversion":"3.912","builddate":"Fri 27 Feb 2026 10:59:40 AM CET","variant":"volumio","hardware":"pi","os":"10"} --- # sudo journalctl -p 7 --------------- -- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Sat 2026-04-04 21:28:53 BST. -- Feb 14 10:11:59 volumio-1 kernel: Booting Linux on physical CPU 0x0 Feb 14 10:11:59 volumio-1 kernel: Linux version 6.6.62-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #1816 SMP Mon Nov 18 15:30:49 GMT 2024 Feb 14 10:11:59 volumio-1 kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d Feb 14 10:11:59 volumio-1 kernel: CPU: div instructions available: patching division code Feb 14 10:11:59 volumio-1 kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Feb 14 10:11:59 volumio-1 kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2 Feb 14 10:11:59 volumio-1 kernel: random: crng init done Feb 14 10:11:59 volumio-1 kernel: Memory policy: Data cache writealloc Feb 14 10:11:59 volumio-1 kernel: Reserved memory: created CMA memory pool at 0x34000000, size 64 MiB Feb 14 10:11:59 volumio-1 kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Feb 14 10:11:59 volumio-1 kernel: OF: reserved mem: 0x34000000..0x37ffffff (65536 KiB) map reusable linux,cma Feb 14 10:11:59 volumio-1 kernel: Zone ranges: Feb 14 10:11:59 volumio-1 kernel: DMA [mem 0x0000000000000000-0x0000000037ffffff] Feb 14 10:11:59 volumio-1 kernel: Normal empty Feb 14 10:11:59 volumio-1 kernel: Movable zone start for each node Feb 14 10:11:59 volumio-1 kernel: Early memory node ranges Feb 14 10:11:59 volumio-1 kernel: node 0: [mem 0x0000000000000000-0x0000000037ffffff] Feb 14 10:11:59 volumio-1 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x0000000037ffffff] Feb 14 10:11:59 volumio-1 kernel: percpu: Embedded 18 pages/cpu s41812 r8192 d23724 u73728 Feb 14 10:11:59 volumio-1 kernel: pcpu-alloc: s41812 r8192 d23724 u73728 alloc=18*4096 Feb 14 10:11:59 volumio-1 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 14 10:11:59 volumio-1 kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=af3faec2-9654-4fec-8791-d5638a5837ad imgfile=/volumio_current.sqsh bootpart=UUID=2E0A-AE55 datapart=UUID=7f14baaf-9650-49e5-a9b0-31d23431a224 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Feb 14 10:11:59 volumio-1 kernel: cgroup: Disabling memory control group subsystem Feb 14 10:11:59 volumio-1 kernel: Unknown kernel command line parameters "splash nodebug imgpart=UUID=af3faec2-9654-4fec-8791-d5638a5837ad imgfile=/volumio_current.sqsh bootpart=UUID=2E0A-AE55 datapart=UUID=7f14baaf-9650-49e5-a9b0-31d23431a224 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe bootdelay=7 use_kmsg=no", will be passed to user space. Feb 14 10:11:59 volumio-1 kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Feb 14 10:11:59 volumio-1 kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 14 10:11:59 volumio-1 kernel: Built 1 zonelists, mobility grouping on. Total pages: 227360 Feb 14 10:11:59 volumio-1 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 14 10:11:59 volumio-1 kernel: Memory: 795944K/917504K available (11264K kernel code, 1476K rwdata, 3260K rodata, 1024K init, 606K bss, 56024K reserved, 65536K cma-reserved) Feb 14 10:11:59 volumio-1 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 14 10:11:59 volumio-1 kernel: ftrace: allocating 36457 entries in 107 pages Feb 14 10:11:59 volumio-1 kernel: ftrace: allocated 107 pages with 5 groups Feb 14 10:11:59 volumio-1 kernel: trace event string verifier disabled Feb 14 10:11:59 volumio-1 kernel: rcu: Hierarchical RCU implementation. Feb 14 10:11:59 volumio-1 kernel: Rude variant of Tasks RCU enabled. Feb 14 10:11:59 volumio-1 kernel: Tracing variant of Tasks RCU enabled. Feb 14 10:11:59 volumio-1 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Feb 14 10:11:59 volumio-1 kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Feb 14 10:11:59 volumio-1 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Feb 14 10:11:59 volumio-1 kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Feb 14 10:11:59 volumio-1 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Feb 14 10:11:59 volumio-1 kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Feb 14 10:11:59 volumio-1 kernel: Switching to timer-based delay loop, resolution 52ns Feb 14 10:11:59 volumio-1 kernel: Console: colour dummy device 80x30 Feb 14 10:11:59 volumio-1 kernel: printk: console [tty1] enabled Feb 14 10:11:59 volumio-1 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Feb 14 10:11:59 volumio-1 kernel: CPU: Testing write buffer coherency: ok Feb 14 10:11:59 volumio-1 kernel: pid_max: default: 32768 minimum: 301 Feb 14 10:11:59 volumio-1 kernel: LSM: initializing lsm=capability,integrity Feb 14 10:11:59 volumio-1 kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 10:11:59 volumio-1 kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 10:11:59 volumio-1 kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Feb 14 10:11:59 volumio-1 kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Feb 14 10:11:59 volumio-1 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Feb 14 10:11:59 volumio-1 kernel: Setting up static identity map for 0x100000 - 0x10003c Feb 14 10:11:59 volumio-1 kernel: rcu: Hierarchical SRCU implementation. Feb 14 10:11:59 volumio-1 kernel: rcu: Max phase no-delay instances is 1000. Feb 14 10:11:59 volumio-1 kernel: smp: Bringing up secondary CPUs ... Feb 14 10:11:59 volumio-1 kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Feb 14 10:11:59 volumio-1 kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Feb 14 10:11:59 volumio-1 kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Feb 14 10:11:59 volumio-1 kernel: smp: Brought up 1 node, 4 CPUs Feb 14 10:11:59 volumio-1 kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). Feb 14 10:11:59 volumio-1 kernel: CPU: All CPU(s) started in HYP mode. Feb 14 10:11:59 volumio-1 kernel: CPU: Virtualization extensions available. Feb 14 10:11:59 volumio-1 kernel: devtmpfs: initialized Feb 14 10:11:59 volumio-1 kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 Feb 14 10:11:59 volumio-1 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Feb 14 10:11:59 volumio-1 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Feb 14 10:11:59 volumio-1 kernel: pinctrl core: initialized pinctrl subsystem Feb 14 10:11:59 volumio-1 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Feb 14 10:11:59 volumio-1 kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Feb 14 10:11:59 volumio-1 kernel: audit: initializing netlink subsys (disabled) Feb 14 10:11:59 volumio-1 kernel: audit: type=2000 audit(0.010:1): state=initialized audit_enabled=0 res=1 Feb 14 10:11:59 volumio-1 kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 14 10:11:59 volumio-1 kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Feb 14 10:11:59 volumio-1 kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Feb 14 10:11:59 volumio-1 kernel: Serial: AMBA PL011 UART driver Feb 14 10:11:59 volumio-1 kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled Feb 14 10:11:59 volumio-1 kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2024-11-11T15:50:32, variant start Feb 14 10:11:59 volumio-1 kernel: raspberrypi-firmware soc:firmware: Firmware hash is 903570ba72a9e117f92e5499de439f59dd96e417 Feb 14 10:11:59 volumio-1 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Feb 14 10:11:59 volumio-1 kernel: bcm2835-dma 3f007000.dma-controller: DMA legacy API manager, dmachans=0x1 Feb 14 10:11:59 volumio-1 kernel: SCSI subsystem initialized Feb 14 10:11:59 volumio-1 kernel: usbcore: registered new interface driver usbfs Feb 14 10:11:59 volumio-1 kernel: usbcore: registered new interface driver hub Feb 14 10:11:59 volumio-1 kernel: usbcore: registered new device driver usb Feb 14 10:11:59 volumio-1 kernel: pps_core: LinuxPPS API ver. 1 registered Feb 14 10:11:59 volumio-1 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Feb 14 10:11:59 volumio-1 kernel: PTP clock support registered Feb 14 10:11:59 volumio-1 kernel: clocksource: Switched to clocksource arch_sys_counter Feb 14 10:11:59 volumio-1 kernel: VFS: Disk quotas dquot_6.6.0 Feb 14 10:11:59 volumio-1 kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Feb 14 10:11:59 volumio-1 kernel: FS-Cache: Loaded Feb 14 10:11:59 volumio-1 kernel: CacheFiles: Loaded Feb 14 10:11:59 volumio-1 kernel: NET: Registered PF_INET protocol family Feb 14 10:11:59 volumio-1 kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Feb 14 10:11:59 volumio-1 kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) Feb 14 10:11:59 volumio-1 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 14 10:11:59 volumio-1 kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Feb 14 10:11:59 volumio-1 kernel: TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear) Feb 14 10:11:59 volumio-1 kernel: TCP: Hash tables configured (established 8192 bind 8192) Feb 14 10:11:59 volumio-1 kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 10:11:59 volumio-1 kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 10:11:59 volumio-1 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Feb 14 10:11:59 volumio-1 kernel: RPC: Registered named UNIX socket transport module. Feb 14 10:11:59 volumio-1 kernel: RPC: Registered udp transport module. Feb 14 10:11:59 volumio-1 kernel: RPC: Registered tcp transport module. Feb 14 10:11:59 volumio-1 kernel: RPC: Registered tcp-with-tls transport module. Feb 14 10:11:59 volumio-1 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 14 10:11:59 volumio-1 kernel: Trying to unpack rootfs image as initramfs... Feb 14 10:11:59 volumio-1 kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Feb 14 10:11:59 volumio-1 kernel: Freeing initrd memory: 28132K Feb 14 10:11:59 volumio-1 kernel: Initialise system trusted keyrings Feb 14 10:11:59 volumio-1 kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 Feb 14 10:11:59 volumio-1 kernel: zbud: loaded Feb 14 10:11:59 volumio-1 kernel: NFS: Registering the id_resolver key type Feb 14 10:11:59 volumio-1 kernel: Key type id_resolver registered Feb 14 10:11:59 volumio-1 kernel: Key type id_legacy registered Feb 14 10:11:59 volumio-1 kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Feb 14 10:11:59 volumio-1 kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Feb 14 10:11:59 volumio-1 kernel: Key type asymmetric registered Feb 14 10:11:59 volumio-1 kernel: Asymmetric key parser 'x509' registered Feb 14 10:11:59 volumio-1 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Feb 14 10:11:59 volumio-1 kernel: io scheduler mq-deadline registered Feb 14 10:11:59 volumio-1 kernel: io scheduler kyber registered Feb 14 10:11:59 volumio-1 kernel: io scheduler bfq registered Feb 14 10:11:59 volumio-1 kernel: pinctrl-bcm2835 3f200000.gpio: GPIO_OUT persistence: yes Feb 14 10:11:59 volumio-1 kernel: bcm2708_fb soc:fb: FB found 1 display(s) Feb 14 10:11:59 volumio-1 kernel: Console: switching to colour frame buffer device 80x30 Feb 14 10:11:59 volumio-1 kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Feb 14 10:11:59 volumio-1 kernel: bcm2835-rng 3f104000.rng: hwrng registered Feb 14 10:11:59 volumio-1 kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Feb 14 10:11:59 volumio-1 kernel: brd: module loaded Feb 14 10:11:59 volumio-1 kernel: loop: module loaded Feb 14 10:11:59 volumio-1 kernel: Loading iSCSI transport class v2.0-870. Feb 14 10:11:59 volumio-1 kernel: usbcore: registered new interface driver lan78xx Feb 14 10:11:59 volumio-1 kernel: usbcore: registered new interface driver smsc95xx Feb 14 10:11:59 volumio-1 kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Feb 14 10:11:59 volumio-1 kernel: Core Release: 2.80a Feb 14 10:11:59 volumio-1 kernel: Setting default values for core params Feb 14 10:11:59 volumio-1 kernel: Finished setting default values for core params Feb 14 10:11:59 volumio-1 kernel: Using Buffer DMA mode Feb 14 10:11:59 volumio-1 kernel: Periodic Transfer Interrupt Enhancement - disabled Feb 14 10:11:59 volumio-1 kernel: Multiprocessor Interrupt Enhancement - disabled Feb 14 10:11:59 volumio-1 kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Feb 14 10:11:59 volumio-1 kernel: Dedicated Tx FIFOs mode Feb 14 10:11:59 volumio-1 kernel: Feb 14 10:11:59 volumio-1 kernel: WARN::dwc_otg_hcd_init:1070: FIQ DMA bounce buffers: virt = b4104000 dma = 0xf4104000 len=9024 Feb 14 10:11:59 volumio-1 kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Feb 14 10:11:59 volumio-1 kernel: dwc_otg: Microframe scheduler enabled Feb 14 10:11:59 volumio-1 kernel: Feb 14 10:11:59 volumio-1 kernel: WARN::hcd_init_fiq:457: FIQ on core 1 Feb 14 10:11:59 volumio-1 kernel: Feb 14 10:11:59 volumio-1 kernel: WARN::hcd_init_fiq:458: FIQ ASM at 808886e4 length 36 Feb 14 10:11:59 volumio-1 kernel: Feb 14 10:11:59 volumio-1 kernel: WARN::hcd_init_fiq:496: MPHI regs_base at b8810000 Feb 14 10:11:59 volumio-1 kernel: dwc_otg 3f980000.usb: DWC OTG Controller Feb 14 10:11:59 volumio-1 kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Feb 14 10:11:59 volumio-1 kernel: dwc_otg 3f980000.usb: irq 89, io mem 0x00000000 Feb 14 10:11:59 volumio-1 kernel: Init: Port Power? op_state=1 Feb 14 10:11:59 volumio-1 kernel: Init: Power Port (0) Feb 14 10:11:59 volumio-1 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Feb 14 10:11:59 volumio-1 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 14 10:11:59 volumio-1 kernel: usb usb1: Product: DWC OTG Controller Feb 14 10:11:59 volumio-1 kernel: usb usb1: Manufacturer: Linux 6.6.62-v7+ dwc_otg_hcd Feb 14 10:11:59 volumio-1 kernel: usb usb1: SerialNumber: 3f980000.usb Feb 14 10:11:59 volumio-1 kernel: hub 1-0:1.0: USB hub found Feb 14 10:11:59 volumio-1 kernel: hub 1-0:1.0: 1 port detected Apr 04 21:27:09 volumio-1 kernel: dwc_otg: FIQ enabled Apr 04 21:27:09 volumio-1 kernel: dwc_otg: NAK holdoff enabled Apr 04 21:27:09 volumio-1 kernel: dwc_otg: FIQ split-transaction FSM enabled Apr 04 21:27:09 volumio-1 kernel: Module dwc_common_port init Apr 04 21:27:09 volumio-1 kernel: usbcore: registered new interface driver usb-storage Apr 04 21:27:09 volumio-1 kernel: mousedev: PS/2 mouse device common for all mice Apr 04 21:27:09 volumio-1 kernel: sdhci: Secure Digital Host Controller Interface driver Apr 04 21:27:09 volumio-1 kernel: sdhci: Copyright(c) Pierre Ossman Apr 04 21:27:09 volumio-1 kernel: sdhci-pltfm: SDHCI platform and OF driver helper Apr 04 21:27:09 volumio-1 kernel: ledtrig-cpu: registered to indicate activity on CPUs Apr 04 21:27:09 volumio-1 kernel: hid: raw HID events driver (C) Jiri Kosina Apr 04 21:27:09 volumio-1 kernel: usbcore: registered new interface driver usbhid Apr 04 21:27:09 volumio-1 kernel: usbhid: USB HID core driver Apr 04 21:27:09 volumio-1 kernel: Initializing XFRM netlink socket Apr 04 21:27:09 volumio-1 kernel: NET: Registered PF_PACKET protocol family Apr 04 21:27:09 volumio-1 kernel: Key type dns_resolver registered Apr 04 21:27:09 volumio-1 kernel: Registering SWP/SWPB emulation handler Apr 04 21:27:09 volumio-1 kernel: registered taskstats version 1 Apr 04 21:27:09 volumio-1 kernel: Loading compiled-in X.509 certificates Apr 04 21:27:09 volumio-1 kernel: Key type .fscrypt registered Apr 04 21:27:09 volumio-1 kernel: Key type fscrypt-provisioning registered Apr 04 21:27:09 volumio-1 kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Apr 04 21:27:09 volumio-1 kernel: 3f201000.serial: ttyAMA1 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2 Apr 04 21:27:09 volumio-1 kernel: serial serial0: tty port ttyAMA1 registered Apr 04 21:27:09 volumio-1 kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Apr 04 21:27:09 volumio-1 kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Apr 04 21:27:09 volumio-1 kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 Apr 04 21:27:09 volumio-1 kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated Apr 04 21:27:09 volumio-1 kernel: sdhost: log_buf @ 7b305a81 (f4107000) Apr 04 21:27:09 volumio-1 kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Apr 04 21:27:09 volumio-1 kernel: of_cfs_init Apr 04 21:27:09 volumio-1 kernel: of_cfs_init: OK Apr 04 21:27:09 volumio-1 kernel: clk: Disabling unused clocks Apr 04 21:27:09 volumio-1 kernel: Freeing unused kernel image (initmem) memory: 1024K Apr 04 21:27:09 volumio-1 kernel: Run /init as init process Apr 04 21:27:09 volumio-1 kernel: with arguments: Apr 04 21:27:09 volumio-1 kernel: /init Apr 04 21:27:09 volumio-1 kernel: splash Apr 04 21:27:09 volumio-1 kernel: nodebug Apr 04 21:27:09 volumio-1 kernel: with environment: Apr 04 21:27:09 volumio-1 kernel: HOME=/ Apr 04 21:27:09 volumio-1 kernel: TERM=linux Apr 04 21:27:09 volumio-1 kernel: imgpart=UUID=af3faec2-9654-4fec-8791-d5638a5837ad Apr 04 21:27:09 volumio-1 kernel: imgfile=/volumio_current.sqsh Apr 04 21:27:09 volumio-1 kernel: bootpart=UUID=2E0A-AE55 Apr 04 21:27:09 volumio-1 kernel: datapart=UUID=7f14baaf-9650-49e5-a9b0-31d23431a224 Apr 04 21:27:09 volumio-1 kernel: uuidconfig=cmdline.txt Apr 04 21:27:09 volumio-1 kernel: pcie_aspm=off Apr 04 21:27:09 volumio-1 kernel: pci=pcie_bus_safe Apr 04 21:27:09 volumio-1 kernel: bootdelay=7 Apr 04 21:27:09 volumio-1 kernel: use_kmsg=no Apr 04 21:27:09 volumio-1 kernel: Indeed it is in host mode hprt0 = 00021501 Apr 04 21:27:09 volumio-1 kernel: mmc0: host does not support reading read-only switch, assuming write-enable Apr 04 21:27:09 volumio-1 kernel: mmc0: Host Software Queue enabled Apr 04 21:27:09 volumio-1 kernel: mmc0: new high speed SDHC card at address aaaa Apr 04 21:27:09 volumio-1 kernel: mmcblk0: mmc0:aaaa SC16G 14.8 GiB Apr 04 21:27:09 volumio-1 kernel: mmcblk0: p1 p2 p3 Apr 04 21:27:09 volumio-1 kernel: mmcblk0: mmc0:aaaa SC16G 14.8 GiB (quirks 0x0000c000) Apr 04 21:27:09 volumio-1 kernel: mmc1: new high speed SDIO card at address 0001 Apr 04 21:27:09 volumio-1 kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Apr 04 21:27:09 volumio-1 kernel: Indeed it is in host mode hprt0 = 00001101 Apr 04 21:27:09 volumio-1 kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Apr 04 21:27:09 volumio-1 kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Apr 04 21:27:09 volumio-1 kernel: hub 1-1:1.0: USB hub found Apr 04 21:27:09 volumio-1 kernel: hub 1-1:1.0: 5 ports detected Apr 04 21:27:09 volumio-1 kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Apr 04 21:27:09 volumio-1 kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Apr 04 21:27:09 volumio-1 kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Apr 04 21:27:09 volumio-1 kernel: smsc95xx v2.0.0 Apr 04 21:27:09 volumio-1 kernel: fuse: init (API version 7.39) Apr 04 21:27:09 volumio-1 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 04 21:27:09 volumio-1 kernel: SMSC LAN8700 usb-001:003:01: attached PHY driver (mii_bus:phy_addr=usb-001:003:01, irq=199) Apr 04 21:27:09 volumio-1 kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:89:7c:3f Apr 04 21:27:09 volumio-1 kernel: usbcore: registered new interface driver uas Apr 04 21:27:09 volumio-1 kernel: usb 1-1.5: new high-speed USB device number 4 using dwc_otg Apr 04 21:27:09 volumio-1 kernel: usb 1-1.5: New USB device found, idVendor=0781, idProduct=5591, bcdDevice= 1.00 Apr 04 21:27:09 volumio-1 kernel: usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Apr 04 21:27:09 volumio-1 kernel: usb 1-1.5: Product: Ultra USB 3.0 Apr 04 21:27:09 volumio-1 kernel: usb 1-1.5: Manufacturer: SanDisk Apr 04 21:27:09 volumio-1 kernel: usb 1-1.5: SerialNumber: 0101b748bd072a14296154306e8d5fa9513d1efcffa3721469d73c2db807bb5a1c3c00000000000000000000a0182df8ff8e45009155810795269266 Apr 04 21:27:09 volumio-1 kernel: usb-storage 1-1.5:1.0: USB Mass Storage device detected Apr 04 21:27:09 volumio-1 kernel: scsi host0: usb-storage 1-1.5:1.0 Apr 04 21:27:09 volumio-1 kernel: scsi 0:0:0:0: Direct-Access SanDisk Ultra USB 3.0 1.00 PQ: 0 ANSI: 6 Apr 04 21:27:09 volumio-1 kernel: sd 0:0:0:0: [sda] 120176640 512-byte logical blocks: (61.5 GB/57.3 GiB) Apr 04 21:27:09 volumio-1 kernel: sd 0:0:0:0: [sda] Write Protect is off Apr 04 21:27:09 volumio-1 kernel: sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00 Apr 04 21:27:09 volumio-1 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Apr 04 21:27:09 volumio-1 kernel: sda: Apr 04 21:27:09 volumio-1 kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Apr 04 21:27:09 volumio-1 kernel: EXT4-fs (mmcblk0p2): recovery complete Apr 04 21:27:09 volumio-1 kernel: EXT4-fs (mmcblk0p2): mounted filesystem af3faec2-9654-4fec-8791-d5638a5837ad r/w with ordered data mode. Quota mode: none. Apr 04 21:27:09 volumio-1 kernel: loop0: detected capacity change from 0 to 1018800 Apr 04 21:27:09 volumio-1 kernel: EXT4-fs (mmcblk0p3): recovery complete Apr 04 21:27:09 volumio-1 kernel: EXT4-fs (mmcblk0p3): mounted filesystem 7f14baaf-9650-49e5-a9b0-31d23431a224 r/w with ordered data mode. Quota mode: none. Apr 04 21:27:09 volumio-1 systemd[1]: System time before build time, advancing clock. Apr 04 21:27:09 volumio-1 kernel: NET: Registered PF_INET6 protocol family Apr 04 21:27:09 volumio-1 kernel: Segment Routing with IPv6 Apr 04 21:27:09 volumio-1 kernel: In-situ OAM (IOAM) with IPv6 Apr 04 21:27:09 volumio-1 systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Apr 04 21:27:09 volumio-1 systemd[1]: Detected architecture arm. Apr 04 21:27:09 volumio-1 systemd[1]: Set hostname to . Apr 04 21:27:09 volumio-1 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 04 21:27:09 volumio-1 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Apr 04 21:27:09 volumio-1 systemd[1]: Created slice system-getty.slice. Apr 04 21:27:09 volumio-1 systemd[1]: Listening on udev Control Socket. Apr 04 21:27:09 volumio-1 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Apr 04 21:27:09 volumio-1 systemd[1]: Created slice User and Session Slice. Apr 04 21:27:09 volumio-1 systemd[1]: Reached target Slices. Apr 04 21:27:09 volumio-1 kernel: i2c_dev: i2c /dev entries driver Apr 04 21:27:09 volumio-1 systemd-journald[327]: Journal started Apr 04 21:27:09 volumio-1 systemd-journald[327]: Runtime journal (/run/log/journal/eea64a7df36cabb6e36ce31068484641) is 7.5M, max 30.0M, 22.5M free. Apr 04 21:27:09 volumio-1 systemd-modules-load[317]: Inserted module 'i2c_dev' Apr 04 21:27:09 volumio-1 fake-hwclock[322]: Sat Apr 4 20:27:09 UTC 2026 Apr 04 21:27:09 volumio-1 systemd[1]: dynamicswap.service: Succeeded. Apr 04 21:27:09 volumio-1 systemd[1]: Started Create System Users. Apr 04 21:27:09 volumio-1 systemd[1]: Starting Create Static Device Nodes in /dev... Apr 04 21:27:09 volumio-1 systemd[1]: Started udev Coldplug all Devices. Apr 04 21:27:09 volumio-1 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Apr 04 21:27:09 volumio-1 systemd[1]: Started Helper to synchronize boot up for ifupdown. Apr 04 21:27:09 volumio-1 systemd[1]: Started Create Static Device Nodes in /dev. Apr 04 21:27:09 volumio-1 systemd[1]: Starting udev Kernel Device Manager... Apr 04 21:27:09 volumio-1 systemd[1]: Reached target Local File Systems (Pre). Apr 04 21:27:09 volumio-1 systemd[1]: Mounting /var/spool/cups... Apr 04 21:27:09 volumio-1 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Apr 04 21:27:09 volumio-1 systemd[1]: Mounting /var/log... Apr 04 21:27:09 volumio-1 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Apr 04 21:27:09 volumio-1 systemd[1]: Mounting /tmp... Apr 04 21:27:09 volumio-1 systemd[1]: Mounted /var/spool/cups. Apr 04 21:27:09 volumio-1 systemd[1]: Mounted /var/log. Apr 04 21:27:09 volumio-1 systemd[1]: Mounted /tmp. Apr 04 21:27:09 volumio-1 systemd[1]: Starting Flush Journal to Persistent Storage... Apr 04 21:27:09 volumio-1 systemd-udevd[351]: Network interface NamePolicy= disabled on kernel command line, ignoring. Apr 04 21:27:09 volumio-1 systemd[1]: Mounting /var/spool/cups/tmp... Apr 04 21:27:09 volumio-1 systemd[1]: Mounted /var/spool/cups/tmp. Apr 04 21:27:09 volumio-1 systemd[1]: Started udev Kernel Device Manager. Apr 04 21:27:09 volumio-1 systemd[1]: Starting Show Plymouth Boot Screen... Apr 04 21:27:09 volumio-1 systemd-journald[327]: Runtime journal (/run/log/journal/eea64a7df36cabb6e36ce31068484641) is 7.5M, max 30.0M, 22.5M free. Apr 04 21:27:09 volumio-1 systemd[1]: Started Flush Journal to Persistent Storage. Apr 04 21:27:09 volumio-1 systemd[1]: plymouth-start.service: Succeeded. Apr 04 21:27:09 volumio-1 systemd[1]: Started Show Plymouth Boot Screen. Apr 04 21:27:09 volumio-1 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Apr 04 21:27:09 volumio-1 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 04 21:27:09 volumio-1 systemd[1]: Reached target Paths. Apr 04 21:27:09 volumio-1 systemd[1]: Reached target Local Encrypted Volumes. Apr 04 21:27:09 volumio-1 kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Apr 04 21:27:09 volumio-1 kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Apr 04 21:27:09 volumio-1 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Apr 04 21:27:09 volumio-1 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Apr 04 21:27:09 volumio-1 kernel: [vc_sm_connected_init]: start Apr 04 21:27:09 volumio-1 kernel: [vc_sm_connected_init]: installed successfully Apr 04 21:27:09 volumio-1 kernel: mc: Linux media interface: v0.10 Apr 04 21:27:10 volumio-1 kernel: videodev: Linux video capture interface: v2.00 Apr 04 21:27:10 volumio-1 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Apr 04 21:27:10 volumio-1 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Apr 04 21:27:10 volumio-1 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Apr 04 21:27:10 volumio-1 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Apr 04 21:27:10 volumio-1 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Apr 04 21:27:10 volumio-1 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Apr 04 21:27:10 volumio-1 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Apr 04 21:27:10 volumio-1 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Apr 04 21:27:10 volumio-1 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Apr 04 21:27:10 volumio-1 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Apr 04 21:27:10 volumio-1 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Apr 04 21:27:10 volumio-1 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Apr 04 21:27:10 volumio-1 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Apr 04 21:27:10 volumio-1 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Apr 04 21:27:10 volumio-1 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Apr 04 21:27:10 volumio-1 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Apr 04 21:27:10 volumio-1 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Apr 04 21:27:10 volumio-1 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Apr 04 21:27:10 volumio-1 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Apr 04 21:27:10 volumio-1 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Apr 04 21:27:10 volumio-1 systemd[1]: Found device /dev/disk/by-uuid/2E0A-AE55. Apr 04 21:27:10 volumio-1 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Apr 04 21:27:10 volumio-1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Apr 04 21:27:10 volumio-1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Apr 04 21:27:10 volumio-1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Apr 04 21:27:10 volumio-1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Apr 04 21:27:10 volumio-1 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Apr 04 21:27:10 volumio-1 kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Apr 04 21:27:10 volumio-1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Apr 04 21:27:10 volumio-1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Apr 04 21:27:10 volumio-1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Apr 04 21:27:10 volumio-1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Apr 04 21:27:10 volumio-1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Apr 04 21:27:10 volumio-1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Apr 04 21:27:10 volumio-1 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Apr 04 21:27:10 volumio-1 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Apr 04 21:27:10 volumio-1 kernel: Bluetooth: Core ver 2.22 Apr 04 21:27:10 volumio-1 kernel: NET: Registered PF_BLUETOOTH protocol family Apr 04 21:27:10 volumio-1 kernel: Bluetooth: HCI device and connection manager initialized Apr 04 21:27:10 volumio-1 kernel: Bluetooth: HCI socket layer initialized Apr 04 21:27:10 volumio-1 kernel: Bluetooth: L2CAP socket layer initialized Apr 04 21:27:10 volumio-1 kernel: Bluetooth: SCO socket layer initialized Apr 04 21:27:10 volumio-1 systemd[1]: Starting File System Check on /dev/disk/by-uuid/2E0A-AE55... Apr 04 21:27:11 volumio-1 kernel: Bluetooth: HCI UART driver ver 2.3 Apr 04 21:27:11 volumio-1 kernel: Bluetooth: HCI UART protocol H4 registered Apr 04 21:27:11 volumio-1 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Apr 04 21:27:11 volumio-1 kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Apr 04 21:27:11 volumio-1 kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Apr 04 21:27:11 volumio-1 kernel: Bluetooth: HCI UART protocol Broadcom registered Apr 04 21:27:11 volumio-1 kernel: uart-pl011 3f201000.serial: no DMA platform data Apr 04 21:27:11 volumio-1 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Apr 04 21:27:11 volumio-1 systemd[1]: Started File System Check Daemon to report status. Apr 04 21:27:11 volumio-1 systemd-udevd[358]: Using default interface naming scheme 'v240'. Apr 04 21:27:11 volumio-1 systemd-udevd[358]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 04 21:27:11 volumio-1 systemd-fsck[409]: fsck.fat 4.1 (2017-01-24) Apr 04 21:27:11 volumio-1 systemd-fsck[409]: /dev/mmcblk0p1: 404 files, 41766/46774 clusters Apr 04 21:27:11 volumio-1 kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Apr 04 21:27:11 volumio-1 kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Apr 04 21:27:11 volumio-1 kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Apr 04 21:27:11 volumio-1 kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Apr 04 21:27:11 volumio-1 systemd[1]: Started File System Check on /dev/disk/by-uuid/2E0A-AE55. Apr 04 21:27:11 volumio-1 systemd[1]: Mounting /boot... Apr 04 21:27:11 volumio-1 kernel: Bluetooth: hci0: BCM: chip id 94 Apr 04 21:27:11 volumio-1 kernel: Bluetooth: hci0: BCM: features 0x2e Apr 04 21:27:11 volumio-1 kernel: Bluetooth: hci0: BCM43430A1 Apr 04 21:27:11 volumio-1 kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0000 Apr 04 21:27:11 volumio-1 kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Apr 04 21:27:11 volumio-1 kernel: Bluetooth: hci0: BCM43430A1 'brcm/BCM43430A1.hcd' Patch Apr 04 21:27:11 volumio-1 systemd[1]: Mounted /boot. Apr 04 21:27:11 volumio-1 systemd[1]: Reached target Local File Systems. Apr 04 21:27:11 volumio-1 systemd[1]: Starting Preprocess NFS configuration... Apr 04 21:27:11 volumio-1 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Apr 04 21:27:11 volumio-1 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Apr 04 21:27:11 volumio-1 systemd[1]: Starting Create Volatile Files and Directories... Apr 04 21:27:11 volumio-1 systemd[1]: Starting Raise network interfaces... Apr 04 21:27:11 volumio-1 systemd[1]: nfs-config.service: Succeeded. Apr 04 21:27:11 volumio-1 systemd[1]: Started Preprocess NFS configuration. Apr 04 21:27:11 volumio-1 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Apr 04 21:27:11 volumio-1 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Apr 04 21:27:11 volumio-1 systemd[1]: Reached target NFS client services. Apr 04 21:27:11 volumio-1 systemd[1]: plymouth-read-write.service: Succeeded. Apr 04 21:27:11 volumio-1 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Apr 04 21:27:11 volumio-1 systemd[1]: Received SIGRTMIN+20 from PID 200 (plymouthd). Apr 04 21:27:12 volumio-1 kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Apr 04 21:27:12 volumio-1 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Apr 04 21:27:12 volumio-1 kernel: usbcore: registered new interface driver brcmfmac Apr 04 21:27:12 volumio-1 ifup[484]: command failed: No such device (-19) Apr 04 21:27:12 volumio-1 systemd-udevd[360]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 04 21:27:12 volumio-1 systemd[1]: Started Create Volatile Files and Directories. Apr 04 21:27:12 volumio-1 kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Apr 04 21:27:12 volumio-1 kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Jul 19 2021 03:24:18 version 7.45.98 (TOB) (56df937 CY) FWID 01-8e14b897 Apr 04 21:27:12 volumio-1 kernel: Bluetooth: hci0: BCM: features 0x2e Apr 04 21:27:12 volumio-1 kernel: Bluetooth: hci0: BCM43438A1 37.4MHz Raspberry Pi 3-0141 Apr 04 21:27:12 volumio-1 kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0508 Apr 04 21:27:12 volumio-1 systemd-udevd[358]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 04 21:27:13 volumio-1 kernel: hwmon hwmon1: Undervoltage detected! Apr 04 21:27:13 volumio-1 systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. Apr 04 21:27:13 volumio-1 kernel: alsactl[543]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Apr 04 21:27:13 volumio-1 kernel: alsactl[548]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Apr 04 21:27:13 volumio-1 systemd[1]: Started Raise network interfaces. Apr 04 21:27:13 volumio-1 systemd[1]: Created slice system-bthelper.slice. Apr 04 21:27:13 volumio-1 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Apr 04 21:27:13 volumio-1 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Apr 04 21:27:13 volumio-1 systemd[1]: Starting Show Plymouth Boot Screen... Apr 04 21:27:13 volumio-1 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Apr 04 21:27:13 volumio-1 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Apr 04 21:27:13 volumio-1 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Apr 04 21:27:13 volumio-1 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Apr 04 21:27:13 volumio-1 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Apr 04 21:27:13 volumio-1 systemd[1]: Started ifup for eth0. Apr 04 21:27:13 volumio-1 systemd[1]: Starting RPC bind portmap service... Apr 04 21:27:13 volumio-1 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Apr 04 21:27:13 volumio-1 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Apr 04 21:27:13 volumio-1 systemd[1]: plymouth-read-write.service: Succeeded. Apr 04 21:27:13 volumio-1 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Apr 04 21:27:13 volumio-1 systemd[1]: Received SIGRTMIN+20 from PID 200 (plymouthd). Apr 04 21:27:14 volumio-1 systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Apr 04 21:27:14 volumio-1 systemd[1]: plymouth-start.service: Succeeded. Apr 04 21:27:14 volumio-1 systemd[1]: Started Show Plymouth Boot Screen. Apr 04 21:27:14 volumio-1 systemd[1]: Started RPC bind portmap service. Apr 04 21:27:14 volumio-1 kernel: 8021q: 802.1Q VLAN Support v1.8 Apr 04 21:27:14 volumio-1 systemd[1]: Starting Load/Save RF Kill Switch Status... Apr 04 21:27:14 volumio-1 systemd[1]: Reached target RPC Port Mapper. Apr 04 21:27:14 volumio-1 systemd[1]: Reached target Remote File Systems (Pre). Apr 04 21:27:14 volumio-1 systemd[1]: Reached target Remote File Systems. Apr 04 21:27:14 volumio-1 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 04 21:27:14 volumio-1 systemd[1]: Started ifup for wlan0. Apr 04 21:27:14 volumio-1 systemd[1]: Started Update UTMP about System Boot/Shutdown. Apr 04 21:27:14 volumio-1 systemd[1]: Reached target System Initialization. Apr 04 21:27:14 volumio-1 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Apr 04 21:27:14 volumio-1 systemd[1]: Listening on triggerhappy.socket. Apr 04 21:27:14 volumio-1 systemd[1]: Listening on D-Bus System Message Bus Socket. Apr 04 21:27:14 volumio-1 systemd[1]: Reached target Sockets. Apr 04 21:27:14 volumio-1 systemd[1]: Started Daily apt download activities. Apr 04 21:27:14 volumio-1 systemd[1]: Started Daily apt upgrade and clean activities. Apr 04 21:27:14 volumio-1 systemd[1]: Started Daily Cleanup of Temporary Directories. Apr 04 21:27:14 volumio-1 systemd[1]: Reached target Timers. Apr 04 21:27:14 volumio-1 systemd[1]: Reached target Basic System. Apr 04 21:27:14 volumio-1 systemd[1]: Starting triggerhappy global hotkey daemon... Apr 04 21:27:14 volumio-1 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Apr 04 21:27:14 volumio-1 systemd[1]: Started volumio-remote-updater.service. Apr 04 21:27:14 volumio-1 systemd[1]: Started Manage Sound Card State (restore and store). Apr 04 21:27:14 volumio-1 systemd[1]: Starting Save/Restore Sound Card State... Apr 04 21:27:14 volumio-1 systemd[1]: Started Volumio Log Rotation Service. Apr 04 21:27:14 volumio-1 alsactl[629]: alsactl 1.1.8 daemon started Apr 04 21:27:14 volumio-1 thd[625]: Unable to parse trigger line: Apr 04 21:27:14 volumio-1 thd[625]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Apr 04 21:27:14 volumio-1 thd[625]: Unable to parse trigger line: Apr 04 21:27:14 volumio-1 thd[625]: Found socket passed from systemd Apr 04 21:27:14 volumio-1 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Apr 04 21:27:14 volumio-1 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Apr 04 21:27:14 volumio-1 systemd[1]: Starting Volumio Time Update Utility... Apr 04 21:27:14 volumio-1 kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Apr 04 21:27:14 volumio-1 systemd[1]: Started UPnP Renderer front-end to MPD. Apr 04 21:27:14 volumio-1 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Apr 04 21:27:14 volumio-1 systemd[1]: Starting Configure Bluetooth Modems connected by UART... Apr 04 21:27:14 volumio-1 systemd[1]: Starting Wireless Services... Apr 04 21:27:14 volumio-1 systemd[1]: Started Volumio Iptables Module. Apr 04 21:27:14 volumio-1 systemd[1]: Starting Login Service... Apr 04 21:27:14 volumio-1 volumio-time-update[641]: volumio-time-update-util: Fetching time from Volumio... Apr 04 21:27:14 volumio-1 systemd[1]: Started D-Bus System Message Bus. Apr 04 21:27:14 volumio-1 systemd[1]: Starting WPA supplicant... Apr 04 21:27:14 volumio-1 sh[590]: eth0: waiting for carrier Apr 04 21:27:14 volumio-1 sh[590]: eth0: carrier acquired Apr 04 21:27:14 volumio-1 dhcpcd[610]: eth0: waiting for carrier Apr 04 21:27:14 volumio-1 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Apr 04 21:27:14 volumio-1 kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Apr 04 21:27:14 volumio-1 dhcpcd[610]: eth0: carrier acquired Apr 04 21:27:14 volumio-1 systemd[1]: Started Load/Save RF Kill Switch Status. Apr 04 21:27:14 volumio-1 systemd[1]: Started triggerhappy global hotkey daemon. Apr 04 21:27:14 volumio-1 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Apr 04 21:27:14 volumio-1 systemd[1]: Starting dhcpcd on all interfaces... Apr 04 21:27:14 volumio-1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 04 21:27:14 volumio-1 sh[590]: DUID 00:01:00:01:2f:db:04:a3:b8:27:eb:89:7c:3f Apr 04 21:27:14 volumio-1 sh[590]: eth0: IAID eb:89:7c:3f Apr 04 21:27:14 volumio-1 dhcpcd[610]: DUID 00:01:00:01:2f:db:04:a3:b8:27:eb:89:7c:3f Apr 04 21:27:14 volumio-1 dhcpcd[610]: eth0: IAID eb:89:7c:3f Apr 04 21:27:14 volumio-1 sh[590]: eth0: adding address fe80::53c8:f6fd:7136:cedd Apr 04 21:27:14 volumio-1 sh[590]: ipv6_addaddr1: Permission denied Apr 04 21:27:14 volumio-1 dhcpcd[610]: eth0: adding address fe80::53c8:f6fd:7136:cedd Apr 04 21:27:14 volumio-1 dhcpcd[668]: Not running dhcpcd because /etc/network/interfaces Apr 04 21:27:14 volumio-1 dhcpcd[668]: defines some interfaces that will use a Apr 04 21:27:14 volumio-1 dhcpcd[668]: DHCP client or static address Apr 04 21:27:14 volumio-1 dhcpcd[610]: ipv6_addaddr1: Permission denied Apr 04 21:27:15 volumio-1 avahi-daemon[637]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Apr 04 21:27:15 volumio-1 avahi-daemon[637]: Successfully dropped root privileges. Apr 04 21:27:15 volumio-1 avahi-daemon[637]: avahi-daemon 0.7 starting up. Apr 04 21:27:15 volumio-1 systemd-logind[654]: New seat seat0. Apr 04 21:27:15 volumio-1 sh[590]: eth0: soliciting a DHCP lease Apr 04 21:27:15 volumio-1 dhcpcd[610]: eth0: soliciting a DHCP lease Apr 04 21:27:15 volumio-1 sh[590]: eth0: soliciting an IPv6 router Apr 04 21:27:15 volumio-1 dhcpcd[610]: eth0: soliciting an IPv6 router Apr 04 21:27:15 volumio-1 sh[590]: eth0: offered 192.168.0.101 from 192.168.0.1 Apr 04 21:27:15 volumio-1 dhcpcd[610]: eth0: offered 192.168.0.101 from 192.168.0.1 Apr 04 21:27:15 volumio-1 dbus-daemon[657]: [system] Successfully activated service 'org.freedesktop.systemd1' Apr 04 21:27:15 volumio-1 systemd[1]: Started Login Service. Apr 04 21:27:15 volumio-1 systemd[1]: Started Save/Restore Sound Card State. Apr 04 21:27:15 volumio-1 systemd[1]: hciuart.service: Succeeded. Apr 04 21:27:15 volumio-1 systemd[1]: Started Configure Bluetooth Modems connected by UART. Apr 04 21:27:15 volumio-1 systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Apr 04 21:27:15 volumio-1 systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Apr 04 21:27:15 volumio-1 systemd[1]: Failed to start dhcpcd on all interfaces. Apr 04 21:27:15 volumio-1 wpa_supplicant[667]: Successfully initialized wpa_supplicant Apr 04 21:27:15 volumio-1 avahi-daemon[637]: Successfully called chroot(). Apr 04 21:27:15 volumio-1 avahi-daemon[637]: Successfully dropped remaining capabilities. Apr 04 21:27:15 volumio-1 avahi-daemon[637]: Loading service file /services/volumio.service. Apr 04 21:27:15 volumio-1 avahi-daemon[637]: Network interface enumeration completed. Apr 04 21:27:15 volumio-1 avahi-daemon[637]: Server startup complete. Host name is volumio-1.local. Local service cookie is 31869165. Apr 04 21:27:15 volumio-1 avahi-daemon[637]: Service "Volumio-1" (/services/volumio.service) successfully established. Apr 04 21:27:16 volumio-1 sh[590]: eth0: probing address 192.168.0.101/24 Apr 04 21:27:16 volumio-1 dhcpcd[610]: eth0: probing address 192.168.0.101/24 Apr 04 21:27:16 volumio-1 systemd[1]: Started WPA supplicant. Apr 04 21:27:16 volumio-1 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Apr 04 21:27:16 volumio-1 sh[615]: wlan0=wlan0 Apr 04 21:27:16 volumio-1 volumio-remote-updater[627]: Error: No active session Apr 04 21:27:16 volumio-1 systemd[1]: Reached target Network. Apr 04 21:27:16 volumio-1 volumio-remote-updater[627]: [2026-04-04 21:27:16] [info] asio async_connect error: system:111 (Connection refused) Apr 04 21:27:16 volumio-1 volumio-remote-updater[627]: [2026-04-04 21:27:16] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 04 21:27:16 volumio-1 volumio-remote-updater[627]: [2026-04-04 21:27:16] [error] handle_connect error: Underlying Transport Error Apr 04 21:27:16 volumio-1 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Apr 04 21:27:16 volumio-1 volumio[642]: Could not open config: /tmp/upmpdcli.conf Apr 04 21:27:16 volumio-1 systemd[1]: Starting Permit User Sessions... Apr 04 21:27:16 volumio-1 volumio-time-update[641]: volumio-time-update-util: Date not found in response Apr 04 21:27:16 volumio-1 volumio-time-update[641]: volumio-time-update-util: Retrying in 5 seconds... Apr 04 21:27:16 volumio-1 systemd[1]: Starting OpenBSD Secure Shell server... Apr 04 21:27:16 volumio-1 systemd[1]: Starting Network Time Service... Apr 04 21:27:16 volumio-1 systemd[1]: Reached target Network is Online. Apr 04 21:27:16 volumio-1 systemd[1]: Starting /etc/rc.local Compatibility... Apr 04 21:27:16 volumio-1 systemd[1]: Starting LSB: Brings up/down network automatically... Apr 04 21:27:16 volumio-1 systemd[1]: Starting Samba NMB Daemon... Apr 04 21:27:16 volumio-1 systemd[1]: Starting Raspberry Pi bluetooth helper... Apr 04 21:27:16 volumio-1 systemd[1]: Reached target Sound Card. Apr 04 21:27:16 volumio-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 04 21:27:16 volumio-1 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 04 21:27:16 volumio-1 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 04 21:27:16 volumio-1 systemd[1]: Started Permit User Sessions. Apr 04 21:27:16 volumio-1 systemd[1]: Started /etc/rc.local Compatibility. Apr 04 21:27:16 volumio-1 systemd[1]: Starting Terminate Plymouth Boot Screen... Apr 04 21:27:16 volumio-1 systemd[1]: Starting Hold until boot process finishes up... Apr 04 21:27:16 volumio-1 systemd[1]: plymouth-quit.service: Succeeded. Apr 04 21:27:16 volumio-1 systemd[1]: Started Terminate Plymouth Boot Screen. Apr 04 21:27:16 volumio-1 loadcpufreq[638]: Loading cpufreq kernel modules...done (none). Apr 04 21:27:16 volumio-1 systemd[1]: plymouth-quit-wait.service: Succeeded. Apr 04 21:27:16 volumio-1 systemd[1]: Started Hold until boot process finishes up. Apr 04 21:27:16 volumio-1 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Apr 04 21:27:16 volumio-1 systemd[1]: iptables.service: Succeeded. Apr 04 21:27:16 volumio-1 systemd[1]: Received SIGRTMIN+21 from PID 200 (n/a). Apr 04 21:27:16 volumio-1 systemd[1]: Received SIGRTMIN+21 from PID 200 (n/a). Apr 04 21:27:17 volumio-1 bthelper[747]: Raspberry Pi BDADDR already set Apr 04 21:27:17 volumio-1 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Apr 04 21:27:17 volumio-1 systemd[1]: Started Getty on tty1. Apr 04 21:27:17 volumio-1 systemd[1]: Reached target Login Prompts. Apr 04 21:27:17 volumio-1 systemd[1]: Started Raspberry Pi bluetooth helper. Apr 04 21:27:17 volumio-1 systemd[1]: Starting Bluetooth service... Apr 04 21:27:17 volumio-1 haveged[597]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Apr 04 21:27:17 volumio-1 haveged[597]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Apr 04 21:27:17 volumio-1 haveged[597]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00152 Apr 04 21:27:17 volumio-1 haveged[597]: haveged: fills: 0, generated: 0 Apr 04 21:27:17 volumio-1 ifplugd(eth0)[801]: ifplugd 0.28 initializing. Apr 04 21:27:17 volumio-1 ifplugd(eth0)[801]: Using interface eth0/B8:27:EB:89:7C:3F with driver (version: 6.6.62-v7+) Apr 04 21:27:17 volumio-1 ifplugd(eth0)[801]: Using detection mode: SIOCETHTOOL Apr 04 21:27:17 volumio-1 ifplugd(eth0)[801]: Initialization complete, link beat detected. Apr 04 21:27:17 volumio-1 ntpd[740]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 04 21:27:17 volumio-1 ntpd[740]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Apr 04 21:27:17 volumio-1 ntpd[803]: proto: precision = 1.406 usec (-19) Apr 04 21:27:17 volumio-1 systemd[1]: Started Network Time Service. Apr 04 21:27:17 volumio-1 ntpd[803]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 04 21:27:17 volumio-1 ntpd[803]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 04 21:27:17 volumio-1 ntpd[803]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 463 days ago Apr 04 21:27:17 volumio-1 ntpd[803]: Listen and drop on 0 v6wildcard [::]:123 Apr 04 21:27:17 volumio-1 ntpd[803]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 04 21:27:17 volumio-1 ntpd[803]: Listen normally on 2 lo 127.0.0.1:123 Apr 04 21:27:17 volumio-1 ntpd[803]: Listening on routing socket on fd #19 for interface updates Apr 04 21:27:17 volumio-1 ntpd[803]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 04 21:27:17 volumio-1 ntpd[803]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 04 21:27:17 volumio-1 ifplugd(eth0)[801]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 04 21:27:17 volumio-1 cpufrequtils[784]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Apr 04 21:27:17 volumio-1 systemd[1]: Started LSB: set CPUFreq kernel parameters. Apr 04 21:27:17 volumio-1 sshd[811]: Server listening on 0.0.0.0 port 22. Apr 04 21:27:17 volumio-1 sshd[811]: Server listening on :: port 22. Apr 04 21:27:17 volumio-1 systemd[1]: Started OpenBSD Secure Shell server. Apr 04 21:27:17 volumio-1 ifplugd(eth0)[801]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Apr 04 21:27:17 volumio-1 bluetoothd[794]: Bluetooth daemon 5.83 Apr 04 21:27:17 volumio-1 bluetoothd[794]: src/main.c:check_options() Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Apr 04 21:27:17 volumio-1 systemd[1]: Started Bluetooth service. Apr 04 21:27:17 volumio-1 systemd[1]: Reached target Bluetooth. Apr 04 21:27:17 volumio-1 bluetoothd[794]: Starting SDP server Apr 04 21:27:17 volumio-1 bluetoothd[794]: Excluding (cli) hostname Apr 04 21:27:17 volumio-1 bluetoothd[794]: Excluding (cli) scanparam Apr 04 21:27:17 volumio-1 bluetoothd[794]: Excluding (cli) battery Apr 04 21:27:17 volumio-1 bluetoothd[794]: Bluetooth management interface 1.22 initialized Apr 04 21:27:17 volumio-1 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Apr 04 21:27:17 volumio-1 kernel: Bluetooth: BNEP filters: protocol multicast Apr 04 21:27:17 volumio-1 kernel: Bluetooth: BNEP socket layer initialized Apr 04 21:27:17 volumio-1 kernel: Bluetooth: MGMT ver 1.22 Apr 04 21:27:17 volumio-1 bluetoothd[794]: Battery Provider Manager created Apr 04 21:27:17 volumio-1 kernel: NET: Registered PF_ALG protocol family Apr 04 21:27:17 volumio-1 kernel: cryptd: max_cpu_qlen set to 1000 Apr 04 21:27:18 volumio-1 bluetoothd[794]: src/adapter.c:convert_config() Unable set contents for /usr/var/lib/bluetooth/B8:27:EB:23:D6:95/settings: (Failed to create file “/usr/var/lib/bluetooth/B8:27:EB:23:D6:95/settings.QIF8M3”: No such file or directory) Apr 04 21:27:18 volumio-1 bluetoothd[794]: src/adapter.c:load_config() Unable to load key file from /usr/var/lib/bluetooth/B8:27:EB:23:D6:95/settings: (No such file or directory) Apr 04 21:27:18 volumio-1 bluetoothd[794]: Unable to open adapter storage directory: /usr/var/lib/bluetooth/B8:27:EB:23:D6:95 Apr 04 21:27:18 volumio-1 bluetoothd[794]: Failed to set privacy: Rejected (0x0b) Apr 04 21:27:18 volumio-1 ntpd[803]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 04 21:27:18 volumio-1 nmbd[754]: [2026/04/04 21:27:18.827218, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Apr 04 21:27:18 volumio-1 nmbd[754]: started asyncdns process 843 Apr 04 21:27:18 volumio-1 nmbd[754]: [2026/04/04 21:27:18.831139, 0] ../lib/util/become_daemon.c:149(daemon_status) Apr 04 21:27:18 volumio-1 nmbd[754]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Apr 04 21:27:18 volumio-1 nmbd[754]: [2026/04/04 21:27:18.831411, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Apr 04 21:27:18 volumio-1 nmbd[754]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Apr 04 21:27:19 volumio-1 ntpd[803]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 04 21:27:19 volumio-1 systemd[1]: systemd-rfkill.service: Succeeded. Apr 04 21:27:19 volumio-1 wireless.js[650]: Cleaning previous... Apr 04 21:27:19 volumio-1 sudo[851]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 04 21:27:19 volumio-1 sudo[851]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:27:19 volumio-1 sudo[851]: pam_unix(sudo:session): session closed for user root Apr 04 21:27:19 volumio-1 sudo[853]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 04 21:27:19 volumio-1 sudo[853]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:27:20 volumio-1 ntpd[803]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 04 21:27:20 volumio-1 sudo[853]: pam_unix(sudo:session): session closed for user root Apr 04 21:27:20 volumio-1 wireless.js[650]: Stopped aP Apr 04 21:27:20 volumio-1 sudo[860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 04 21:27:20 volumio-1 sudo[860]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:27:20 volumio-1 sudo[860]: pam_unix(sudo:session): session closed for user root Apr 04 21:27:20 volumio-1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 04 21:27:20 volumio-1 sudo[862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 04 21:27:20 volumio-1 sudo[862]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:27:20 volumio-1 sudo[862]: pam_unix(sudo:session): session closed for user root Apr 04 21:27:20 volumio-1 sudo[869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 04 21:27:20 volumio-1 sudo[869]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:27:20 volumio-1 sudo[869]: pam_unix(sudo:session): session closed for user root Apr 04 21:27:20 volumio-1 sudo[871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 04 21:27:20 volumio-1 sudo[871]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:27:21 volumio-1 sudo[871]: pam_unix(sudo:session): session closed for user root Apr 04 21:27:21 volumio-1 wireless.js[650]: SETTING APPROPRIATE REG DOMAIN: GB Apr 04 21:27:21 volumio-1 sudo[876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 04 21:27:21 volumio-1 sudo[876]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:27:21 volumio-1 sudo[876]: pam_unix(sudo:session): session closed for user root Apr 04 21:27:21 volumio-1 volumio-remote-updater[627]: [2026-04-04 21:27:21] [info] asio async_connect error: system:111 (Connection refused) Apr 04 21:27:21 volumio-1 volumio-remote-updater[627]: [2026-04-04 21:27:21] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 04 21:27:21 volumio-1 volumio-remote-updater[627]: [2026-04-04 21:27:21] [error] handle_connect error: Underlying Transport Error Apr 04 21:27:21 volumio-1 ntpd[803]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 04 21:27:21 volumio-1 sudo[878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set GB Apr 04 21:27:21 volumio-1 sudo[878]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:27:21 volumio-1 sudo[878]: pam_unix(sudo:session): session closed for user root Apr 04 21:27:21 volumio-1 wireless.js[650]: SUCCESSFULLY SET NEW REGDOMAIN: GB Apr 04 21:27:21 volumio-1 volumio-time-update[641]: volumio-time-update-util: Fetching time from Volumio... Apr 04 21:27:21 volumio-1 volumio-time-update[641]: volumio-time-update-util: Date not found in response Apr 04 21:27:21 volumio-1 volumio-time-update[641]: volumio-time-update-util: Retrying in 5 seconds... Apr 04 21:27:21 volumio-1 sudo[889]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 04 21:27:21 volumio-1 sudo[889]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:27:21 volumio-1 sudo[889]: pam_unix(sudo:session): session closed for user root Apr 04 21:27:21 volumio-1 sudo[892]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 04 21:27:21 volumio-1 sudo[892]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:27:21 volumio-1 sh[590]: eth0: leased 192.168.0.101 for 86400 seconds Apr 04 21:27:21 volumio-1 dhcpcd[610]: eth0: leased 192.168.0.101 for 86400 seconds Apr 04 21:27:22 volumio-1 sh[590]: eth0: adding route to 192.168.0.0/24 Apr 04 21:27:22 volumio-1 sh[590]: eth0: adding default route via 192.168.0.1 Apr 04 21:27:22 volumio-1 dhcpcd[610]: eth0: adding route to 192.168.0.0/24 Apr 04 21:27:22 volumio-1 dhcpcd[610]: eth0: adding default route via 192.168.0.1 Apr 04 21:27:22 volumio-1 avahi-daemon[637]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.101. Apr 04 21:27:22 volumio-1 avahi-daemon[637]: New relevant interface eth0.IPv4 for mDNS. Apr 04 21:27:22 volumio-1 avahi-daemon[637]: Registering new address record for 192.168.0.101 on eth0.IPv4. Apr 04 21:27:22 volumio-1 sudo[892]: pam_unix(sudo:session): session closed for user root Apr 04 21:27:22 volumio-1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 04 21:27:22 volumio-1 avahi-daemon[637]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Apr 04 21:27:22 volumio-1 avahi-daemon[637]: New relevant interface wlan0.IPv4 for mDNS. Apr 04 21:27:22 volumio-1 avahi-daemon[637]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Apr 04 21:27:22 volumio-1 sh[590]: forked to background, child pid 920 Apr 04 21:27:22 volumio-1 dhcpcd[610]: forked to background, child pid 920 Apr 04 21:27:22 volumio-1 bluetoothd[794]: src/adv_monitor.c:btd_adv_monitor_power_down() Unexpected NULL btd_adv_monitor_manager object upon power down Apr 04 21:27:22 volumio-1 bthelper[747]: [NEW] Media /org/bluez/hci0 Apr 04 21:27:22 volumio-1 bthelper[747]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Apr 04 21:27:22 volumio-1 bthelper[747]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Apr 04 21:27:22 volumio-1 bthelper[747]: hci0 class of device changed: 0x000000 Apr 04 21:27:22 volumio-1 bthelper[747]: hci0 new_settings: bondable ssp br/edr le secure-conn Apr 04 21:27:22 volumio-1 bthelper[747]: [CHG] Controller B8:27:EB:23:D6:95 PowerState: on-disabling Apr 04 21:27:22 volumio-1 bthelper[747]: Changing power off succeeded Apr 04 21:27:22 volumio-1 bthelper[747]: [CHG] Controller B8:27:EB:23:D6:95 PowerState: off Apr 04 21:27:22 volumio-1 bthelper[747]: [CHG] Controller B8:27:EB:23:D6:95 Powered: no Apr 04 21:27:22 volumio-1 bthelper[747]: [CHG] Controller B8:27:EB:23:D6:95 Discovering: no Apr 04 21:27:22 volumio-1 bthelper[747]: [CHG] Controller B8:27:EB:23:D6:95 Class: 0x00000000 (0) Apr 04 21:27:22 volumio-1 systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Apr 04 21:27:22 volumio-1 systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Apr 04 21:27:22 volumio-1 bthelper[747]: [NEW] Media /org/bluez/hci0 Apr 04 21:27:22 volumio-1 bthelper[747]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Apr 04 21:27:22 volumio-1 bthelper[747]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Apr 04 21:27:22 volumio-1 bthelper[747]: [CHG] Controller B8:27:EB:23:D6:95 PowerState: off-enabling Apr 04 21:27:22 volumio-1 dnsmasq[922]: dnsmasq: syntax check OK. Apr 04 21:27:22 volumio-1 hostapd[921]: Configuration file: /etc/hostapd/hostapd.conf Apr 04 21:27:22 volumio-1 hostapd[921]: wlan0: Could not connect to kernel driver Apr 04 21:27:22 volumio-1 hostapd[921]: Using interface wlan0 with hwaddr b8:27:eb:dc:29:6a and ssid "Volumio-74C5D" Apr 04 21:27:22 volumio-1 bthelper[747]: hci0 class of device changed: 0x00041c Apr 04 21:27:22 volumio-1 bthelper[747]: [CHG] Controller B8:27:EB:23:D6:95 Class: 0x0000041c (1052) Apr 04 21:27:22 volumio-1 bthelper[747]: hci0 new_settings: powered bondable ssp br/edr le secure-conn Apr 04 21:27:22 volumio-1 bthelper[747]: Changing power on succeeded Apr 04 21:27:22 volumio-1 bthelper[747]: [CHG] Controller B8:27:EB:23:D6:95 PowerState: on Apr 04 21:27:22 volumio-1 bthelper[747]: [CHG] Controller B8:27:EB:23:D6:95 Powered: yes Apr 04 21:27:22 volumio-1 hostapd[921]: wlan0: interface state UNINITIALIZED->ENABLED Apr 04 21:27:22 volumio-1 hostapd[921]: wlan0: AP-ENABLED Apr 04 21:27:22 volumio-1 systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Apr 04 21:27:22 volumio-1 ifplugd(eth0)[801]: client: ifup: interface eth0 already configured Apr 04 21:27:22 volumio-1 sh[590]: eth0=eth0 Apr 04 21:27:22 volumio-1 dnsmasq[954]: started, version 2.80 cachesize 150 Apr 04 21:27:22 volumio-1 dnsmasq[954]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Apr 04 21:27:22 volumio-1 dnsmasq-dhcp[954]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Apr 04 21:27:22 volumio-1 dnsmasq[954]: reading /etc/resolv.conf Apr 04 21:27:22 volumio-1 dnsmasq[954]: using nameserver 194.168.4.100#53 Apr 04 21:27:22 volumio-1 dnsmasq[954]: using nameserver 194.168.8.100#53 Apr 04 21:27:22 volumio-1 dnsmasq[954]: using nameserver 208.67.222.222#53 Apr 04 21:27:22 volumio-1 dnsmasq[954]: using nameserver 208.67.220.220#53 Apr 04 21:27:22 volumio-1 dnsmasq[954]: read /etc/hosts - 1 addresses Apr 04 21:27:22 volumio-1 iwconfig[958]: Error for wireless request "Set Power Management" (8B2C) : Apr 04 21:27:22 volumio-1 iwconfig[958]: SET failed on device wlan0 ; Invalid argument. Apr 04 21:27:22 volumio-1 systemd[1]: wireless.service: Succeeded. Apr 04 21:27:22 volumio-1 systemd[1]: Started Wireless Services. Apr 04 21:27:22 volumio-1 ifplugd(eth0)[801]: Program executed successfully. Apr 04 21:27:22 volumio-1 ifplugd[742]: Network Interface Plugging Daemon...start eth0...done. Apr 04 21:27:22 volumio-1 systemd[1]: Started LSB: Brings up/down network automatically. Apr 04 21:27:22 volumio-1 systemd[1]: Started Volumio Backend Module. Apr 04 21:27:22 volumio-1 systemd[1]: Started Volumio Cpu Tweaker. Apr 04 21:27:22 volumio-1 systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Apr 04 21:27:22 volumio-1 systemd[1]: Reached target Host and Network Name Lookups. Apr 04 21:27:22 volumio-1 volumio-cpu-tweak[961]: Setting RT Priority for mpd Apr 04 21:27:22 volumio-1 volumio-cpu-tweak[961]: pid 35's current scheduling policy: SCHED_OTHER Apr 04 21:27:22 volumio-1 volumio-cpu-tweak[961]: pid 35's current scheduling priority: 0 Apr 04 21:27:22 volumio-1 volumio-cpu-tweak[961]: Setting MPD Affinity Apr 04 21:27:22 volumio-1 volumio-cpu-tweak[961]: pid 3's current affinity mask: f Apr 04 21:27:22 volumio-1 volumio-cpu-tweak[961]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 04 21:27:22 volumio-1 volumio-cpu-tweak[961]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 04 21:27:22 volumio-1 systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 04 21:27:23 volumio-1 nmbd[754]: [2026/04/04 21:27:23.855352, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 04 21:27:23 volumio-1 nmbd[754]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 04 21:27:23 volumio-1 systemd[1]: Started Samba NMB Daemon. Apr 04 21:27:23 volumio-1 systemd[1]: Starting Samba Winbind Daemon... Apr 04 21:27:24 volumio-1 ntpd[803]: Listen normally on 3 eth0 192.168.0.101:123 Apr 04 21:27:24 volumio-1 ntpd[803]: Listen normally on 4 wlan0 192.168.211.1:123 Apr 04 21:27:24 volumio-1 ntpd[803]: new interface(s) found: waking up resolver Apr 04 21:27:24 volumio-1 winbindd[979]: [2026/04/04 21:27:24.666532, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 04 21:27:24 volumio-1 winbindd[979]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 04 21:27:24 volumio-1 winbindd[979]: [2026/04/04 21:27:24.701030, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 04 21:27:24 volumio-1 winbindd[979]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 04 21:27:24 volumio-1 systemd[1]: Started Samba Winbind Daemon. Apr 04 21:27:24 volumio-1 systemd[1]: Starting Samba SMB Daemon... Apr 04 21:27:25 volumio-1 smbd[984]: [2026/04/04 21:27:25.686832, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 04 21:27:25 volumio-1 smbd[984]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 04 21:27:25 volumio-1 systemd[1]: Started Samba SMB Daemon. Apr 04 21:27:26 volumio-1 volumio-time-update[641]: volumio-time-update-util: Fetching time from Volumio... Apr 04 21:27:26 volumio-1 volumio-time-update[641]: volumio-time-update-util: Setting system time to: 2026-04-04 21:27:48 Apr 04 21:27:26 volumio-1 sudo[997]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-04-04 21:27:48 Apr 04 21:27:26 volumio-1 sudo[997]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:27:26 volumio-1 dbus-daemon[657]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=998 comm="timedatectl set-time 2026-04-04 21:27:48 ") Apr 04 21:27:26 volumio-1 systemd[1]: Starting Time & Date Service... Apr 04 21:27:27 volumio-1 dbus-daemon[657]: [system] Successfully activated service 'org.freedesktop.timedate1' Apr 04 21:27:27 volumio-1 systemd[1]: Started Time & Date Service. Apr 04 21:27:48 volumio-1 systemd-timedated[999]: Changed local time to Sat Apr 4 21:27:48 2026 Apr 04 21:27:48 volumio-1 sudo[997]: pam_unix(sudo:session): session closed for user root Apr 04 21:27:48 volumio-1 volumio-time-update[641]: volumio-time-update-util: System time updated successfully. Apr 04 21:27:48 volumio-1 systemd[1]: Started Volumio Time Update Utility. Apr 04 21:27:48 volumio-1 systemd[1]: Reached target Multi-User System. Apr 04 21:27:48 volumio-1 systemd[1]: Reached target Graphical Interface. Apr 04 21:27:48 volumio-1 systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 04 21:27:48 volumio-1 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 04 21:27:48 volumio-1 systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 04 21:27:48 volumio-1 systemd[1]: Startup finished in 14.101s (kernel) + 20.266s (userspace) = 34.368s. Apr 04 21:27:48 volumio-1 volumio[960]: info: ------------------------------------------- Apr 04 21:27:48 volumio-1 volumio[960]: info: ----- Volumio3 ---- Apr 04 21:27:48 volumio-1 volumio[960]: info: ------------------------------------------- Apr 04 21:27:48 volumio-1 volumio[960]: info: ----- System startup ---- Apr 04 21:27:48 volumio-1 volumio[960]: info: ------------------------------------------- Apr 04 21:27:49 volumio-1 volumio-remote-updater[627]: [2026-04-04 21:27:49] [connect] Successful connection Apr 04 21:27:50 volumio-1 volumio[960]: info: MYVOLUMIO Environment detected Apr 04 21:27:51 volumio-1 volumio[960]: info: Plugin folders cleanup Apr 04 21:27:51 volumio-1 volumio[960]: info: Scanning into folder /volumio/app/plugins/ Apr 04 21:27:51 volumio-1 volumio[960]: info: Scanning category audio_interface Apr 04 21:27:51 volumio-1 volumio[960]: info: Scanning category miscellanea Apr 04 21:27:51 volumio-1 volumio[960]: info: Scanning category music_service Apr 04 21:27:51 volumio-1 volumio[960]: info: Scanning category plugins.json Apr 04 21:27:51 volumio-1 volumio[960]: info: Scanning category system_controller Apr 04 21:27:51 volumio-1 volumio[960]: info: Scanning category user_interface Apr 04 21:27:51 volumio-1 volumio[960]: info: Scanning into folder /data/plugins/ Apr 04 21:27:51 volumio-1 volumio[960]: info: Scanning category music_service Apr 04 21:27:51 volumio-1 volumio[960]: info: Plugin folders cleanup completed Apr 04 21:27:51 volumio-1 volumio[960]: info: ------------------------------------------- Apr 04 21:27:51 volumio-1 volumio[960]: info: ----- Core plugins startup ---- Apr 04 21:27:51 volumio-1 volumio[960]: info: ------------------------------------------- Apr 04 21:27:51 volumio-1 volumio[960]: info: Loading plugins from folder /volumio/app/plugins/ Apr 04 21:27:51 volumio-1 volumio[960]: info: Adding plugin upnp to MyMusic Plugins Apr 04 21:27:51 volumio-1 volumio[960]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 04 21:27:51 volumio-1 volumio[960]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 04 21:27:51 volumio-1 volumio[960]: info: Loading plugins from folder /data/plugins/ Apr 04 21:27:51 volumio-1 volumio[960]: info: Loading plugin "system"... Apr 04 21:27:51 volumio-1 volumio[960]: info: Loading plugin "appearance"... Apr 04 21:27:53 volumio-1 sshd[1014]: Accepted password for volumio from 192.168.0.243 port 58608 ssh2 Apr 04 21:27:53 volumio-1 sshd[1014]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Apr 04 21:27:53 volumio-1 systemd[1]: Created slice User Slice of UID 1000. Apr 04 21:27:53 volumio-1 systemd[1]: Starting User Runtime Directory /run/user/1000... Apr 04 21:27:53 volumio-1 systemd-logind[654]: New session 1 of user volumio. Apr 04 21:27:53 volumio-1 volumio[960]: info: Loading plugin "network"... Apr 04 21:27:53 volumio-1 systemd[1]: Started User Runtime Directory /run/user/1000. Apr 04 21:27:53 volumio-1 systemd[1]: Starting User Manager for UID 1000... Apr 04 21:27:53 volumio-1 volumio[960]: info: Refreshing Cached IP Addresses Apr 04 21:27:53 volumio-1 systemd[1017]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Apr 04 21:27:53 volumio-1 sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 04 21:27:53 volumio-1 sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:27:53 volumio-1 sudo[1019]: pam_unix(sudo:session): session closed for user root Apr 04 21:27:53 volumio-1 sudo[1022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 04 21:27:53 volumio-1 sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:27:53 volumio-1 sudo[1022]: pam_unix(sudo:session): session closed for user root Apr 04 21:27:53 volumio-1 volumio[960]: info: Loading plugin "services"... Apr 04 21:27:54 volumio-1 volumio[960]: info: Loading plugin "volumio5onboarding"... Apr 04 21:27:54 volumio-1 sudo[1031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 04 21:27:54 volumio-1 sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:27:54 volumio-1 volumio[960]: info: Loading plugin "alsa_controller"... Apr 04 21:27:54 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 04 21:27:54 volumio-1 volumio[960]: info: Loading plugin "wizard"... Apr 04 21:27:54 volumio-1 volumio[960]: info: Loading plugin "networkfs"... Apr 04 21:27:54 volumio-1 volumio[960]: info: Starting Udev Watcher for removable devices Apr 04 21:27:54 volumio-1 systemd[1017]: Reached target Timers. Apr 04 21:27:54 volumio-1 systemd[1017]: Reached target Paths. Apr 04 21:27:54 volumio-1 systemd[1017]: Listening on GnuPG cryptographic agent and passphrase cache. Apr 04 21:27:54 volumio-1 systemd[1017]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Apr 04 21:27:54 volumio-1 systemd[1017]: Listening on Sound System. Apr 04 21:27:54 volumio-1 systemd[1017]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Apr 04 21:27:54 volumio-1 systemd[1017]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Apr 04 21:27:54 volumio-1 systemd[1017]: Listening on GnuPG network certificate management daemon. Apr 04 21:27:54 volumio-1 volumio[960]: info: Ignoring mount for partition: boot Apr 04 21:27:54 volumio-1 volumio[960]: info: Ignoring mount for partition: volumio Apr 04 21:27:54 volumio-1 volumio[960]: info: Ignoring mount for partition: volumio_data Apr 04 21:27:54 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 04 21:27:54 volumio-1 volumio[960]: info: Loading plugin "volumio_command_line_client"... Apr 04 21:27:54 volumio-1 systemd[1017]: Starting D-Bus User Message Bus Socket. Apr 04 21:27:54 volumio-1 volumio[960]: info: Loading plugin "upnp"... Apr 04 21:27:54 volumio-1 volumio[960]: info: [1775334474390] Starting Upmpd Daemon Apr 04 21:27:54 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 04 21:27:54 volumio-1 volumio[960]: info: Loading plugin "my_music"... Apr 04 21:27:54 volumio-1 volumio[960]: info: Loading plugin "mpd"... Apr 04 21:27:54 volumio-1 systemd[1017]: Listening on D-Bus User Message Bus Socket. Apr 04 21:27:54 volumio-1 systemd[1017]: Reached target Sockets. Apr 04 21:27:54 volumio-1 systemd[1017]: Reached target Basic System. Apr 04 21:27:54 volumio-1 systemd[1017]: Reached target Default. Apr 04 21:27:54 volumio-1 systemd[1]: Started User Manager for UID 1000. Apr 04 21:27:54 volumio-1 systemd[1017]: Startup finished in 442ms. Apr 04 21:27:54 volumio-1 systemd[1]: Started Session 1 of user volumio. Apr 04 21:27:54 volumio-1 sudo[1031]: pam_unix(sudo:session): session closed for user root Apr 04 21:27:55 volumio-1 volumio[960]: info: Loading plugin "upnp_browser"... Apr 04 21:27:56 volumio-1 volumio[960]: info: Starting UPNP Browser Apr 04 21:27:56 volumio-1 volumio[960]: info: Loading plugin "alarm-clock"... Apr 04 21:27:56 volumio-1 volumio[960]: info: Loading plugin "airplay_emulation"... Apr 04 21:27:56 volumio-1 volumio[960]: info: Starting Shairport Sync Apr 04 21:27:56 volumio-1 volumio[960]: info: Loading plugin "last_100"... Apr 04 21:27:56 volumio-1 volumio[960]: info: Loading plugin "webradio"... Apr 04 21:27:56 volumio-1 volumio[960]: info: Loading plugin "i2s_dacs"... Apr 04 21:27:56 volumio-1 volumio[960]: info: Loading plugin "volumiodiscovery"... Apr 04 21:27:57 volumio-1 volumio[960]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 04 21:27:57 volumio-1 volumio[960]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 04 21:27:57 volumio-1 volumio[960]: *** WARNING *** For more information see Apr 04 21:27:57 volumio-1 node[960]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 04 21:27:57 volumio-1 volumio[960]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 04 21:27:57 volumio-1 volumio[960]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 04 21:27:57 volumio-1 volumio[960]: *** WARNING *** For more information see Apr 04 21:27:57 volumio-1 node[960]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 04 21:27:57 volumio-1 node[960]: *** WARNING *** For more information see Apr 04 21:27:57 volumio-1 node[960]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 04 21:27:57 volumio-1 node[960]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 04 21:27:57 volumio-1 node[960]: *** WARNING *** For more information see Apr 04 21:27:57 volumio-1 volumio[960]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 04 21:27:57 volumio-1 volumio[960]: info: Discovery: Started advertising with name: Volumio-1 Apr 04 21:27:57 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 04 21:27:57 volumio-1 volumio[960]: info: Loading plugin "mixcloud"... Apr 04 21:27:57 volumio-1 sshd[1056]: Did not receive identification string from 192.168.0.243 port 47532 Apr 04 21:27:57 volumio-1 sshd[1065]: Did not receive identification string from 192.168.0.243 port 47550 Apr 04 21:27:57 volumio-1 sshd[1066]: Did not receive identification string from 192.168.0.243 port 47548 Apr 04 21:27:58 volumio-1 volumio[960]: info: Loading plugin "outputs"... Apr 04 21:27:58 volumio-1 volumio[960]: info: Loading plugin "albumart"... Apr 04 21:27:58 volumio-1 volumio[960]: info: Plugin example_plugin is not enabled Apr 04 21:27:58 volumio-1 volumio[960]: info: Loading plugin "inputs"... Apr 04 21:27:58 volumio-1 volumio[960]: info: Loading plugin "updater_comm"... Apr 04 21:27:59 volumio-1 volumio[960]: info: Plugin mpdemulation is not enabled Apr 04 21:27:59 volumio-1 volumio[960]: info: Loading plugin "rest_api"... Apr 04 21:27:59 volumio-1 volumio[960]: info: Loading plugin "websocket"... Apr 04 21:27:59 volumio-1 volumio[960]: info: Starting Socket.io Server version 2.3.0 Apr 04 21:27:59 volumio-1 volumio[960]: info: Loading i18n strings for locale en Apr 04 21:27:59 volumio-1 volumio[960]: Updating browse sources language Apr 04 21:27:59 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 21:27:59 volumio-1 volumio[960]: Forking 3 albumart workers Apr 04 21:28:00 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 21:28:00 volumio-1 volumio[960]: info: CoreCommandRouter::initPlayerControls Apr 04 21:28:00 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:00 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:00 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:00 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:00 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:00 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:00 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:00 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:00 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 21:28:00 volumio-1 volumio[960]: Express server listening on port 3000 Apr 04 21:28:00 volumio-1 volumio[960]: [Metrics] WebUI: 13s 125.84ms Apr 04 21:28:00 volumio-1 volumio[960]: info: CoreStateMachine::resetVolumioState Apr 04 21:28:00 volumio-1 volumio[960]: info: CoreStateMachine::getcurrentVolume Apr 04 21:28:00 volumio-1 volumio[960]: info: CoreCommandRouter::volumioRetrievevolume Apr 04 21:28:00 volumio-1 volumio[960]: info: Volumio Network Manager: Network status updated: 1 Apr 04 21:28:00 volumio-1 volumio[960]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.243 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Apr 04 21:28:00 volumio-1 volumio[960]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.243 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Apr 04 21:28:00 volumio-1 volumio[960]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.243 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Apr 04 21:28:00 volumio-1 volumio[960]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.243 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Apr 04 21:28:00 volumio-1 volumio[960]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.243 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Apr 04 21:28:00 volumio-1 volumio[960]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.243 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 04 21:28:00 volumio-1 volumio[960]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.243 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 04 21:28:00 volumio-1 volumio[960]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.243 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 04 21:28:00 volumio-1 volumio[960]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.243 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Apr 04 21:28:00 volumio-1 volumio[960]: info: Reloading queue from file Apr 04 21:28:00 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:01 volumio-1 volumio[960]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.243 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Apr 04 21:28:01 volumio-1 volumio[960]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.243 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Apr 04 21:28:01 volumio-1 volumio[960]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.243 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Apr 04 21:28:01 volumio-1 volumio[960]: info: VolumeController:: Volume=32 Mute =false Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreStateMachine::pushState Apr 04 21:28:01 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreCommandRouter::volumioPushState Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreStateMachine::updateTrackBlock Apr 04 21:28:01 volumio-1 volumio[960]: info: CorePlayQueue::getTrackBlock Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreCommandRouter::volumioRetrievevolume Apr 04 21:28:01 volumio-1 volumio[960]: Starting albumart workers Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreStateMachine::setRepeat true single undefined Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreStateMachine::pushState Apr 04 21:28:01 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreCommandRouter::volumioPushState Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreStateMachine::setRandom true Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreStateMachine::pushState Apr 04 21:28:01 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreCommandRouter::volumioPushState Apr 04 21:28:01 volumio-1 volumio[960]: info: Setting Device type: Raspberry PI Apr 04 21:28:01 volumio-1 volumio[960]: Starting albumart workers Apr 04 21:28:01 volumio-1 volumio[960]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.243 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Apr 04 21:28:01 volumio-1 volumio[960]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.243 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Apr 04 21:28:01 volumio-1 volumio[960]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.243 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Apr 04 21:28:01 volumio-1 volumio[960]: Starting albumart workers Apr 04 21:28:01 volumio-1 volumio[960]: info: Completed loading Core Plugins Apr 04 21:28:01 volumio-1 volumio[960]: info: Preparing to generate the ALSA configuration file Apr 04 21:28:01 volumio-1 volumio[960]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 04 21:28:01 volumio-1 volumio[960]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Apr 04 21:28:01 volumio-1 volumio[960]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 04 21:28:01 volumio-1 volumio[960]: info: Discovery: adding fbee92b6-26c5-4bdb-8f2e-719629ede871 Apr 04 21:28:01 volumio-1 volumio[960]: info: Discovery: Found device Volumio-1 Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:01 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:01 volumio-1 volumio[960]: info: Discovery: this is already registered, fbee92b6-26c5-4bdb-8f2e-719629ede871 Apr 04 21:28:01 volumio-1 volumio[960]: info: Discovery: Found device Volumio-1 Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:01 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:01 volumio-1 volumio[960]: info: VolumeController:: Volume=32 Mute =false Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreStateMachine::pushState Apr 04 21:28:01 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreCommandRouter::volumioPushState Apr 04 21:28:01 volumio-1 volumio[960]: info: Asound.conf file written Apr 04 21:28:01 volumio-1 sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 04 21:28:01 volumio-1 sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:01 volumio-1 sudo[1111]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:01 volumio-1 volumio[960]: info: Output device has changed, restarting MPD Apr 04 21:28:01 volumio-1 sudo[1116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 04 21:28:01 volumio-1 sudo[1116]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:01 volumio-1 volumio[960]: info: Output device has changed, restarting Shairport Sync Apr 04 21:28:01 volumio-1 sudo[1116]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:01 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:02 volumio-1 sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 04 21:28:02 volumio-1 sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:02 volumio-1 volumio[960]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 04 21:28:02 volumio-1 volumio[960]: info: ___________ START PLUGINS ___________ Apr 04 21:28:02 volumio-1 systemd[1]: Listening on mpd.socket. Apr 04 21:28:02 volumio-1 systemd[1]: Starting Music Player Daemon... Apr 04 21:28:02 volumio-1 volumio[960]: info: ControllerMpd::onStart: Initializing MPD Apr 04 21:28:02 volumio-1 volumio[960]: info: Creating MPD Configuration file Apr 04 21:28:02 volumio-1 sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Apr 04 21:28:02 volumio-1 sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:02 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 04 21:28:02 volumio-1 systemd[1]: systemd-fsckd.service: Succeeded. Apr 04 21:28:02 volumio-1 volumio[960]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 04 21:28:02 volumio-1 volumio[960]: info: [1775334482395] CoreMusicLibrary::Adding element Media Servers Apr 04 21:28:02 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 21:28:02 volumio-1 volumio[960]: info: UPNP Browser: Client initialized successfully Apr 04 21:28:02 volumio-1 sudo[1130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 04 21:28:02 volumio-1 sudo[1130]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:02 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:02 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:02 volumio-1 sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 04 21:28:02 volumio-1 sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:02 volumio-1 systemd[1]: Started Volumio5 Onboarding Server. Apr 04 21:28:02 volumio-1 sudo[1130]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:02 volumio-1 sudo[1125]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 04 21:28:02 volumio-1 sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:02 volumio-1 sudo[1125]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 04 21:28:02 volumio-1 sudo[1125]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:02 volumio-1 sudo[1127]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:02 volumio-1 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 04 21:28:02 volumio-1 systemd[1]: mpd.service: Succeeded. Apr 04 21:28:02 volumio-1 systemd[1]: Stopped Music Player Daemon. Apr 04 21:28:02 volumio-1 systemd[1]: Starting Music Player Daemon... Apr 04 21:28:02 volumio-1 volumio[960]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 04 21:28:02 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:02 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:02 volumio-1 sudo[1149]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 04 21:28:02 volumio-1 sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:02 volumio-1 sudo[1149]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 04 21:28:02 volumio-1 sudo[1149]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:02 volumio-1 volumio[960]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 04 21:28:02 volumio-1 volumio[960]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 04 21:28:02 volumio-1 volumio[960]: info: [1775334482990] CoreMusicLibrary::Adding element Last_100 Apr 04 21:28:02 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 21:28:02 volumio-1 volumio[960]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 04 21:28:02 volumio-1 volumio[960]: info: [1775334482998] CoreMusicLibrary::Adding element Webradio Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 04 21:28:03 volumio-1 volumio[960]: info: Initializing BBC Radios Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 04 21:28:03 volumio-1 volumio[960]: info: [1775334483234] CoreMusicLibrary::Adding element Mixcloud Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 21:28:03 volumio-1 volumio[960]: Cannot find translation for source Mixcloud Apr 04 21:28:03 volumio-1 volumio[960]: info: Volumio Calling Home Apr 04 21:28:03 volumio-1 volumio5-onboarding[1133]: time=2026-04-04T21:28:03.630+01:00 level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Apr 04 21:28:03 volumio-1 volumio[960]: info: MPD Permissions set Apr 04 21:28:03 volumio-1 volumio[960]: info: MPD Permissions set Apr 04 21:28:03 volumio-1 volumio[960]: 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: 16 Apr 04 21:28:03 volumio-1 volumio[960]: info: Volumio called home Apr 04 21:28:03 volumio-1 volumio[960]: 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: 16 Apr 04 21:28:03 volumio-1 volumio[960]: info: Received Get System Info Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 21:28:03 volumio-1 volumio[960]: info: Discovery: Getting this device information Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:03 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 21:28:03 volumio-1 volumio5-onboarding[1133]: time=2026-04-04T21:28:03.916+01:00 level=INFO msg="system info for 74c5df7bf7f77a44a49186f2f6f4d875" deviceName=Volumio-1 deviceVariant=volumio deviceModel= softwareVersion=3.912 Apr 04 21:28:03 volumio-1 volumio[960]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:03 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:04 volumio-1 volumio[960]: info: Received Get System Info Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 21:28:04 volumio-1 volumio[960]: info: Discovery: Getting this device information Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:04 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 04 21:28:04 volumio-1 volumio5-onboarding[1133]: time=2026-04-04T21:28:04.195+01:00 level=INFO msg="enabling local network discovery" Apr 04 21:28:04 volumio-1 volumio5-onboarding[1133]: time=2026-04-04T21:28:04.241+01:00 level=INFO msg="enabling BLE discovery" Apr 04 21:28:04 volumio-1 volumio[960]: info: Starting Shairport Sync Apr 04 21:28:04 volumio-1 volumio[960]: info: Starting Shairport Sync Apr 04 21:28:04 volumio-1 volumio[960]: info: Starting Shairport Sync Apr 04 21:28:04 volumio-1 bluetoothd[794]: src/adapter.c:store_adapter_info() Unable set contents for /usr/var/lib/bluetooth/B8:27:EB:23:D6:95/settings: (Failed to create file “/usr/var/lib/bluetooth/B8:27:EB:23:D6:95/settings.QGYDN3”: No such file or directory) Apr 04 21:28:04 volumio-1 sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 04 21:28:04 volumio-1 sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:04 volumio-1 volumio5-onboarding[1133]: time=2026-04-04T21:28:04.372+01:00 level=INFO msg="bootstrapping state" hasInternet=true Apr 04 21:28:04 volumio-1 sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 04 21:28:04 volumio-1 sudo[1222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 04 21:28:04 volumio-1 sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:04 volumio-1 sudo[1222]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:04 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:04 volumio-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 04 21:28:04 volumio-1 systemd[1]: shairport-sync.service: Succeeded. Apr 04 21:28:04 volumio-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 04 21:28:04 volumio-1 sudo[1227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 04 21:28:04 volumio-1 sudo[1227]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:04 volumio-1 volumio[960]: info: Received Get System Info Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 21:28:04 volumio-1 sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 04 21:28:04 volumio-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 21:28:04 volumio-1 volumio[960]: info: Discovery: Getting this device information Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:04 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:04 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 21:28:04 volumio-1 sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:04 volumio-1 sudo[1227]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:04 volumio-1 sudo[1230]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:04 volumio-1 sudo[1222]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:04 volumio-1 sudo[1220]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:04 volumio-1 sudo[1218]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:04 volumio-1 volumio-remote-updater[627]: [2026-04-04 21:28:04] [connect] Successful connection Apr 04 21:28:04 volumio-1 sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 04 21:28:04 volumio-1 sudo[1236]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:04 volumio-1 sudo[1236]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:04 volumio-1 volumio[960]: info: Shairport-Sync Started Apr 04 21:28:04 volumio-1 volumio[960]: Error adding Membership: Error: addMembership EINVAL Apr 04 21:28:04 volumio-1 volumio[960]: info: Shairport-Sync Started Apr 04 21:28:04 volumio-1 volumio[960]: info: Shairport-Sync Started Apr 04 21:28:04 volumio-1 sudo[1238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 04 21:28:04 volumio-1 sudo[1238]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:04 volumio-1 sudo[1238]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:04 volumio-1 volumio-remote-updater[627]: [2026-04-04 21:28:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1775334484 101 Apr 04 21:28:04 volumio-1 volumio[960]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 18 Apr 04 21:28:04 volumio-1 volumio[960]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.243 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Apr 04 21:28:04 volumio-1 sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 04 21:28:04 volumio-1 sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:05 volumio-1 systemd[1]: Started UPnP Renderer front-end to MPD. Apr 04 21:28:05 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 04 21:28:05 volumio-1 sudo[1242]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:05 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetVisibleSources Apr 04 21:28:05 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 21:28:05 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:05 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:05 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 04 21:28:05 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 04 21:28:05 volumio-1 volumio[960]: info: Received Get System Info Apr 04 21:28:05 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 21:28:05 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 21:28:05 volumio-1 volumio[960]: info: Discovery: Getting this device information Apr 04 21:28:05 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:05 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:05 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 21:28:05 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:05 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:05 volumio-1 volumio[960]: info: Listing playlists Apr 04 21:28:05 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 04 21:28:05 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 04 21:28:05 volumio-1 volumio[960]: info: Upmpdcli Daemon Started Apr 04 21:28:05 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:05 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:06 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 04 21:28:06 volumio-1 volumio[960]: info: Received Get System Info Apr 04 21:28:06 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 21:28:06 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 21:28:06 volumio-1 volumio[960]: info: Discovery: Getting this device information Apr 04 21:28:06 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:06 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:06 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 21:28:06 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 04 21:28:06 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 04 21:28:07 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 04 21:28:07 volumio-1 volumio[960]: info: Received Get System Info Apr 04 21:28:07 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 21:28:07 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 21:28:07 volumio-1 volumio[960]: info: Discovery: Getting this device information Apr 04 21:28:07 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:07 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:07 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 21:28:07 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 04 21:28:08 volumio-1 mpd[1175]: Apr 04 21:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 04 21:28:08 volumio-1 systemd[1]: Started Music Player Daemon. Apr 04 21:28:08 volumio-1 volumio[960]: Upnp client error: Error: This socket has been ended by the other party Apr 04 21:28:08 volumio-1 sudo[1119]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:08 volumio-1 sudo[1132]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:08 volumio-1 volumio[960]: info: Completed starting Core Plugins Apr 04 21:28:08 volumio-1 volumio[960]: info: ------------------------------------------- Apr 04 21:28:08 volumio-1 volumio[960]: info: ----- MyVolumio plugins startup ---- Apr 04 21:28:08 volumio-1 volumio[960]: info: ------------------------------------------- Apr 04 21:28:08 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 04 21:28:08 volumio-1 volumio[960]: error: MPD error: The expression evaluated to a falsy value: Apr 04 21:28:08 volumio-1 volumio[960]: assert.ok(self.idling) Apr 04 21:28:08 volumio-1 volumio[960]: error: The expression evaluated to a falsy value: Apr 04 21:28:08 volumio-1 volumio[960]: assert.ok(self.idling) Apr 04 21:28:08 volumio-1 volumio[960]: error: updateQueue error: null Apr 04 21:28:08 volumio-1 volumio[960]: info: MPD running with PID1175 Apr 04 21:28:08 volumio-1 volumio[960]: ,establishing connection Apr 04 21:28:08 volumio-1 volumio[960]: error: updateQueue error: null Apr 04 21:28:09 volumio-1 volumio[1245]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 04 21:28:13 volumio-1 volumio[960]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 04 21:28:14 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 04 21:28:16 volumio-1 volumio[960]: verbose: New Socket.io Connection to 192.168.0.101 from 192.168.0.243 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 Apr 04 21:28:16 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:16 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:16 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetQueue Apr 04 21:28:16 volumio-1 volumio[960]: info: CoreStateMachine::getQueue Apr 04 21:28:16 volumio-1 volumio[960]: info: CorePlayQueue::getQueue Apr 04 21:28:16 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Apr 04 21:28:16 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Apr 04 21:28:16 volumio-1 volumio[960]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Apr 04 21:28:16 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Apr 04 21:28:16 volumio-1 volumio[960]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Apr 04 21:28:16 volumio-1 volumio[960]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Apr 04 21:28:16 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 04 21:28:16 volumio-1 volumio[960]: info: Adding plugin bluetooth to MyMusic Plugins Apr 04 21:28:16 volumio-1 volumio[960]: info: Adding plugin multiroom to MyMusic Plugins Apr 04 21:28:16 volumio-1 volumio[960]: info: Adding plugin metavolumio to MyMusic Plugins Apr 04 21:28:16 volumio-1 volumio[960]: info: Adding plugin cd_controller to MyMusic Plugins Apr 04 21:28:16 volumio-1 volumio[960]: info: Adding plugin qobuzconnect to MyMusic Plugins Apr 04 21:28:16 volumio-1 volumio[960]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 04 21:28:16 volumio-1 volumio[960]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 04 21:28:16 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 04 21:28:18 volumio-1 systemd[1]: systemd-timedated.service: Succeeded. Apr 04 21:28:19 volumio-1 volumio[960]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 04 21:28:19 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 04 21:28:19 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:19 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:19 volumio-1 volumio[960]: info: Starting MyVolumio Remote Streaming Endpoints Apr 04 21:28:19 volumio-1 volumio[960]: info: MyVolumio login type: Token Apr 04 21:28:19 volumio-1 volumio[960]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 04 21:28:19 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 04 21:28:22 volumio-1 volumio[960]: info: Starting Streaming Service Transparent Proxy Apr 04 21:28:22 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 04 21:28:22 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 04 21:28:22 volumio-1 volumio[960]: info: Streaming services startup Apr 04 21:28:22 volumio-1 volumio[960]: info: Starting Streaming Daemon Apr 04 21:28:22 volumio-1 sudo[1288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 04 21:28:22 volumio-1 sudo[1288]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:22 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 04 21:28:22 volumio-1 sudo[1288]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:22 volumio-1 volumio[960]: error: Cannot start Volumio Streaming Daemon Apr 04 21:28:22 volumio-1 volumio[960]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 04 21:28:22 volumio-1 volumio[960]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 04 21:28:23 volumio-1 volumio[960]: error: MyVolumio Custom Token format not valid, refreshing it Apr 04 21:28:23 volumio-1 volumio[960]: info: MyVolumio login type: Token Apr 04 21:28:24 volumio-1 volumio[960]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 04 21:28:24 volumio-1 volumio[960]: STREAMING PROXY: Starting server on port 3245 Apr 04 21:28:24 volumio-1 volumio[960]: Node JS runtime: 14 Apr 04 21:28:24 volumio-1 volumio[960]: info: MyVolumio token set successfully Apr 04 21:28:24 volumio-1 volumio[960]: info: MYVOLUMIO: Adding device Apr 04 21:28:24 volumio-1 volumio[960]: info: MYVOLUMIO: Evaluating Server Apr 04 21:28:25 volumio-1 volumio[960]: info: MyVolumio Plan changed: superstar Apr 04 21:28:25 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Apr 04 21:28:25 volumio-1 volumio[960]: info: Adding audio output: Apr 04 21:28:25 volumio-1 volumio[960]: info: MYVOLUMIO: Adding device Apr 04 21:28:25 volumio-1 volumio[960]: info: MYVOLUMIO: Evaluating Server Apr 04 21:28:25 volumio-1 volumio[960]: info: Remote config written successfully Apr 04 21:28:25 volumio-1 volumio[960]: info: Starting Tunnel 1 Apr 04 21:28:25 volumio-1 volumio[960]: info: Starting Tunnel Connection Checker Apr 04 21:28:25 volumio-1 volumio[960]: info: Completed starting MyVolumio Plugin Apr 04 21:28:26 volumio-1 volumio[960]: info: MYVolumio Device enabled Apr 04 21:28:26 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 04 21:28:26 volumio-1 volumio[960]: info: MyVolumio status changed Apr 04 21:28:26 volumio-1 volumio[960]: info: Streaming services startup Apr 04 21:28:26 volumio-1 volumio[960]: info: Starting Streaming Daemon Apr 04 21:28:26 volumio-1 sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 04 21:28:26 volumio-1 sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:26 volumio-1 volumio[960]: info: Setting Geolocation for MyVolumio to eu3 Apr 04 21:28:26 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:26 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:26 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:26 volumio-1 sudo[1348]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:26 volumio-1 volumio[960]: error: Cannot start Volumio Streaming Daemon Apr 04 21:28:26 volumio-1 volumio[960]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 04 21:28:26 volumio-1 volumio[960]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 04 21:28:26 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 04 21:28:26 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Apr 04 21:28:26 volumio-1 volumio[960]: info: [1775334506588] Starting BluetoothController Apr 04 21:28:26 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Apr 04 21:28:29 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Apr 04 21:28:30 volumio-1 sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 04 21:28:30 volumio-1 sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:30 volumio-1 sudo[1351]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:30 volumio-1 volumio[960]: info: MRS: MultiRoom plugin initialized Apr 04 21:28:30 volumio-1 volumio[960]: info: MRS: STOPPING SNAPCLIENT Apr 04 21:28:30 volumio-1 volumio[960]: info: MRS: Snap server stop Apr 04 21:28:30 volumio-1 sudo[1367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 04 21:28:30 volumio-1 sudo[1367]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:30 volumio-1 volumio[960]: info: MRS: STOPPING volumioStreaming Apr 04 21:28:30 volumio-1 sudo[1369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 04 21:28:30 volumio-1 sudo[1369]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:30 volumio-1 sudo[1372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 04 21:28:30 volumio-1 sudo[1367]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:30 volumio-1 sudo[1372]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:30 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Apr 04 21:28:30 volumio-1 sudo[1376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 04 21:28:30 volumio-1 sudo[1376]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:30 volumio-1 sudo[1369]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:30 volumio-1 sudo[1376]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:30 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Apr 04 21:28:30 volumio-1 sudo[1372]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:30 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Apr 04 21:28:30 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Apr 04 21:28:30 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Apr 04 21:28:30 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Apr 04 21:28:30 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Apr 04 21:28:30 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Apr 04 21:28:30 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Apr 04 21:28:31 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Apr 04 21:28:31 volumio-1 volumio[960]: info: Preparing to generate the ALSA configuration file Apr 04 21:28:31 volumio-1 volumio[960]: info: Updating MyVolumio device info Apr 04 21:28:31 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:31 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:31 volumio-1 volumio[960]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 04 21:28:31 volumio-1 volumio[960]: info: Reading ALSA contributions from plugins. Apr 04 21:28:31 volumio-1 volumio[960]: info: Setting Geolocation for MyVolumio to eu2 Apr 04 21:28:31 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:31 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:31 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:31 volumio-1 volumio[960]: info: MRS: Removed streaming files Apr 04 21:28:31 volumio-1 volumio[960]: info: MRS: volumioStreaming STOPPED Apr 04 21:28:31 volumio-1 volumio[960]: info: MRS: SNAPSERVER STOPPED Apr 04 21:28:31 volumio-1 volumio[960]: info: MRS: SNAPCLIENT STOPPED Apr 04 21:28:31 volumio-1 volumio[960]: info: Asound.conf file written Apr 04 21:28:31 volumio-1 sudo[1383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 04 21:28:31 volumio-1 sudo[1383]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:31 volumio-1 sudo[1386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 04 21:28:31 volumio-1 sudo[1386]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:31 volumio-1 systemd[1]: Started MyVolumio SSH Tunnel. Apr 04 21:28:31 volumio-1 sudo[1386]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:31 volumio-1 sudo[1383]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:32 volumio-1 autossh[1390]: port set to 0, monitoring disabled Apr 04 21:28:32 volumio-1 autossh[1390]: starting ssh (count 1) Apr 04 21:28:32 volumio-1 autossh[1390]: ssh child pid is 1394 Apr 04 21:28:32 volumio-1 volumio[960]: info: Output device has changed, restarting MPD Apr 04 21:28:32 volumio-1 sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 04 21:28:32 volumio-1 sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:32 volumio-1 volumio[960]: info: Output device has changed, restarting Shairport Sync Apr 04 21:28:32 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:32 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:32 volumio-1 sudo[1396]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:32 volumio-1 sudo[1398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 04 21:28:32 volumio-1 sudo[1398]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:32 volumio-1 volumio[960]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 04 21:28:32 volumio-1 volumio[960]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 04 21:28:32 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:32 volumio-1 systemd[1]: Stopping Music Player Daemon... Apr 04 21:28:32 volumio-1 volumiossh-tunnel[1387]: Warning: Permanently added '[eu3.myvolumio.org]:2222,[167.71.136.233]:2222' (RSA) to the list of known hosts. Apr 04 21:28:32 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Apr 04 21:28:32 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 04 21:28:32 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Apr 04 21:28:32 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 04 21:28:32 volumio-1 volumio[960]: info: MRS: Pushing multiroomSync output for this device Apr 04 21:28:32 volumio-1 volumio[960]: info: MRS: Pushing multiroomSync output Apr 04 21:28:32 volumio-1 volumio[960]: info: Adding audio output: Apr 04 21:28:32 volumio-1 volumio[960]: info: Adding audio output: Apr 04 21:28:32 volumio-1 volumio[960]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 04 21:28:32 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:32 volumio-1 sudo[1407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio-1 Apr 04 21:28:32 volumio-1 sudo[1407]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:32 volumio-1 sudo[1407]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:32 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Apr 04 21:28:32 volumio-1 volumio[960]: info: Adding METAVOLUMIO REST API Endpoints Apr 04 21:28:32 volumio-1 volumio[960]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 04 21:28:32 volumio-1 volumio[960]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 04 21:28:32 volumio-1 volumio[960]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 04 21:28:32 volumio-1 volumio[960]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 04 21:28:32 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Apr 04 21:28:32 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Apr 04 21:28:32 volumio-1 volumio[960]: info: Preparing CD Folders Apr 04 21:28:32 volumio-1 volumio[960]: info: Adding CD REST API Endpoints Apr 04 21:28:32 volumio-1 volumio[960]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 04 21:28:32 volumio-1 volumio[960]: info: Starting UDEV Watcher for CD Apr 04 21:28:32 volumio-1 volumio[960]: info: Detecting CD presence with UDEV Apr 04 21:28:32 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Apr 04 21:28:32 volumio-1 systemd[1]: mpd.service: Succeeded. Apr 04 21:28:32 volumio-1 systemd[1]: Stopped Music Player Daemon. Apr 04 21:28:32 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Apr 04 21:28:32 volumio-1 volumio[960]: info: Adding inputs REST Endpoints Apr 04 21:28:32 volumio-1 volumio[960]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 04 21:28:32 volumio-1 volumio[960]: info: Scanning Audio Inputs Apr 04 21:28:32 volumio-1 systemd[1]: Starting Music Player Daemon... Apr 04 21:28:32 volumio-1 volumio[960]: info: Checking against Known Cards name Apr 04 21:28:32 volumio-1 volumio[960]: info: Adding Server instance for streaming Apr 04 21:28:32 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Apr 04 21:28:32 volumio-1 volumio[960]: error: Hi Res Audio Failed Login: Missing Login Data Apr 04 21:28:32 volumio-1 volumio[960]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 04 21:28:32 volumio-1 volumio[960]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 04 21:28:32 volumio-1 volumio[960]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 04 21:28:32 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Apr 04 21:28:32 volumio-1 volumio[960]: info: Refreshing TIDAL token Apr 04 21:28:32 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Apr 04 21:28:32 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Apr 04 21:28:32 volumio-1 volumio[960]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Apr 04 21:28:32 volumio-1 volumio[960]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Apr 04 21:28:32 volumio-1 sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 04 21:28:32 volumio-1 sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:32 volumio-1 volumio[960]: info: QobuzConnect: Starting Qobuz Connect socket and service Apr 04 21:28:32 volumio-1 sudo[1412]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 04 21:28:32 volumio-1 sudo[1412]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:32 volumio-1 sudo[1412]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:32 volumio-1 sudo[1416]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:32 volumio-1 sudo[1422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 04 21:28:32 volumio-1 sudo[1422]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:32 volumio-1 volumio[960]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Apr 04 21:28:32 volumio-1 volumio[960]: info: Stopping AccessToken refresher cron for QOBUZ Apr 04 21:28:33 volumio-1 sudo[1422]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:33 volumio-1 volumio[960]: info: AccessToken refresher cron started for QOBUZ Apr 04 21:28:33 volumio-1 volumio[960]: info: Adding QOBUZ REST API Endpoints Apr 04 21:28:33 volumio-1 volumio[960]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Apr 04 21:28:33 volumio-1 volumio[960]: info: Updating MyVolumio device info Apr 04 21:28:33 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:33 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:33 volumio-1 sudo[1427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 04 21:28:33 volumio-1 sudo[1427]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:33 volumio-1 volumio[960]: info: Successfully Updated MyVolumio device Apr 04 21:28:33 volumio-1 volumio[960]: info: Successfully Added MyVolumio device Apr 04 21:28:33 volumio-1 volumio[960]: info: Successfully Added MyVolumio device Apr 04 21:28:33 volumio-1 volumio[960]: info: Bluetooth name changed to Volumio-1 Apr 04 21:28:33 volumio-1 volumio[960]: info: MPD Permissions set Apr 04 21:28:33 volumio-1 volumio[960]: info: Remote SSH Started Apr 04 21:28:33 volumio-1 systemd[1]: Started Volumio Qobuz Connect Service. Apr 04 21:28:33 volumio-1 sudo[1427]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:33 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 04 21:28:33 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:33 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 04 21:28:33 volumio-1 volumio[960]: info: Executing endpoint qc_getconfig Apr 04 21:28:33 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 04 21:28:33 volumio-1 qobuz-connect[1429]: 20260404 21:28:33.912 [1429.1429] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 04 21:28:33 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:33 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:34 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:34 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:34 volumio-1 qobuz-connect[1429]: 20260404 21:28:34.152 [1429.1429] INFO VolumeManager: [0xe6f900]: Setting new playback volume: 75 Apr 04 21:28:34 volumio-1 qobuz-connect[1429]: 20260404 21:28:34.153 [1429.1429] INFO VolumeManager: [0xe6f900]: Setting new mute state: 0 Apr 04 21:28:34 volumio-1 qobuz-connect[1429]: 20260404 21:28:34.153 [1429.1429] INFO AudioStreamManager: [0xe6f658]: Setting new audio download buffer size: 1048576 Apr 04 21:28:34 volumio-1 qobuz-connect[1429]: 20260404 21:28:34.153 [1429.1429] INFO QobuzConnect: [0xe701c8]: Client initialized! Apr 04 21:28:34 volumio-1 qobuz-connect[1429]: 20260404 21:28:34.153 [1429.1429] INFO SampleApp: Starting Avahi advertising, name: Volumio-1, service name: _qobuz-connect._tcp Apr 04 21:28:34 volumio-1 qobuz-connect[1429]: 20260404 21:28:34.182 [1429.1429] INFO LocalConfigManager: [0xe6f380]: Starting Local Configuration server Apr 04 21:28:34 volumio-1 qobuz-connect[1429]: 20260404 21:28:34.182 [1429.1429] INFO SampleApp: Starting Local configuration server Apr 04 21:28:34 volumio-1 qobuz-connect[1429]: 20260404 21:28:34.183 [1429.1429] INFO SampleApp: Connected to UNIX socket client 0xe64f60 Apr 04 21:28:34 volumio-1 qobuz-connect[1429]: 20260404 21:28:34.184 [1429.1429] INFO SampleApp: Playback volume changed: 75 Apr 04 21:28:34 volumio-1 volumio5-onboarding[1133]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Apr 04 21:28:34 volumio-1 systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 04 21:28:34 volumio-1 systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 04 21:28:34 volumio-1 systemd[1]: volumio5-onboarding.service: Service RestartSec=100ms expired, scheduling restart. Apr 04 21:28:34 volumio-1 systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Apr 04 21:28:34 volumio-1 systemd[1]: Stopped Volumio5 Onboarding Server. Apr 04 21:28:34 volumio-1 volumio[960]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 04 21:28:34 volumio-1 systemd[1]: Started Volumio5 Onboarding Server. Apr 04 21:28:34 volumio-1 volumio-remote-updater[627]: No test mode Apr 04 21:28:34 volumio-1 volumio-remote-updater[627]: No alpha test mode Apr 04 21:28:34 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 04 21:28:34 volumio-1 volumio5-onboarding[1453]: time=2026-04-04T21:28:34.677+01:00 level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Apr 04 21:28:34 volumio-1 volumio[960]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 04 21:28:34 volumio-1 volumio[960]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 04 21:28:34 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 04 21:28:34 volumio-1 volumio[960]: info: Successfully Updated MyVolumio device Apr 04 21:28:34 volumio-1 volumio[960]: info: Access Token successfully retrieved Apr 04 21:28:34 volumio-1 volumio[960]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 04 21:28:34 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 04 21:28:34 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:34 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:35 volumio-1 volumio[960]: 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: 5 Apr 04 21:28:35 volumio-1 volumio[960]: info: Starting Shairport Sync Apr 04 21:28:35 volumio-1 volumio[960]: 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: 5 Apr 04 21:28:35 volumio-1 volumio[960]: info: Received Get System Info Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 21:28:35 volumio-1 volumio[960]: info: Discovery: Getting this device information Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:35 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 21:28:35 volumio-1 sudo[1465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 04 21:28:35 volumio-1 sudo[1465]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:35 volumio-1 volumio5-onboarding[1453]: time=2026-04-04T21:28:35.068+01:00 level=INFO msg="system info for 74c5df7bf7f77a44a49186f2f6f4d875" deviceName=Volumio-1 deviceVariant=volumio deviceModel= softwareVersion=3.912 Apr 04 21:28:35 volumio-1 volumio[960]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 04 21:28:35 volumio-1 volumio[960]: info: Received Get System Info Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 21:28:35 volumio-1 volumio[960]: info: Discovery: Getting this device information Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:35 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 21:28:35 volumio-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 04 21:28:35 volumio-1 systemd[1]: shairport-sync.service: Succeeded. Apr 04 21:28:35 volumio-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:35 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 04 21:28:35 volumio-1 volumio5-onboarding[1453]: time=2026-04-04T21:28:35.167+01:00 level=INFO msg="enabling local network discovery" Apr 04 21:28:35 volumio-1 volumio5-onboarding[1453]: time=2026-04-04T21:28:35.203+01:00 level=INFO msg="enabling BLE discovery" Apr 04 21:28:35 volumio-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 04 21:28:35 volumio-1 sudo[1465]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:35 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:35 volumio-1 volumio5-onboarding[1453]: time=2026-04-04T21:28:35.339+01:00 level=INFO msg="bootstrapping state" hasInternet=true Apr 04 21:28:35 volumio-1 volumio[960]: info: Shairport-Sync Started Apr 04 21:28:35 volumio-1 volumio[960]: info: Successfully retrieved User Session From TIDAL Apr 04 21:28:35 volumio-1 sudo[1472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 04 21:28:35 volumio-1 sudo[1472]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:35 volumio-1 volumio[960]: info: Received Get System Info Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 04 21:28:35 volumio-1 volumio[960]: info: Discovery: Getting this device information Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:35 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 04 21:28:35 volumio-1 volumio-remote-updater[627]: No test mode Apr 04 21:28:35 volumio-1 volumio-remote-updater[627]: No alpha test mode Apr 04 21:28:35 volumio-1 systemd[1]: Started Volumio Bluetooth Module. Apr 04 21:28:35 volumio-1 sudo[1472]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:35 volumio-1 sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Apr 04 21:28:35 volumio-1 sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:35 volumio-1 volumiobt[1475]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Apr 04 21:28:35 volumio-1 sudo[1477]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:35 volumio-1 volumio[960]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 04 21:28:35 volumio-1 volumio[960]: info: Volumio BT Module successfully started Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 04 21:28:35 volumio-1 volumio[960]: info: Successfully retrieved User Subscription From TIDAL Apr 04 21:28:35 volumio-1 volumio[960]: info: Adding TIDAL to Browse Sources Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 04 21:28:35 volumio-1 volumio[960]: info: [1775334515846] CoreMusicLibrary::Adding element TIDAL Apr 04 21:28:35 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 04 21:28:35 volumio-1 volumio[960]: Cannot find translation for source Mixcloud Apr 04 21:28:35 volumio-1 volumio[960]: Cannot find translation for source TIDAL Apr 04 21:28:35 volumio-1 volumio[960]: info: Adding TIDAL REST API Endpoints Apr 04 21:28:35 volumio-1 volumio[960]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Apr 04 21:28:35 volumio-1 volumio[960]: info: TidalConnect service stoped! Apr 04 21:28:36 volumio-1 volumio[960]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 04 21:28:36 volumio-1 volumio[960]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 04 21:28:36 volumio-1 sudo[1489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 04 21:28:36 volumio-1 sudo[1489]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:36 volumio-1 mpd[1425]: Apr 04 21:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 04 21:28:36 volumio-1 systemd[1]: Started Volumio Tidal Connect Service. Apr 04 21:28:36 volumio-1 systemd[1]: Started Music Player Daemon. Apr 04 21:28:36 volumio-1 sudo[1398]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:36 volumio-1 sudo[1489]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:36 volumio-1 volumio[960]: error: updateQueue error: null Apr 04 21:28:36 volumio-1 volumio5-onboarding[1453]: time=2026-04-04T21:28:36.646+01:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=spotify error="could not open plugin config file for \"spop\": open /data/configuration/music_service/spop/config.json: no such file or directory" Apr 04 21:28:36 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 04 21:28:36 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 04 21:28:36 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 04 21:28:36 volumio-1 sudo[1535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Apr 04 21:28:36 volumio-1 sudo[1535]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:36 volumio-1 volumiobt[1475]: pulseaudio: no process found Apr 04 21:28:36 volumio-1 sudo[1535]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:36 volumio-1 dbus-daemon[657]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.28' (uid=1000 pid=1539 comm="/usr/bin/pulseaudio --high-priority=true ") Apr 04 21:28:36 volumio-1 systemd[1]: Starting RealtimeKit Scheduling Policy Service... Apr 04 21:28:36 volumio-1 volumio[960]: info: Executing endpoint tc_getconfig Apr 04 21:28:36 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 04 21:28:36 volumio-1 vtcs[1492]: STARTING TidalConnect services, version: 1.6.1 Apr 04 21:28:37 volumio-1 vtcs[1492]: STARTED TidalConnect services. Apr 04 21:28:37 volumio-1 dbus-daemon[657]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Apr 04 21:28:37 volumio-1 volumio[960]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 04 21:28:37 volumio-1 rtkit-daemon[1540]: Successfully called chroot. Apr 04 21:28:37 volumio-1 systemd[1]: Started RealtimeKit Scheduling Policy Service. Apr 04 21:28:37 volumio-1 rtkit-daemon[1540]: Successfully dropped privileges. Apr 04 21:28:37 volumio-1 rtkit-daemon[1540]: Successfully limited resources. Apr 04 21:28:37 volumio-1 rtkit-daemon[1540]: Running. Apr 04 21:28:37 volumio-1 rtkit-daemon[1540]: Canary thread running. Apr 04 21:28:37 volumio-1 rtkit-daemon[1540]: Watchdog thread running. Apr 04 21:28:37 volumio-1 dbus-daemon[657]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.29' (uid=0 pid=1540 comm="/usr/lib/rtkit/rtkit-daemon ") Apr 04 21:28:37 volumio-1 systemd[1]: Starting Authorization Manager... Apr 04 21:28:37 volumio-1 volumio[960]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 04 21:28:37 volumio-1 volumio[960]: info: Executing endpoint tc_connect Apr 04 21:28:37 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 04 21:28:37 volumio-1 volumio[960]: info: Connecting to TidalConnect Apr 04 21:28:37 volumio-1 volumio[960]: info: CoreCommandRouter::servicePushState Apr 04 21:28:37 volumio-1 volumio[960]: info: CoreStateMachine::pushState Apr 04 21:28:37 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:37 volumio-1 volumio[960]: info: CoreCommandRouter::volumioPushState Apr 04 21:28:37 volumio-1 volumio[960]: info: MRS: Pushing multiroomSync output update for this device Apr 04 21:28:37 volumio-1 volumio[960]: info: MRS: Pushing multiroomSync output Apr 04 21:28:37 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:37 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:37 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:37 volumio-1 volumio[960]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Apr 04 21:28:37 volumio-1 volumio[960]: info: CoreCommandRouter::servicePushState Apr 04 21:28:37 volumio-1 volumio[960]: info: CoreStateMachine::pushState Apr 04 21:28:37 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:37 volumio-1 volumio[960]: info: CoreCommandRouter::volumioPushState Apr 04 21:28:37 volumio-1 volumio[960]: info: MRS: Pushing multiroomSync output update for this device Apr 04 21:28:37 volumio-1 volumio[960]: info: MRS: Pushing multiroomSync output Apr 04 21:28:37 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetState Apr 04 21:28:37 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:37 volumio-1 volumio[960]: info: CorePlayQueue::getTrack 0 Apr 04 21:28:37 volumio-1 volumio[960]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Apr 04 21:28:37 volumio-1 polkitd[1552]: started daemon version 0.105 using authority implementation `local' version `0.105' Apr 04 21:28:37 volumio-1 dbus-daemon[657]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Apr 04 21:28:37 volumio-1 systemd[1]: Started Authorization Manager. Apr 04 21:28:37 volumio-1 volumio[960]: info: MRS: Getting audio outputs on start Apr 04 21:28:37 volumio-1 volumio[960]: info: MRS: Requesting all other devices output Apr 04 21:28:37 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 04 21:28:37 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 04 21:28:38 volumio-1 pulseaudio[1539]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 04 21:28:38 volumio-1 pulseaudio[1539]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 04 21:28:38 volumio-1 pulseaudio[1539]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Apr 04 21:28:38 volumio-1 bluetoothd[794]: Endpoint registered: sender=:1.50 path=/MediaEndpoint/A2DPSource Apr 04 21:28:38 volumio-1 bluetoothd[794]: Endpoint registered: sender=:1.50 path=/MediaEndpoint/A2DPSink Apr 04 21:28:38 volumio-1 volumiobt[1475]: Applying permissions Apr 04 21:28:38 volumio-1 volumiobt[1475]: Setting BT discoverable and pairable Apr 04 21:28:38 volumio-1 kernel: Bluetooth: RFCOMM TTY layer initialized Apr 04 21:28:38 volumio-1 kernel: Bluetooth: RFCOMM socket layer initialized Apr 04 21:28:38 volumio-1 kernel: Bluetooth: RFCOMM ver 1.11 Apr 04 21:28:38 volumio-1 volumiobt[1475]: [96B blob data] Apr 04 21:28:38 volumio-1 volumiobt[1475]: [77B blob data] Apr 04 21:28:38 volumio-1 volumiobt[1475]: [77B blob data] Apr 04 21:28:38 volumio-1 volumiobt[1475]: [33B blob data] Apr 04 21:28:38 volumio-1 volumiobt[1475]: [bluetoothctl]> -e power on Apr 04 21:28:38 volumio-1 volumiobt[1475]: Invalid command in menu main: -e Apr 04 21:28:38 volumio-1 volumiobt[1475]: Apr 04 21:28:38 volumio-1 volumiobt[1475]: Use "help" for a list of available commands in a menu. Apr 04 21:28:38 volumio-1 volumiobt[1475]: Use "menu " if you want to enter any submenu. Apr 04 21:28:38 volumio-1 volumiobt[1475]: Use "back" if you want to return to menu main. Apr 04 21:28:38 volumio-1 volumiobt[1475]: [bluetoothctl]> agent on Apr 04 21:28:38 volumio-1 volumiobt[1475]: Agent is already registered Apr 04 21:28:38 volumio-1 volumiobt[1475]: [bluetoothctl]> discoverable on Apr 04 21:28:38 volumio-1 volumiobt[1475]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Apr 04 21:28:38 volumio-1 volumiobt[1475]: [bluetoothctl]> pairable on Apr 04 21:28:38 volumio-1 volumiobt[1475]: [bluetoothctl]> agent NoInputNoOutput Apr 04 21:28:38 volumio-1 volumiobt[1475]: Agent is already registered Apr 04 21:28:38 volumio-1 volumiobt[1475]: [bluetoothctl]> default-agent Apr 04 21:28:38 volumio-1 volumiobt[1475]: [bluetoothctl]> quit Apr 04 21:28:38 volumio-1 volumiobt[1475]: [65B blob data] Apr 04 21:28:38 volumio-1 volumio5-onboarding[1453]: time=2026-04-04T21:28:38.961+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=ZWBfBWa2w4P2HqS4oR4uqDev5fA2 tokenExpiry=2026-04-04T22:28:38.961+01:00 Apr 04 21:28:39 volumio-1 volumio[960]: info: TidalConnect service started! Apr 04 21:28:39 volumio-1 volumio[960]: [Metrics] CommandRouter: 50s 566.38ms Apr 04 21:28:39 volumio-1 volumio[960]: info: CoreCommandRouter::volumiosetStartupVolume Apr 04 21:28:39 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:39 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 04 21:28:39 volumio-1 volumio[960]: info: CoreCommandRouter::Close All Modals sent Apr 04 21:28:39 volumio-1 volumio[960]: info: CoreCommandRouter::Close All Modals sent Apr 04 21:28:39 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:39 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 04 21:28:39 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 04 21:28:39 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 04 21:28:39 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 04 21:28:39 volumio-1 volumio[960]: info: CoreCommandRouter::volumioGetBrowseSources Apr 04 21:28:39 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 04 21:28:40 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 04 21:28:40 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 04 21:28:40 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 04 21:28:41 volumio-1 volumio5-onboarding[1453]: time=2026-04-04T21:28:41.287+01:00 level=INFO msg="service successfully established" component=discovery/localnet Apr 04 21:28:43 volumio-1 volumio[960]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Apr 04 21:28:43 volumio-1 volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Apr 04 21:28:43 volumio-1 sudo[1590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Apr 04 21:28:43 volumio-1 sudo[1590]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:43 volumio-1 sudo[1590]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:44 volumio-1 ntpd[803]: Soliciting pool server 178.79.155.116 Apr 04 21:28:44 volumio-1 sudo[1603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Apr 04 21:28:44 volumio-1 sudo[1603]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:44 volumio-1 sudo[1603]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:44 volumio-1 sudo[1606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 10 Apr 04 21:28:44 volumio-1 sudo[1606]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:44 volumio-1 sudo[1606]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:44 volumio-1 sudo[1609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 11 Apr 04 21:28:44 volumio-1 sudo[1609]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 04 21:28:44 volumio-1 sudo[1609]: pam_unix(sudo:session): session closed for user root Apr 04 21:28:45 volumio-1 ntpd[803]: Soliciting pool server 185.57.191.230 Apr 04 21:28:46 volumio-1 ntpd[803]: Soliciting pool server 83.151.207.133 Apr 04 21:28:46 volumio-1 ntpd[803]: Soliciting pool server 139.162.255.65 Apr 04 21:28:46 volumio-1 volumio[960]: info: BOOT COMPLETED Apr 04 21:28:47 volumio-1 ntpd[803]: Soliciting pool server 194.213.3.203 Apr 04 21:28:47 volumio-1 volumio[1245]: ..............................................................................................................................................................++++ Apr 04 21:28:48 volumio-1 ntpd[803]: Soliciting pool server 129.250.35.251 Apr 04 21:28:53 volumio-1 sudo[1674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Apr 04 21:28:53 volumio-1 sudo[1674]: pam_unix(sudo:session): session opened for user root by (uid=0)