Wed May 28 19:42:56 EEST 2025 Description="'Sending log report, please wait'" # cat /proc/version --------------- Linux version 6.12.27-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #1876 SMP Tue May 6 15:10:05 BST 2025 # cat /etc/os-release --------------- PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="1da895bfdbb6eb90d965df32a25a3568df7c84ed" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 28 07:32:00 UTC 2025" VOLUMIO_VERSION="4.002" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="0fc76cf16cbffb5d07df2b0e7f3ca424" # ifconfig --------------- eth0: flags=4163 mtu 1500 inet 192.168.1.191 netmask 255.255.255.0 broadcast 192.168.1.255 ether dc:a6:32:33:a7:9c txqueuelen 1000 (Ethernet) RX packets 0 bytes 0 (0.0 B) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 0 bytes 0 (0.0 B) 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 0 bytes 0 (0.0 B) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 0 bytes 0 (0.0 B) 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 dc:a6:32:33:a7:9d txqueuelen 1000 (Ethernet) RX packets 0 bytes 0 (0.0 B) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 0 bytes 0 (0.0 B) 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 --------------- **** List of PLAYBACK Hardware Devices **** card 0: Headphones [bcm2835 Headphones], device 0: bcm2835 Headphones [bcm2835 Headphones] Subdevices: 8/8 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 Subdevice #4: subdevice #4 Subdevice #5: subdevice #5 Subdevice #6: subdevice #6 Subdevice #7: subdevice #7 card 5: A20 [AUDIOLAB USB Audio 2.0], device 0: USB Audio [USB Audio] Subdevices: 1/1 Subdevice #0: subdevice #0 # lsusb --------------- Bus 002 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub Bus 001 Device 003: ID 2622:0040 AUDIOLAB AUDIOLAB USB Audio 2.0 Bus 001 Device 002: ID 2109:3431 VIA Labs, Inc. Hub Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub # lsusb -t --------------- /: Bus 02.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/4p, 5000M /: Bus 01.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/1p, 480M |__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/4p, 480M |__ Port 4: Dev 3, If 0, Class=Audio, Driver=snd-usb-audio, 480M |__ Port 4: Dev 3, If 1, Class=Audio, Driver=snd-usb-audio, 480M |__ Port 4: Dev 3, If 2, Class=Application Specific Interface, Driver=, 480M # lsusb -v --------------- Couldn't open device, some information will be missing Couldn't open device, some information will be missing Bus 002 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 3.00 bDeviceClass 9 Hub bDeviceSubClass 0 bDeviceProtocol 3 bMaxPacketSize0 9 idVendor 0x1d6b Linux Foundation idProduct 0x0003 3.0 root hub bcdDevice 6.12 iManufacturer 3 Linux 6.12.27-v7l+ xhci-hcd iProduct 2 xHCI Host Controller iSerial 1 0000:01:00.0 bNumConfigurations 1 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x001f 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 bMaxBurst 0 Bus 001 Device 003: ID 2622:0040 AUDIOLAB AUDIOLAB USB Audio 2.0 Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 2.00 bDeviceClass 239 Miscellaneous Device bDeviceSubClass 2 bDeviceProtocol 1 Interface Association bMaxPacketSize0 64 idVendor 0x2622 idProduct 0x0040 bcdDevice 3.30 iManufacturer 1 AUDIOLAB iProduct 2 AUDIOLAB USB Audio 2.0 iSerial 3 0000 bNumConfigurations 2 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x00b9 bNumInterfaces 3 bConfigurationValue 1 iConfiguration 0 bmAttributes 0x80 (Bus Powered) MaxPower 500mA Interface Association: bLength 8 bDescriptorType 11 bFirstInterface 0 bInterfaceCount 2 bFunctionClass 1 Audio bFunctionSubClass 0 bFunctionProtocol 32 iFunction 0 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 1 bInterfaceClass 1 Audio bInterfaceSubClass 1 Control Device bInterfaceProtocol 32 iInterface 2 AudioControl Interface Descriptor: bLength 9 bDescriptorType 36 bDescriptorSubtype 1 (HEADER) bcdADC 2.00 bCategory 8 wTotalLength 0x0048 bmControls 0x00 AudioControl Interface Descriptor: bLength 8 bDescriptorType 36 bDescriptorSubtype 10 (CLOCK_SOURCE) bClockID 41 bmAttributes 3 Internal programmable clock bmControls 0x07 Clock Frequency Control (read/write) Clock Validity Control (read-only) bAssocTerminal 0 iClockSource 9 AudioControl Interface Descriptor: bLength 8 bDescriptorType 36 bDescriptorSubtype 11 (CLOCK_SELECTOR) bClockID 40 bNrInPins 1 baCSourceID(0) 41 bmControls 0x03 Clock Selector Control (read/write) iClockSelector 8 AudioControl Interface Descriptor: bLength 17 bDescriptorType 36 bDescriptorSubtype 2 (INPUT_TERMINAL) bTerminalID 2 wTerminalType 0x0101 USB Streaming bAssocTerminal 0 bCSourceID 40 bNrChannels 2 bmChannelConfig 0x00000000 iChannelNames 15 bmControls 0x0000 iTerminal 6 AudioControl Interface Descriptor: bLength 18 bDescriptorType 36 bDescriptorSubtype 6 (FEATURE_UNIT) bUnitID 10 bSourceID 2 bmaControls(0) 0x0000000f Mute Control (read/write) Volume Control (read/write) bmaControls(1) 0x0000000f Mute Control (read/write) Volume Control (read/write) bmaControls(2) 0x0000000f Mute Control (read/write) Volume Control (read/write) iFeature 0 AudioControl Interface Descriptor: bLength 12 bDescriptorType 36 bDescriptorSubtype 3 (OUTPUT_TERMINAL) bTerminalID 20 wTerminalType 0x0301 Speaker bAssocTerminal 0 bSourceID 10 bCSourceID 40 bmControls 0x0000 iTerminal 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x84 EP 4 IN bmAttributes 3 Transfer Type Interrupt Synch Type None Usage Type Data wMaxPacketSize 0x0006 1x 6 bytes bInterval 8 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 1 bAlternateSetting 0 bNumEndpoints 0 bInterfaceClass 1 Audio bInterfaceSubClass 2 Streaming bInterfaceProtocol 32 iInterface 4 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 1 bAlternateSetting 1 bNumEndpoints 2 bInterfaceClass 1 Audio bInterfaceSubClass 2 Streaming bInterfaceProtocol 32 iInterface 4 AudioStreaming Interface Descriptor: bLength 16 bDescriptorType 36 bDescriptorSubtype 1 (AS_GENERAL) bTerminalLink 2 bmControls 0x00 bFormatType 1 bmFormats 0x00000001 PCM bNrChannels 2 bmChannelConfig 0x00000000 iChannelNames 15 AudioStreaming Interface Descriptor: bLength 6 bDescriptorType 36 bDescriptorSubtype 2 (FORMAT_TYPE) bFormatType 1 (FORMAT_TYPE_I) bSubslotSize 4 bBitResolution 24 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x01 EP 1 OUT bmAttributes 5 Transfer Type Isochronous Synch Type Asynchronous Usage Type Data wMaxPacketSize 0x0400 1x 1024 bytes bInterval 1 AudioStreaming Endpoint Descriptor: bLength 8 bDescriptorType 37 bDescriptorSubtype 1 (EP_GENERAL) bmAttributes 0x00 bmControls 0x00 bLockDelayUnits 2 Decoded PCM samples wLockDelay 0x0008 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 17 Transfer Type Isochronous Synch Type None Usage Type Feedback wMaxPacketSize 0x0004 1x 4 bytes bInterval 4 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 2 bAlternateSetting 0 bNumEndpoints 0 bInterfaceClass 254 Application Specific Interface bInterfaceSubClass 1 Device Firmware Update bInterfaceProtocol 1 iInterface 12 Device Firmware Upgrade Interface Descriptor: bLength 9 bDescriptorType 33 bmAttributes 7 Will Not Detach Manifestation Tolerant Upload Supported Download Supported wDetachTimeout 250 milliseconds wTransferSize 64 bytes bcdDFUVersion 1.10 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x00b9 bNumInterfaces 3 bConfigurationValue 1 iConfiguration 0 bmAttributes 0x80 (Bus Powered) MaxPower 500mA Interface Association: bLength 8 bDescriptorType 11 bFirstInterface 0 bInterfaceCount 2 bFunctionClass 1 Audio bFunctionSubClass 0 bFunctionProtocol 32 iFunction 0 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 1 bInterfaceClass 1 Audio bInterfaceSubClass 1 Control Device bInterfaceProtocol 32 iInterface 2 AudioControl Interface Descriptor: bLength 9 bDescriptorType 36 bDescriptorSubtype 1 (HEADER) bcdADC 2.00 bCategory 8 wTotalLength 0x0048 bmControls 0x00 AudioControl Interface Descriptor: bLength 8 bDescriptorType 36 bDescriptorSubtype 10 (CLOCK_SOURCE) bClockID 41 bmAttributes 3 Internal programmable clock bmControls 0x07 Clock Frequency Control (read/write) Clock Validity Control (read-only) bAssocTerminal 0 iClockSource 9 AudioControl Interface Descriptor: bLength 8 bDescriptorType 36 bDescriptorSubtype 11 (CLOCK_SELECTOR) bClockID 40 bNrInPins 1 baCSourceID(0) 41 bmControls 0x03 Clock Selector Control (read/write) iClockSelector 8 AudioControl Interface Descriptor: bLength 17 bDescriptorType 36 bDescriptorSubtype 2 (INPUT_TERMINAL) bTerminalID 2 wTerminalType 0x0101 USB Streaming bAssocTerminal 0 bCSourceID 40 bNrChannels 2 bmChannelConfig 0x00000000 iChannelNames 15 bmControls 0x0000 iTerminal 6 AudioControl Interface Descriptor: bLength 18 bDescriptorType 36 bDescriptorSubtype 6 (FEATURE_UNIT) bUnitID 10 bSourceID 2 bmaControls(0) 0x0000000f Mute Control (read/write) Volume Control (read/write) bmaControls(1) 0x0000000f Mute Control (read/write) Volume Control (read/write) bmaControls(2) 0x0000000f Mute Control (read/write) Volume Control (read/write) iFeature 0 AudioControl Interface Descriptor: bLength 12 bDescriptorType 36 bDescriptorSubtype 3 (OUTPUT_TERMINAL) bTerminalID 20 wTerminalType 0x0301 Speaker bAssocTerminal 0 bSourceID 10 bCSourceID 40 bmControls 0x0000 iTerminal 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x84 EP 4 IN bmAttributes 3 Transfer Type Interrupt Synch Type None Usage Type Data wMaxPacketSize 0x0006 1x 6 bytes bInterval 8 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 1 bAlternateSetting 0 bNumEndpoints 0 bInterfaceClass 1 Audio bInterfaceSubClass 2 Streaming bInterfaceProtocol 32 iInterface 4 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 1 bAlternateSetting 1 bNumEndpoints 2 bInterfaceClass 1 Audio bInterfaceSubClass 2 Streaming bInterfaceProtocol 32 iInterface 4 AudioStreaming Interface Descriptor: bLength 16 bDescriptorType 36 bDescriptorSubtype 1 (AS_GENERAL) bTerminalLink 2 bmControls 0x00 bFormatType 1 bmFormats 0x00000001 PCM bNrChannels 2 bmChannelConfig 0x00000000 iChannelNames 15 AudioStreaming Interface Descriptor: bLength 6 bDescriptorType 36 bDescriptorSubtype 2 (FORMAT_TYPE) bFormatType 1 (FORMAT_TYPE_I) bSubslotSize 4 bBitResolution 24 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x01 EP 1 OUT bmAttributes 5 Transfer Type Isochronous Synch Type Asynchronous Usage Type Data wMaxPacketSize 0x0400 1x 1024 bytes bInterval 1 AudioStreaming Endpoint Descriptor: bLength 8 bDescriptorType 37 bDescriptorSubtype 1 (EP_GENERAL) bmAttributes 0x00 bmControls 0x00 bLockDelayUnits 2 Decoded PCM samples wLockDelay 0x0008 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 17 Transfer Type Isochronous Synch Type None Usage Type Feedback wMaxPacketSize 0x0004 1x 4 bytes bInterval 4 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 2 bAlternateSetting 0 bNumEndpoints 0 bInterfaceClass 254 Application Specific Interface bInterfaceSubClass 1 Device Firmware Update bInterfaceProtocol 1 iInterface 12 Device Firmware Upgrade Interface Descriptor: bLength 9 bDescriptorType 33 bmAttributes 7 Will Not Detach Manifestation Tolerant Couldn't open device, some information will be missing Couldn't open device, some information will be missing Upload Supported Download Supported wDetachTimeout 250 milliseconds wTransferSize 64 bytes bcdDFUVersion 1.10 Bus 001 Device 002: ID 2109:3431 VIA Labs, Inc. Hub Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 2.10 bDeviceClass 9 Hub bDeviceSubClass 0 bDeviceProtocol 1 Single TT bMaxPacketSize0 64 idVendor 0x2109 VIA Labs, Inc. idProduct 0x3431 Hub bcdDevice 4.21 iManufacturer 0 iProduct 1 USB2.0 Hub iSerial 0 bNumConfigurations 1 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x0019 bNumInterfaces 1 bConfigurationValue 1 iConfiguration 0 bmAttributes 0xe0 Self Powered Remote Wakeup MaxPower 100mA 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 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.12 iManufacturer 3 Linux 6.12.27-v7l+ xhci-hcd iProduct 2 xHCI Host Controller iSerial 1 0000:01:00.0 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 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 2 19:39 ? 00:00:04 /sbin/init splash nodebug root 2 0 0 19:39 ? 00:00:00 [kthreadd] root 3 2 0 19:39 ? 00:00:00 [pool_workqueue_release] root 4 2 0 19:39 ? 00:00:00 [kworker/R-kvfree_rcu_reclaim] root 5 2 0 19:39 ? 00:00:00 [kworker/R-rcu_gp] root 6 2 0 19:39 ? 00:00:00 [kworker/R-sync_wq] root 7 2 0 19:39 ? 00:00:00 [kworker/R-slub_flushwq] root 8 2 0 19:39 ? 00:00:00 [kworker/R-netns] root 9 2 0 19:39 ? 00:00:00 [kworker/0:0-events] root 10 2 0 19:39 ? 00:00:00 [kworker/0:0H-events_highpri] root 11 2 0 19:39 ? 00:00:00 [kworker/0:1-events] root 12 2 0 19:39 ? 00:00:00 [kworker/u16:0-ipv6_addrconf] root 13 2 0 19:39 ? 00:00:00 [kworker/R-mm_percpu_wq] root 14 2 0 19:39 ? 00:00:00 [rcu_tasks_rude_kthread] root 15 2 0 19:39 ? 00:00:00 [rcu_tasks_trace_kthread] root 16 2 0 19:39 ? 00:00:00 [ksoftirqd/0] root 17 2 0 19:39 ? 00:00:00 [rcu_sched] root 18 2 0 19:39 ? 00:00:00 [rcu_exp_par_gp_kthread_worker/0] root 19 2 0 19:39 ? 00:00:00 [rcu_exp_gp_kthread_worker] root 20 2 0 19:39 ? 00:00:00 [migration/0] root 21 2 0 19:39 ? 00:00:00 [cpuhp/0] root 22 2 0 19:39 ? 00:00:00 [cpuhp/1] root 23 2 0 19:39 ? 00:00:00 [migration/1] root 24 2 0 19:39 ? 00:00:00 [ksoftirqd/1] root 25 2 0 19:39 ? 00:00:00 [kworker/1:0-events] root 26 2 0 19:39 ? 00:00:00 [kworker/1:0H-events_highpri] root 27 2 0 19:39 ? 00:00:00 [cpuhp/2] root 28 2 0 19:39 ? 00:00:00 [migration/2] root 29 2 0 19:39 ? 00:00:00 [ksoftirqd/2] root 30 2 0 19:39 ? 00:00:00 [kworker/2:0-events_power_efficient] root 31 2 0 19:39 ? 00:00:00 [kworker/2:0H-events_highpri] root 32 2 0 19:39 ? 00:00:00 [cpuhp/3] root 33 2 0 19:39 ? 00:00:00 [migration/3] root 34 2 0 19:39 ? 00:00:00 [ksoftirqd/3] root 35 2 0 19:39 ? 00:00:00 [kworker/3:0-events_freezable] root 36 2 0 19:39 ? 00:00:00 [kworker/3:0H-events_highpri] root 37 2 0 19:39 ? 00:00:00 [kworker/u17:0-kvfree_rcu_reclaim] root 38 2 0 19:39 ? 00:00:00 [kworker/u18:0-iou_exit] root 39 2 0 19:39 ? 00:00:01 [kworker/u19:0-loop0] root 40 2 0 19:39 ? 00:00:00 [kworker/u20:0-iou_exit] root 41 2 0 19:39 ? 00:00:00 [kdevtmpfs] root 42 2 0 19:39 ? 00:00:00 [kworker/R-inet_frag_wq] root 43 2 0 19:39 ? 00:00:00 [kauditd] root 44 2 0 19:39 ? 00:00:00 [khungtaskd] root 45 2 0 19:39 ? 00:00:00 [oom_reaper] root 46 2 0 19:39 ? 00:00:00 [kworker/u17:1-iou_exit] root 47 2 0 19:39 ? 00:00:00 [kworker/R-writeback] root 48 2 0 19:39 ? 00:00:00 [kcompactd0] root 49 2 0 19:39 ? 00:00:00 [kworker/R-kintegrityd] root 50 2 0 19:39 ? 00:00:00 [kworker/R-kblockd] root 51 2 0 19:39 ? 00:00:00 [kworker/R-blkcg_punt_bio] root 52 2 0 19:39 ? 00:00:00 [kworker/u17:2-ext4-rsv-conversion] root 53 2 0 19:39 ? 00:00:00 [watchdogd] root 54 2 0 19:39 ? 00:00:00 [kworker/2:1-events] root 55 2 0 19:39 ? 00:00:00 [kworker/R-rpciod] root 56 2 0 19:39 ? 00:00:00 [kworker/2:1H-kblockd] root 57 2 0 19:39 ? 00:00:00 [kworker/R-xprtiod] root 58 2 0 19:39 ? 00:00:00 [kworker/u19:1-loop0] root 59 2 0 19:39 ? 00:00:00 [kworker/1:1-mm_percpu_wq] root 60 2 0 19:39 ? 00:00:00 [kswapd0] root 61 2 0 19:39 ? 00:00:00 [kworker/R-nfsiod] root 62 2 0 19:39 ? 00:00:00 [kworker/R-kthrotld] root 63 2 0 19:39 ? 00:00:00 [kworker/1:2-mm_percpu_wq] root 64 2 0 19:39 ? 00:00:00 [kworker/1:3-events] root 65 2 0 19:39 ? 00:00:00 [kworker/u18:1-loop0] root 67 2 0 19:39 ? 00:00:00 [kworker/3:1-events_freezable] root 70 2 0 19:39 ? 00:00:00 [kworker/u18:2-loop0] root 71 2 0 19:39 ? 00:00:00 [hwrng] root 72 2 0 19:39 ? 00:00:00 [kworker/R-iscsi_conn_cleanup] root 73 2 0 19:39 ? 00:00:00 [kworker/R-nvme-wq] root 74 2 0 19:39 ? 00:00:00 [kworker/R-nvme-reset-wq] root 75 2 0 19:39 ? 00:00:00 [kworker/R-nvme-delete-wq] root 77 2 0 19:39 ? 00:00:00 [kworker/R-DWC Notification WorkQ] root 78 2 0 19:39 ? 00:00:00 [kworker/R-uas] root 79 2 0 19:39 ? 00:00:00 [vchiq-slot/0] root 80 2 0 19:39 ? 00:00:00 [vchiq-recy/0] root 81 2 0 19:39 ? 00:00:00 [vchiq-sync/0] root 82 2 0 19:39 ? 00:00:00 [kworker/u21:0-hci0] root 83 2 0 19:39 ? 00:00:00 [kworker/u22:0] root 84 2 0 19:39 ? 00:00:00 [kworker/u23:0] root 85 2 0 19:39 ? 00:00:00 [kworker/u24:0] root 86 2 0 19:39 ? 00:00:00 [kworker/u25:0] root 87 2 0 19:39 ? 00:00:00 [kworker/u18:3-loop0] root 88 2 0 19:39 ? 00:00:00 [kworker/R-sdhci] root 89 2 0 19:39 ? 00:00:00 [irq/51-mmc0] root 106 2 0 19:39 ? 00:00:00 [kworker/1:4-events] root 117 2 0 19:39 ? 00:00:00 [kworker/R-mmc_complete] root 119 2 0 19:39 ? 00:00:00 [kworker/0:1H-kblockd] root 121 2 0 19:39 ? 00:00:00 [kworker/3:1H-kblockd] root 135 2 0 19:39 ? 00:00:00 [kworker/0:2H-mmc_complete] root 136 2 0 19:39 ? 00:00:00 [kworker/2:2H-kblockd] root 137 2 0 19:39 ? 00:00:00 [kworker/1:1H-kblockd] root 151 2 0 19:39 ? 00:00:00 [kworker/u20:1+iou_exit] root 175 2 0 19:39 ? 00:00:00 [kworker/u20:2+iou_exit] root 180 2 0 19:39 ? 00:00:00 [kworker/0:2-mm_percpu_wq] root 200 2 0 19:39 ? 00:00:00 [kworker/0:3-events] root 218 2 0 19:39 ? 00:00:00 [jbd2/mmcblk0p2-8] root 219 2 0 19:39 ? 00:00:00 [kworker/R-ext4-rsv-conversion] root 240 2 0 19:39 ? 00:00:00 [kworker/3:2H-kblockd] root 252 2 0 19:39 ? 00:00:00 [jbd2/mmcblk0p3-8] root 253 2 0 19:39 ? 00:00:00 [kworker/R-ext4-rsv-conversion] root 278 2 0 19:39 ? 00:00:00 [kworker/u19:2-iou_exit] root 287 2 0 19:39 ? 00:00:00 [kworker/R-mld] root 288 2 0 19:39 ? 00:00:00 [kworker/R-ipv6_addrconf] root 289 2 0 19:39 ? 00:00:00 [kworker/u16:1-ipv6_addrconf] root 325 1 0 19:39 ? 00:00:00 /lib/systemd/systemd-journald root 331 2 0 19:39 ? 00:00:00 [kworker/2:2-cgroup_destroy] root 347 2 0 19:39 ? 00:00:00 [kworker/u19:3-events_unbound] root 352 1 0 19:39 ? 00:00:00 /lib/systemd/systemd-udevd root 367 2 0 19:39 ? 00:00:00 [kworker/u20:3-iou_exit] root 384 2 0 19:39 ? 00:00:00 [kworker/1:2H] root 418 2 0 19:39 ? 00:00:00 [vchiq-keep/0] root 419 2 0 19:39 ? 00:00:00 [SMIO] root 423 2 0 19:39 ? 00:00:00 [irq/53-feb00000.codec] root 460 2 0 19:39 ? 00:00:00 [kworker/R-mmal-vchiq] root 461 2 0 19:39 ? 00:00:00 [kworker/R-cfg80211] root 462 2 0 19:39 ? 00:00:00 [kworker/R-mmal-vchiq] root 463 2 0 19:39 ? 00:00:00 [kworker/R-mmal-vchiq] root 466 2 0 19:39 ? 00:00:00 [kworker/R-mmal-vchiq] root 467 2 0 19:39 ? 00:00:00 [kworker/R-mmal-vchiq] root 470 2 0 19:39 ? 00:00:00 [kworker/R-mmal-vchiq] root 471 2 0 19:39 ? 00:00:00 [kworker/R-mmal-vchiq] root 504 2 0 19:39 ? 00:00:00 [kworker/u21:1-hci0] root 506 2 0 19:39 ? 00:00:00 [kworker/u21:2-brcmf_wq/mmc1:0001:1] root 526 1 0 19:39 ? 00:00:00 /usr/sbin/haveged --Foreground --verbose=1 root 531 2 0 19:39 ? 00:00:00 [kworker/2:3-events] dhcpcd 532 1 0 19:39 ? 00:00:00 dhcpcd: eth0 [ip4] [ip6] root 533 532 0 19:39 ? 00:00:00 dhcpcd: [privileged proxy] eth0 [ip4] [ip6] dhcpcd 534 532 0 19:39 ? 00:00:00 dhcpcd: [network proxy] eth0 [ip4] [ip6] dhcpcd 535 532 0 19:39 ? 00:00:00 dhcpcd: [control proxy] eth0 [ip4] [ip6] root 537 2 0 19:39 ? 00:00:00 [kworker/2:4-events] root 544 2 0 19:39 ? 00:00:00 [kworker/2:5-events] _rpc 548 1 0 19:39 ? 00:00:00 /sbin/rpcbind -f -w root 551 2 0 19:39 ? 00:00:00 [kworker/R-brcmf_wq/mmc1:0001:1] root 554 2 0 19:39 ? 00:00:00 [brcmf_wdog/mmc1:0001:1] root 583 2 0 19:39 ? 00:00:00 [kworker/u21:3-hci0] root 603 2 0 19:39 ? 00:00:00 [kworker/3:2-events] avahi 651 1 0 19:39 ? 00:00:00 avahi-daemon: running [volumio-rpi4-living.local] root 652 1 0 19:39 ? 00:00:00 /usr/libexec/bluetooth/bluetoothd --noplugin=sap,battery,midi,wiimote -E message+ 653 1 0 19:39 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 662 1 0 19:39 ? 00:00:00 /lib/systemd/systemd-logind avahi 663 651 0 19:39 ? 00:00:00 avahi-daemon: chroot helper nobody 664 1 0 19:39 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* volumio 667 1 0 19:39 ? 00:00:00 /usr/bin/volumio-remote-updater root 689 2 0 19:39 ? 00:00:00 [kworker/u17:3-iou_exit] dhcpcd 721 1 0 19:39 ? 00:00:00 dhcpcd: [manager] [ip4] [ip6] root 722 721 0 19:39 ? 00:00:00 dhcpcd: [privileged proxy] dhcpcd 723 721 0 19:39 ? 00:00:00 dhcpcd: [network proxy] dhcpcd 724 721 0 19:39 ? 00:00:00 dhcpcd: [control proxy] root 766 1 0 19:39 ? 00:00:00 /sbin/wpa_supplicant -u -s -O DIR=/run/wpa_supplicant GROUP=netdev root 806 1 0 19:39 ? 00:00:00 /usr/bin/bluealsa -S -p a2dp-source -p a2dp-sink root 816 2 0 19:39 ? 00:00:00 [kworker/R-cryptd] root 859 1 0 19:39 ? 00:00:00 /bin/bash -c ip mon addr | sed -nu -r 's/.*[[:digit:]]+:[[:space:]]+([^[:space:]]+).*/\1/p' | while read iface; do systemctl restart ip-changed@${iface}.target; done root 863 859 0 19:39 ? 00:00:00 ip mon addr root 865 859 0 19:39 ? 00:00:00 sed -nu -r s/.*[[:digit:]]+:[[:space:]]+([^[:space:]]+).*/\1/p root 866 859 0 19:39 ? 00:00:00 /bin/bash -c ip mon addr | sed -nu -r 's/.*[[:digit:]]+:[[:space:]]+([^[:space:]]+).*/\1/p' | while read iface; do systemctl restart ip-changed@${iface}.target; done root 884 1 0 19:39 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I ntpsec 886 1 0 19:39 ? 00:00:00 /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec root 938 1 0 19:40 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 939 938 0 19:40 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group dhcpcd 983 533 0 19:40 ? 00:00:00 dhcpcd: [BPF ARP] eth0 192.168.1.191 dhcpcd 985 722 0 19:40 ? 00:00:00 dhcpcd: [BPF ARP] eth0 192.168.1.191 root 1017 1 0 19:40 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 1018 1 0 19:40 ? 00:00:00 /usr/sbin/hostapd -B -P /run/hostapd.pid /etc/hostapd/hostapd.conf root 1021 2 0 19:40 ? 00:00:00 [kworker/u19:4-events_unbound] root 1022 2 0 19:40 ? 00:00:00 [kworker/u18:4] root 1026 2 0 19:40 ? 00:00:00 [kworker/u19:5] root 1027 2 0 19:40 ? 00:00:00 [kworker/u19:6-loop0] volumio 1028 1 80 19:40 ? 00:02:16 /usr/bin/node /volumio/index.js dnsmasq 1052 1 0 19:40 ? 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 root 1057 1017 0 19:40 ? 00:00:00 winbindd: domain child [VOLUMIO-RPI4-LIVING] dhcpcd 1082 533 0 19:40 ? 00:00:00 dhcpcd: [network proxy] 192.168.1.191 root 1113 1 0 19:40 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 1140 1113 0 19:40 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 1143 1113 0 19:40 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group dhcpcd 1151 722 0 19:40 ? 00:00:00 dhcpcd: [BPF BOOTP] wlan0 root 1154 1017 0 19:40 ? 00:00:00 winbindd: idmap child volumio 1177 1028 0 19:40 ? 00:00:00 /bin/bash /volumio/app/plugins/system_controller/network/network_monitor.sh dhcpcd 1207 722 0 19:40 ? 00:00:00 dhcpcd: [BPF ARP] wlan0 169.254.41.79 volumio 1210 1028 99 19:40 ? 00:02:41 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1220 1210 67 19:40 ? 00:01:48 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1221 1210 68 19:40 ? 00:01:50 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1222 1210 68 19:40 ? 00:01:51 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart root 1312 2 0 19:40 ? 00:00:00 [kworker/3:3-cgroup_destroy] root 1366 2 0 19:40 ? 00:00:00 [kworker/R-dio/overlay] root 1406 2 0 19:40 ? 00:00:00 [kworker/u20:4-iou_exit] volumio 1434 1 0 19:40 ? 00:00:00 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf root 1533 2 0 19:40 ? 00:00:00 [kworker/u17:4] mpd 1586 1 1 19:40 ? 00:00:01 /usr/bin/mpd --no-daemon volumio 1596 1 0 19:40 ? 00:00:00 bash /bin/bt/btstart.sh start volumio 1622 1596 0 19:40 ? 00:00:00 /usr/bin/python3 -u /bin/bt/a2dp-agent volumio 1628 1 0 19:40 ? 00:00:00 /bin/sh /tmp/sshtunnel.sh volumio 1629 1628 0 19:40 ? 00:00:00 /usr/lib/autossh/autossh -M 0 -o HostKeyAlgorithms=+ssh-rsa -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R MOJnSA90pkPyvDP7mYtoMTOHBL23-53d8493208beabb2d066cb23cfd85dc8.eu7.myvolumio.org:8091:localhost:3000 eu7.myvolumio.org volumio 1632 1629 0 19:40 ? 00:00:00 /usr/bin/ssh -o HostKeyAlgorithms=+ssh-rsa -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R MOJnSA90pkPyvDP7mYtoMTOHBL23-53d8493208beabb2d066cb23cfd85dc8.eu7.myvolumio.org:8091:localhost:3000 eu7.myvolumio.org shairpo+ 1638 1 1 19:40 ? 00:00:01 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf root 1910 1 0 19:42 ? 00:00:00 /lib/systemd/systemd-timedated volumio 1946 1177 0 19:42 ? 00:00:00 sleep 10 volumio 1947 1028 0 19:42 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'Sending log report, please wait' volumio 1948 1947 99 19:42 ? 00:00:01 /usr/bin/node /volumio/logsubmit.js Sending log report, please wait root 1970 2 0 19:42 ? 00:00:00 [kworker/u20:5-loop0] root 1971 2 0 19:42 ? 00:00:00 [kworker/u20:6] volumio 1991 1948 0 19:42 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 1992 1991 66 19:42 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on udev 860M 0 860M 0% /dev tmpfs 182M 6.9M 175M 4% /run /dev/mmcblk0p2 2.7G 2.2G 353M 87% /imgpart /dev/loop0 657M 657M 0 100% /static overlay 12G 22M 11G 1% / tmpfs 909M 0 909M 0% /dev/shm tmpfs 5.0M 12K 5.0M 1% /run/lock tmpfs 909M 100K 909M 1% /tmp tmpfs 909M 0 909M 0% /var/spool/cups tmpfs 20M 40K 20M 1% /var/log tmpfs 909M 0 909M 0% /var/spool/cups/tmp /dev/mmcblk0p1 241M 88M 153M 37% /boot # 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=879948k,nr_inodes=145063,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=186080k,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) cgroup2 on /sys/fs/cgroup type cgroup2 (rw,nosuid,nodev,noexec,relatime,nsdelegate,memory_recursiveprot) 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) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=29,pgrp=1,timeout=0,minproto=5,maxproto=5,direct) mqueue on /dev/mqueue type mqueue (rw,nosuid,nodev,noexec,relatime) tracefs on /sys/kernel/tracing type tracefs (rw,nosuid,nodev,noexec,relatime) debugfs on /sys/kernel/debug type debugfs (rw,nosuid,nodev,noexec,relatime) fusectl on /sys/fs/fuse/connections type fusectl (rw,nosuid,nodev,noexec,relatime) configfs on /sys/kernel/config type configfs (rw,nosuid,nodev,noexec,relatime) ramfs on /run/credentials/systemd-sysusers.service type ramfs (ro,nosuid,nodev,noexec,relatime,mode=700) ramfs on /run/credentials/systemd-sysctl.service type ramfs (ro,nosuid,nodev,noexec,relatime,mode=700) ramfs on /run/credentials/systemd-tmpfiles-setup-dev.service type ramfs (ro,nosuid,nodev,noexec,relatime,mode=700) tmpfs on /tmp type tmpfs (rw,noatime,mode=755) 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 /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) ramfs on /run/credentials/systemd-tmpfiles-setup.service type ramfs (ro,nosuid,nodev,noexec,relatime,mode=700) sunrpc on /run/rpc_pipefs type rpc_pipefs (rw,relatime) binfmt_misc on /proc/sys/fs/binfmt_misc type binfmt_misc (rw,nosuid,nodev,noexec,relatime) # 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:49152 0.0.0.0:* LISTEN 1434/upmpdcli tcp 0 0 0.0.0.0:53 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:111 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:5000 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:49149 0.0.0.0:* LISTEN 1434/upmpdcli tcp 0 0 127.0.0.1:39158 127.0.0.1:3000 ESTABLISHED 1028/node tcp 0 0 192.168.1.191:51114 104.21.80.1:443 TIME_WAIT - tcp 0 0 127.0.0.1:58372 127.0.0.1:3000 ESTABLISHED 667/volumio-remote- tcp 0 0 192.168.1.191:47940 35.201.97.85:443 ESTABLISHED 1028/node tcp 0 0 192.168.1.191:53698 172.67.141.32:443 TIME_WAIT - tcp 0 0 127.0.0.1:45568 127.0.0.1:3000 TIME_WAIT - tcp 0 0 127.0.0.1:34178 127.0.0.1:3000 ESTABLISHED 1028/node tcp 0 0 192.168.1.191:59994 84.247.14.230:80 ESTABLISHED 1028/node tcp 0 0 192.168.1.191:42542 104.26.12.54:80 TIME_WAIT - tcp 0 0 127.0.0.1:50074 127.0.0.1:3000 ESTABLISHED 1028/node tcp 0 0 127.0.0.1:54240 127.0.0.1:6599 ESTABLISHED 1434/upmpdcli tcp 0 0 127.0.0.1:54224 127.0.0.1:6599 ESTABLISHED 1434/upmpdcli tcp 0 0 192.168.1.191:46422 167.71.43.69:2222 ESTABLISHED 1632/ssh tcp 0 0 127.0.0.1:39108 127.0.0.1:3000 ESTABLISHED 1028/node tcp6 0 0 :::53 :::* LISTEN - tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::3005 :::* LISTEN 1028/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 1028/node tcp6 0 0 :::3001 :::* LISTEN 1210/node tcp6 0 0 :::6599 :::* LISTEN 1028/node tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 192.168.1.191:3000 192.168.1.180:52940 ESTABLISHED 1028/node tcp6 0 0 192.168.1.191:3000 192.168.1.180:52922 TIME_WAIT - tcp6 0 0 192.168.1.191:3000 192.168.1.180:52926 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:34186 TIME_WAIT - tcp6 0 0 192.168.1.191:3000 192.168.1.117:55522 ESTABLISHED 1028/node tcp6 0 0 192.168.1.191:3000 192.168.1.180:52938 ESTABLISHED 1028/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:39158 ESTABLISHED 1028/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:34178 ESTABLISHED 1028/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:58372 ESTABLISHED 1028/node tcp6 0 0 192.168.1.191:3000 192.168.1.180:52920 TIME_WAIT - tcp6 0 0 127.0.0.1:6599 127.0.0.1:54240 ESTABLISHED 1028/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:50074 ESTABLISHED 1028/node tcp6 0 0 192.168.1.191:3000 192.168.1.180:52923 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:39108 ESTABLISHED 1028/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:34198 TIME_WAIT - tcp6 0 0 192.168.1.191:3000 192.168.1.180:52927 ESTABLISHED 1028/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:34172 TIME_WAIT - tcp6 0 0 127.0.0.1:6599 127.0.0.1:54224 ESTABLISHED 1028/node tcp6 0 0 192.168.1.191:3000 192.168.1.180:52924 TIME_WAIT - tcp6 0 0 192.168.1.191:3000 192.168.1.180:52939 ESTABLISHED 1028/node tcp6 0 0 192.168.1.191:3000 192.168.1.180:52941 ESTABLISHED 1028/node tcp6 0 0 192.168.1.191:3000 192.168.1.180:52925 TIME_WAIT - # cat /boot/config.txt --------------- # 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 "volumioOutput" } # There is always a plug before the hardware to be safe pcm.volumioOutput { type plug slave.pcm "volumioHw" } pcm.volumioHw { type hw card "A20" } # cat /var/log/mpd.log --------------- 2025-05-28T19:40:21 zeroconf: No global port, disabling zeroconf 2025-05-28T19:40:26 exception: No such directory 2025-05-28T19:40:46 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: 'Touch Display', name: 'touch_display', category: 'user_interface', version: '3.5.2', icon: 'fa-hand-pointer-o', 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"}}},"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":"STOPPED"}},"rest_api":{"enabled":{"type":"boolean","value":true},"status":{"type":"string","value":"STARTED"}},"touch_display":{"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"}}}} # 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" } 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, 2149 ms: OK https://www.googleapis.com, 1728 ms: OK https://securetoken.googleapis.com, 1884 ms: OK https://myvolumio.firebaseio.com, 1885 ms: OK https://functions.volumio.cloud, 1825 ms: OK https://oauth-performer.dfs.volumio.org, 1721 ms: OK https://browsing-performer.dfs.volumio.org, 1768 ms: OK http://cddb.volumio.org, 2210 ms: OK https://functions.volumio.cloud, 1849 ms: OK http://pushupdates.volumio.org, 1369 ms: OK http://plugins.volumio.org, 2430 ms: OK https://database.volumio.cloud, 1972 ms: OK https://radio-directory.firebaseapp.com, 1808 ms: OK ---------- REMOTE ENDPOINTS TEST OK, all Endpoints are reachable ---------- # cat /data/updatesHistoryFile --------------- --- Wed May 28 2025 15:25:49 GMT+0000 (Coordinated Universal Time) {"systemversion":"0.069","builddate":"Fri May 23 08:02:06 UTC 2025","variant":"volumio","hardware":"pi"} ------ Wed May 28 2025 18:56:04 GMT+0300 (Eastern European Summer Time) {"systemversion":"4.002","builddate":"Wed May 28 07:32:00 UTC 2025","variant":"volumio","hardware":"pi","os":"12"} --- # sudo journalctl -p 7 --------------- May 28 18:53:38 volumio-rpi4-living kernel: Booting Linux on physical CPU 0x0 May 28 18:53:38 volumio-rpi4-living kernel: Linux version 6.12.27-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #1876 SMP Tue May 6 15:10:05 BST 2025 May 28 18:53:38 volumio-rpi4-living kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d May 28 18:53:38 volumio-rpi4-living kernel: CPU: div instructions available: patching division code May 28 18:53:38 volumio-rpi4-living kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache May 28 18:53:38 volumio-rpi4-living kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.1 May 28 18:53:38 volumio-rpi4-living kernel: random: crng init done May 28 18:53:38 volumio-rpi4-living kernel: Memory policy: Data cache writealloc May 28 18:53:38 volumio-rpi4-living kernel: Reserved memory: created CMA memory pool at 0x0000000028c00000, size 64 MiB May 28 18:53:38 volumio-rpi4-living kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool May 28 18:53:38 volumio-rpi4-living kernel: OF: reserved mem: 0x0000000028c00000..0x000000002cbfffff (65536 KiB) map reusable linux,cma May 28 18:53:38 volumio-rpi4-living kernel: Zone ranges: May 28 18:53:38 volumio-rpi4-living kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] May 28 18:53:38 volumio-rpi4-living kernel: Normal empty May 28 18:53:38 volumio-rpi4-living kernel: HighMem [mem 0x0000000030000000-0x000000007fffffff] May 28 18:53:38 volumio-rpi4-living kernel: Movable zone start for each node May 28 18:53:38 volumio-rpi4-living kernel: Early memory node ranges May 28 18:53:38 volumio-rpi4-living kernel: node 0: [mem 0x0000000000000000-0x0000000037ffffff] May 28 18:53:38 volumio-rpi4-living kernel: node 0: [mem 0x0000000040000000-0x000000007fffffff] May 28 18:53:38 volumio-rpi4-living kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000007fffffff] May 28 18:53:38 volumio-rpi4-living kernel: OF: reserved mem: 0x000000003ef667e0..0x000000003ef668c1 (0 KiB) nomap non-reusable nvram@0 May 28 18:53:38 volumio-rpi4-living kernel: OF: reserved mem: 0x000000003ef665a0..0x000000003ef6679f (0 KiB) nomap non-reusable nvram@1 May 28 18:53:38 volumio-rpi4-living kernel: percpu: Embedded 19 pages/cpu s45772 r8192 d23860 u77824 May 28 18:53:38 volumio-rpi4-living kernel: pcpu-alloc: s45772 r8192 d23860 u77824 alloc=19*4096 May 28 18:53:38 volumio-rpi4-living kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 May 28 18:53:38 volumio-rpi4-living kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 numa=fake=1 system_heap.max_order=0 smsc95xx.macaddr=DC:A6:32:33:A7:9C 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=2d6bf3e1-3b9d-416b-94c7-51c320b903be imgfile=/volumio_current.sqsh bootpart=UUID=B901-874B datapart=UUID=b4f21d74-cb45-46d4-9629-7f69cf9b3464 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 May 28 18:53:38 volumio-rpi4-living kernel: cgroup: Disabling memory control group subsystem May 28 18:53:38 volumio-rpi4-living kernel: Unknown kernel command line parameters "splash nodebug numa_policy=interleave numa=fake=1 imgpart=UUID=2d6bf3e1-3b9d-416b-94c7-51c320b903be imgfile=/volumio_current.sqsh bootpart=UUID=B901-874B datapart=UUID=b4f21d74-cb45-46d4-9629-7f69cf9b3464 uuidconfig=cmdline.txt pcie_aspm=off bootdelay=7 use_kmsg=no", will be passed to user space. May 28 18:53:38 volumio-rpi4-living kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) May 28 18:53:38 volumio-rpi4-living kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) May 28 18:53:38 volumio-rpi4-living kernel: Built 1 zonelists, mobility grouping on. Total pages: 491520 May 28 18:53:38 volumio-rpi4-living kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 28 18:53:38 volumio-rpi4-living kernel: software IO TLB: area num 4. May 28 18:53:38 volumio-rpi4-living kernel: software IO TLB: mapped [mem 0x0000000023a00000-0x0000000027a00000] (64MB) May 28 18:53:38 volumio-rpi4-living kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 28 18:53:38 volumio-rpi4-living kernel: ftrace: allocating 39956 entries in 118 pages May 28 18:53:38 volumio-rpi4-living kernel: ftrace: allocated 118 pages with 5 groups May 28 18:53:38 volumio-rpi4-living kernel: rcu: Hierarchical RCU implementation. May 28 18:53:38 volumio-rpi4-living kernel: Rude variant of Tasks RCU enabled. May 28 18:53:38 volumio-rpi4-living kernel: Tracing variant of Tasks RCU enabled. May 28 18:53:38 volumio-rpi4-living kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. May 28 18:53:38 volumio-rpi4-living kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 28 18:53:38 volumio-rpi4-living kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. May 28 18:53:38 volumio-rpi4-living kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 May 28 18:53:38 volumio-rpi4-living kernel: GIC: Using split EOI/Deactivate mode May 28 18:53:38 volumio-rpi4-living kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 28 18:53:38 volumio-rpi4-living kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). May 28 18:53:38 volumio-rpi4-living kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns May 28 18:53:38 volumio-rpi4-living kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns May 28 18:53:38 volumio-rpi4-living kernel: Switching to timer-based delay loop, resolution 18ns May 28 18:53:38 volumio-rpi4-living kernel: Console: colour dummy device 80x30 May 28 18:53:38 volumio-rpi4-living kernel: printk: legacy console [tty1] enabled May 28 18:53:38 volumio-rpi4-living kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) May 28 18:53:38 volumio-rpi4-living kernel: CPU: Testing write buffer coherency: ok May 28 18:53:38 volumio-rpi4-living kernel: CPU0: Spectre BHB: enabling loop workaround for all CPUs May 28 18:53:38 volumio-rpi4-living kernel: pid_max: default: 32768 minimum: 301 May 28 18:53:38 volumio-rpi4-living kernel: LSM: initializing lsm=capability May 28 18:53:38 volumio-rpi4-living kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) May 28 18:53:38 volumio-rpi4-living kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) May 28 18:53:38 volumio-rpi4-living kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 May 28 18:53:38 volumio-rpi4-living kernel: Setting up static identity map for 0x200000 - 0x20003c May 28 18:53:38 volumio-rpi4-living kernel: rcu: Hierarchical SRCU implementation. May 28 18:53:38 volumio-rpi4-living kernel: rcu: Max phase no-delay instances is 1000. May 28 18:53:38 volumio-rpi4-living kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level May 28 18:53:38 volumio-rpi4-living kernel: smp: Bringing up secondary CPUs ... May 28 18:53:38 volumio-rpi4-living kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 May 28 18:53:38 volumio-rpi4-living kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 May 28 18:53:38 volumio-rpi4-living kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 May 28 18:53:38 volumio-rpi4-living kernel: smp: Brought up 1 node, 4 CPUs May 28 18:53:38 volumio-rpi4-living kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). May 28 18:53:38 volumio-rpi4-living kernel: CPU: All CPU(s) started in HYP mode. May 28 18:53:38 volumio-rpi4-living kernel: CPU: Virtualization extensions available. May 28 18:53:38 volumio-rpi4-living kernel: Memory: 1756472K/1966080K available (12288K kernel code, 1647K rwdata, 3840K rodata, 2048K init, 399K bss, 140644K reserved, 65536K cma-reserved, 1179648K highmem) May 28 18:53:38 volumio-rpi4-living kernel: devtmpfs: initialized May 28 18:53:38 volumio-rpi4-living kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 May 28 18:53:38 volumio-rpi4-living kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns May 28 18:53:38 volumio-rpi4-living kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) May 28 18:53:38 volumio-rpi4-living kernel: pinctrl core: initialized pinctrl subsystem May 28 18:53:38 volumio-rpi4-living kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 28 18:53:38 volumio-rpi4-living kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations May 28 18:53:38 volumio-rpi4-living kernel: audit: initializing netlink subsys (disabled) May 28 18:53:38 volumio-rpi4-living kernel: audit: type=2000 audit(0.000:1): state=initialized audit_enabled=0 res=1 May 28 18:53:38 volumio-rpi4-living kernel: thermal_sys: Registered thermal governor 'step_wise' May 28 18:53:38 volumio-rpi4-living kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. May 28 18:53:38 volumio-rpi4-living kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. May 28 18:53:38 volumio-rpi4-living kernel: Serial: AMBA PL011 UART driver May 28 18:53:38 volumio-rpi4-living kernel: /soc/interrupt-controller@40041000: Fixed dependency cycle(s) with /soc/interrupt-controller@40041000 May 28 18:53:38 volumio-rpi4-living kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled May 28 18:53:38 volumio-rpi4-living kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2025-04-30T13:33:39, variant start May 28 18:53:38 volumio-rpi4-living kernel: raspberrypi-firmware soc:firmware: Firmware hash is 5560078dcc8591a00f57b9068d13e5544aeef3aa May 28 18:53:38 volumio-rpi4-living kernel: /scb/pcie@7d500000: Fixed dependency cycle(s) with /scb/pcie@7d500000 May 28 18:53:38 volumio-rpi4-living kernel: /scb/pcie@7d500000: Fixed dependency cycle(s) with /scb/pcie@7d500000 May 28 18:53:38 volumio-rpi4-living kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 28 18:53:38 volumio-rpi4-living kernel: bcm2835-dma fe007000.dma-controller: DMA legacy API manager, dmachans=0x1 May 28 18:53:38 volumio-rpi4-living kernel: SCSI subsystem initialized May 28 18:53:38 volumio-rpi4-living kernel: usbcore: registered new interface driver usbfs May 28 18:53:38 volumio-rpi4-living kernel: usbcore: registered new interface driver hub May 28 18:53:38 volumio-rpi4-living kernel: usbcore: registered new device driver usb May 28 18:53:38 volumio-rpi4-living kernel: pps_core: LinuxPPS API ver. 1 registered May 28 18:53:38 volumio-rpi4-living kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti May 28 18:53:38 volumio-rpi4-living kernel: PTP clock support registered May 28 18:53:38 volumio-rpi4-living kernel: vgaarb: loaded May 28 18:53:38 volumio-rpi4-living kernel: clocksource: Switched to clocksource arch_sys_counter May 28 18:53:38 volumio-rpi4-living kernel: VFS: Disk quotas dquot_6.6.0 May 28 18:53:38 volumio-rpi4-living kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) May 28 18:53:38 volumio-rpi4-living kernel: NET: Registered PF_INET protocol family May 28 18:53:38 volumio-rpi4-living kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) May 28 18:53:38 volumio-rpi4-living kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) May 28 18:53:38 volumio-rpi4-living kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 28 18:53:38 volumio-rpi4-living kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) May 28 18:53:38 volumio-rpi4-living kernel: TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear) May 28 18:53:38 volumio-rpi4-living kernel: TCP: Hash tables configured (established 8192 bind 8192) May 28 18:53:38 volumio-rpi4-living kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) May 28 18:53:38 volumio-rpi4-living kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) May 28 18:53:38 volumio-rpi4-living kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 28 18:53:38 volumio-rpi4-living kernel: RPC: Registered named UNIX socket transport module. May 28 18:53:38 volumio-rpi4-living kernel: RPC: Registered udp transport module. May 28 18:53:38 volumio-rpi4-living kernel: RPC: Registered tcp transport module. May 28 18:53:38 volumio-rpi4-living kernel: RPC: Registered tcp-with-tls transport module. May 28 18:53:38 volumio-rpi4-living kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 28 18:53:38 volumio-rpi4-living kernel: PCI: CLS 0 bytes, default 64 May 28 18:53:38 volumio-rpi4-living kernel: Trying to unpack rootfs image as initramfs... May 28 18:53:38 volumio-rpi4-living kernel: Initialise system trusted keyrings May 28 18:53:38 volumio-rpi4-living kernel: workingset: timestamp_bits=14 max_order=19 bucket_order=5 May 28 18:53:38 volumio-rpi4-living kernel: NFS: Registering the id_resolver key type May 28 18:53:38 volumio-rpi4-living kernel: Key type id_resolver registered May 28 18:53:38 volumio-rpi4-living kernel: Key type id_legacy registered May 28 18:53:38 volumio-rpi4-living kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... May 28 18:53:38 volumio-rpi4-living kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... May 28 18:53:38 volumio-rpi4-living kernel: Key type asymmetric registered May 28 18:53:38 volumio-rpi4-living kernel: Asymmetric key parser 'x509' registered May 28 18:53:38 volumio-rpi4-living kernel: bounce: pool size: 64 pages May 28 18:53:38 volumio-rpi4-living kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) May 28 18:53:38 volumio-rpi4-living kernel: io scheduler mq-deadline registered May 28 18:53:38 volumio-rpi4-living kernel: io scheduler kyber registered May 28 18:53:38 volumio-rpi4-living kernel: io scheduler bfq registered May 28 18:53:38 volumio-rpi4-living kernel: pinctrl-bcm2835 fe200000.gpio: GPIO_OUT persistence: yes May 28 18:53:38 volumio-rpi4-living kernel: Freeing initrd memory: 33308K May 28 18:53:38 volumio-rpi4-living kernel: ledtrig-cpu: registered to indicate activity on CPUs May 28 18:53:38 volumio-rpi4-living kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: May 28 18:53:38 volumio-rpi4-living kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] May 28 18:53:38 volumio-rpi4-living kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 May 28 18:53:38 volumio-rpi4-living kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x007fffffff -> 0x0400000000 May 28 18:53:38 volumio-rpi4-living kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 May 28 18:53:38 volumio-rpi4-living kernel: pci_bus 0000:00: root bus resource [bus 00-ff] May 28 18:53:38 volumio-rpi4-living kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) May 28 18:53:38 volumio-rpi4-living kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 PCIe Root Port May 28 18:53:38 volumio-rpi4-living kernel: pci 0000:00:00.0: PCI bridge to [bus 00] May 28 18:53:38 volumio-rpi4-living kernel: pci 0000:00:00.0: bridge window [mem 0x80000000-0xbfffffff] May 28 18:53:38 volumio-rpi4-living kernel: pci 0000:00:00.0: PME# supported from D0 D3hot May 28 18:53:38 volumio-rpi4-living kernel: PCI: bus0: Fast back to back transfers disabled May 28 18:53:38 volumio-rpi4-living kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring May 28 18:53:38 volumio-rpi4-living kernel: pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator May 28 18:53:38 volumio-rpi4-living kernel: pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator May 28 18:53:38 volumio-rpi4-living kernel: pci_bus 0000:01: supply vpcie12v not found, using dummy regulator May 28 18:53:38 volumio-rpi4-living kernel: brcm-pcie fd500000.pcie: clkreq-mode set to default May 28 18:53:38 volumio-rpi4-living kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) May 28 18:53:38 volumio-rpi4-living kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 PCIe Endpoint May 28 18:53:38 volumio-rpi4-living kernel: pci 0000:01:00.0: BAR 0 [mem 0x00000000-0x00000fff 64bit] May 28 18:53:38 volumio-rpi4-living kernel: pci 0000:01:00.0: PME# supported from D0 D3cold May 28 18:53:38 volumio-rpi4-living kernel: PCI: bus1: Fast back to back transfers disabled May 28 18:53:38 volumio-rpi4-living kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 May 28 18:53:38 volumio-rpi4-living kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff]: assigned May 28 18:53:38 volumio-rpi4-living kernel: pci 0000:01:00.0: BAR 0 [mem 0x600000000-0x600000fff 64bit]: assigned May 28 18:53:38 volumio-rpi4-living kernel: pci 0000:00:00.0: PCI bridge to [bus 01] May 28 18:53:38 volumio-rpi4-living kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] May 28 18:53:38 volumio-rpi4-living kernel: pci_bus 0000:00: resource 4 [mem 0x600000000-0x63fffffff] May 28 18:53:38 volumio-rpi4-living kernel: pci_bus 0000:01: resource 1 [mem 0x600000000-0x6000fffff] May 28 18:53:38 volumio-rpi4-living kernel: pci 0000:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 May 28 18:53:38 volumio-rpi4-living kernel: pci 0000:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 May 28 18:53:38 volumio-rpi4-living kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) May 28 18:53:38 volumio-rpi4-living kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 41 May 28 18:53:38 volumio-rpi4-living kernel: bcm2708_fb soc:fb: FB found 1 display(s) May 28 18:53:38 volumio-rpi4-living kernel: Console: switching to colour frame buffer device 100x30 May 28 18:53:38 volumio-rpi4-living kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 800x480 May 28 18:53:38 volumio-rpi4-living kernel: iproc-rng200 fe104000.rng: hwrng registered May 28 18:53:38 volumio-rpi4-living kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) May 28 18:53:38 volumio-rpi4-living kernel: brd: module loaded May 28 18:53:38 volumio-rpi4-living kernel: loop: module loaded May 28 18:53:38 volumio-rpi4-living kernel: Loading iSCSI transport class v2.0-870. May 28 18:53:38 volumio-rpi4-living kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 May 28 18:53:38 volumio-rpi4-living kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus May 28 18:53:38 volumio-rpi4-living kernel: usbcore: registered new interface driver lan78xx May 28 18:53:38 volumio-rpi4-living kernel: usbcore: registered new interface driver smsc95xx May 28 18:53:38 volumio-rpi4-living kernel: xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142) May 28 18:53:38 volumio-rpi4-living kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller May 28 18:53:38 volumio-rpi4-living kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 May 28 18:53:38 volumio-rpi4-living kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0300240000000890 May 28 18:53:38 volumio-rpi4-living kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller May 28 18:53:38 volumio-rpi4-living kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 May 28 18:53:38 volumio-rpi4-living kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed May 28 18:53:38 volumio-rpi4-living kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.12 May 28 18:53:38 volumio-rpi4-living kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 May 28 18:53:38 volumio-rpi4-living kernel: usb usb1: Product: xHCI Host Controller May 28 18:53:38 volumio-rpi4-living kernel: usb usb1: Manufacturer: Linux 6.12.27-v7l+ xhci-hcd May 28 18:53:38 volumio-rpi4-living kernel: usb usb1: SerialNumber: 0000:01:00.0 May 28 18:53:38 volumio-rpi4-living kernel: hub 1-0:1.0: USB hub found May 28 18:53:38 volumio-rpi4-living kernel: hub 1-0:1.0: 1 port detected May 28 18:53:38 volumio-rpi4-living kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.12 May 28 18:53:38 volumio-rpi4-living kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 May 28 18:53:38 volumio-rpi4-living kernel: usb usb2: Product: xHCI Host Controller May 28 18:53:38 volumio-rpi4-living kernel: usb usb2: Manufacturer: Linux 6.12.27-v7l+ xhci-hcd May 28 18:53:38 volumio-rpi4-living kernel: usb usb2: SerialNumber: 0000:01:00.0 May 28 18:53:38 volumio-rpi4-living kernel: hub 2-0:1.0: USB hub found May 28 18:53:38 volumio-rpi4-living kernel: hub 2-0:1.0: 4 ports detected May 28 18:53:38 volumio-rpi4-living kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) May 28 18:53:38 volumio-rpi4-living kernel: dwc_otg: FIQ enabled May 28 18:53:38 volumio-rpi4-living kernel: dwc_otg: NAK holdoff enabled May 28 18:53:38 volumio-rpi4-living kernel: dwc_otg: FIQ split-transaction FSM enabled May 28 18:53:38 volumio-rpi4-living kernel: Module dwc_common_port init May 28 18:53:38 volumio-rpi4-living kernel: usbcore: registered new interface driver uas May 28 18:53:38 volumio-rpi4-living kernel: usbcore: registered new interface driver usb-storage May 28 18:53:38 volumio-rpi4-living kernel: mousedev: PS/2 mouse device common for all mice May 28 18:53:38 volumio-rpi4-living kernel: sdhci: Secure Digital Host Controller Interface driver May 28 18:53:38 volumio-rpi4-living kernel: sdhci: Copyright(c) Pierre Ossman May 28 18:53:38 volumio-rpi4-living kernel: sdhci-pltfm: SDHCI platform and OF driver helper May 28 18:53:38 volumio-rpi4-living kernel: hid: raw HID events driver (C) Jiri Kosina May 28 18:53:38 volumio-rpi4-living kernel: usbcore: registered new interface driver usbhid May 28 18:53:38 volumio-rpi4-living kernel: usbhid: USB HID core driver May 28 18:53:38 volumio-rpi4-living kernel: Initializing XFRM netlink socket May 28 18:53:38 volumio-rpi4-living kernel: NET: Registered PF_PACKET protocol family May 28 18:53:38 volumio-rpi4-living kernel: Key type dns_resolver registered May 28 18:53:38 volumio-rpi4-living kernel: Registering SWP/SWPB emulation handler May 28 18:53:38 volumio-rpi4-living kernel: registered taskstats version 1 May 28 18:53:38 volumio-rpi4-living kernel: Loading compiled-in X.509 certificates May 28 18:53:38 volumio-rpi4-living kernel: Key type .fscrypt registered May 28 18:53:38 volumio-rpi4-living kernel: Key type fscrypt-provisioning registered May 28 18:53:38 volumio-rpi4-living kernel: uart-pl011 fe201000.serial: there is not valid maps for state default May 28 18:53:38 volumio-rpi4-living kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled May 28 18:53:38 volumio-rpi4-living kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 50, base_baud = 0) is a PL011 rev3 May 28 18:53:38 volumio-rpi4-living kernel: serial serial0: tty port ttyAMA1 registered May 28 18:53:38 volumio-rpi4-living kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer May 28 18:53:38 volumio-rpi4-living kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver May 28 18:53:38 volumio-rpi4-living kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 May 28 18:53:38 volumio-rpi4-living kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated May 28 18:53:38 volumio-rpi4-living kernel: of_cfs_init May 28 18:53:38 volumio-rpi4-living kernel: of_cfs_init: OK May 28 18:53:38 volumio-rpi4-living kernel: clk: Disabling unused clocks May 28 18:53:38 volumio-rpi4-living kernel: PM: genpd: Disabling unused power domains May 28 18:53:38 volumio-rpi4-living kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA May 28 18:53:38 volumio-rpi4-living kernel: Freeing unused kernel image (initmem) memory: 2048K May 28 18:53:38 volumio-rpi4-living kernel: Run /init as init process May 28 18:53:38 volumio-rpi4-living kernel: with arguments: May 28 18:53:38 volumio-rpi4-living kernel: /init May 28 18:53:38 volumio-rpi4-living kernel: splash May 28 18:53:38 volumio-rpi4-living kernel: nodebug May 28 18:53:38 volumio-rpi4-living kernel: with environment: May 28 18:53:38 volumio-rpi4-living kernel: HOME=/ May 28 18:53:38 volumio-rpi4-living kernel: TERM=linux May 28 18:53:38 volumio-rpi4-living kernel: numa_policy=interleave May 28 18:53:38 volumio-rpi4-living kernel: numa=fake=1 May 28 18:53:38 volumio-rpi4-living kernel: imgpart=UUID=2d6bf3e1-3b9d-416b-94c7-51c320b903be May 28 18:53:38 volumio-rpi4-living kernel: imgfile=/volumio_current.sqsh May 28 18:53:38 volumio-rpi4-living kernel: bootpart=UUID=B901-874B May 28 18:53:38 volumio-rpi4-living kernel: datapart=UUID=b4f21d74-cb45-46d4-9629-7f69cf9b3464 May 28 18:53:38 volumio-rpi4-living kernel: uuidconfig=cmdline.txt May 28 18:53:38 volumio-rpi4-living kernel: pcie_aspm=off May 28 18:53:38 volumio-rpi4-living kernel: bootdelay=7 May 28 18:53:38 volumio-rpi4-living kernel: use_kmsg=no May 28 18:53:38 volumio-rpi4-living kernel: mmc1: new high speed SDIO card at address 0001 May 28 18:53:38 volumio-rpi4-living kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa May 28 18:53:38 volumio-rpi4-living kernel: mmcblk0: mmc0:aaaa SC16G 14.8 GiB May 28 18:53:38 volumio-rpi4-living kernel: mmcblk0: p1 p2 p3 May 28 18:53:38 volumio-rpi4-living kernel: mmcblk0: mmc0:aaaa SC16G 14.8 GiB (quirks 0x0000c000) May 28 18:53:38 volumio-rpi4-living kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd May 28 18:53:38 volumio-rpi4-living kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 May 28 18:53:38 volumio-rpi4-living kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 May 28 18:53:38 volumio-rpi4-living kernel: usb 1-1: Product: USB2.0 Hub May 28 18:53:38 volumio-rpi4-living kernel: hub 1-1:1.0: USB hub found May 28 18:53:38 volumio-rpi4-living kernel: hub 1-1:1.0: 4 ports detected May 28 18:53:38 volumio-rpi4-living kernel: fuse: init (API version 7.41) May 28 18:53:38 volumio-rpi4-living kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 28 18:53:38 volumio-rpi4-living kernel: usb 1-1.4: new high-speed USB device number 3 using xhci_hcd May 28 18:53:38 volumio-rpi4-living kernel: usb 1-1.4: New USB device found, idVendor=2622, idProduct=0040, bcdDevice= 3.30 May 28 18:53:38 volumio-rpi4-living kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 28 18:53:38 volumio-rpi4-living kernel: usb 1-1.4: Product: AUDIOLAB USB Audio 2.0 May 28 18:53:38 volumio-rpi4-living kernel: usb 1-1.4: Manufacturer: AUDIOLAB May 28 18:53:38 volumio-rpi4-living kernel: usb 1-1.4: SerialNumber: 0000 May 28 18:53:38 volumio-rpi4-living kernel: EXT4-fs (mmcblk0p2): recovery complete May 28 18:53:38 volumio-rpi4-living kernel: EXT4-fs (mmcblk0p2): mounted filesystem 2d6bf3e1-3b9d-416b-94c7-51c320b903be r/w with ordered data mode. Quota mode: none. May 28 18:53:38 volumio-rpi4-living kernel: loop0: detected capacity change from 0 to 1344064 May 28 18:53:38 volumio-rpi4-living kernel: EXT4-fs warning (device mmcblk0p3): ext4_clear_journal_err:6278: Filesystem error recorded from previous mount: IO failure May 28 18:53:38 volumio-rpi4-living kernel: EXT4-fs warning (device mmcblk0p3): ext4_clear_journal_err:6286: Marked fs in need of filesystem check. May 28 18:53:38 volumio-rpi4-living kernel: EXT4-fs (mmcblk0p3): warning: mounting fs with errors, running e2fsck is recommended May 28 18:53:38 volumio-rpi4-living kernel: EXT4-fs (mmcblk0p3): Errors on filesystem, clearing orphan list. May 28 18:53:38 volumio-rpi4-living kernel: EXT4-fs (mmcblk0p3): recovery complete May 28 18:53:38 volumio-rpi4-living kernel: EXT4-fs (mmcblk0p3): mounted filesystem b4f21d74-cb45-46d4-9629-7f69cf9b3464 r/w with ordered data mode. Quota mode: none. May 28 18:53:38 volumio-rpi4-living systemd[1]: System time before build time, advancing clock. May 28 18:53:38 volumio-rpi4-living kernel: NET: Registered PF_INET6 protocol family May 28 18:53:38 volumio-rpi4-living kernel: Segment Routing with IPv6 May 28 18:53:38 volumio-rpi4-living kernel: In-situ OAM (IOAM) with IPv6 May 28 18:53:38 volumio-rpi4-living systemd[1]: systemd 252.36-1~deb12u1+rpi1 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) May 28 18:53:38 volumio-rpi4-living systemd[1]: Detected architecture arm. May 28 18:53:38 volumio-rpi4-living systemd[1]: Hostname set to . May 28 18:53:38 volumio-rpi4-living systemd[1]: Queued start job for default target graphical.target. May 28 18:53:38 volumio-rpi4-living systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 28 18:53:38 volumio-rpi4-living systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 28 18:53:38 volumio-rpi4-living systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 28 18:53:38 volumio-rpi4-living systemd[1]: Created slice user.slice - User and Session Slice. May 28 18:53:38 volumio-rpi4-living systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 28 18:53:38 volumio-rpi4-living systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 28 18:53:38 volumio-rpi4-living systemd[1]: Expecting device dev-disk-by\x2duuid-B901\x2d874B.device - /dev/disk/by-uuid/B901-874B... May 28 18:53:38 volumio-rpi4-living systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 28 18:53:38 volumio-rpi4-living systemd[1]: Reached target slices.target - Slice Units. May 28 18:53:38 volumio-rpi4-living systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 28 18:53:38 volumio-rpi4-living systemd[1]: Listening on rpcbind.socket - RPCbind Server Activation Socket. May 28 18:53:38 volumio-rpi4-living systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. May 28 18:53:38 volumio-rpi4-living systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. May 28 18:53:38 volumio-rpi4-living systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. May 28 18:53:38 volumio-rpi4-living systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 28 18:53:38 volumio-rpi4-living systemd[1]: Listening on systemd-journald.socket - Journal Socket. May 28 18:53:38 volumio-rpi4-living systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 28 18:53:38 volumio-rpi4-living systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 28 18:53:38 volumio-rpi4-living systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). May 28 18:53:38 volumio-rpi4-living systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 28 18:53:38 volumio-rpi4-living systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 28 18:53:38 volumio-rpi4-living systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 28 18:53:38 volumio-rpi4-living systemd[1]: auth-rpcgss-module.service - Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). May 28 18:53:38 volumio-rpi4-living systemd[1]: Started dynamicswap.service - dynamicswap service. May 28 18:53:38 volumio-rpi4-living systemd[1]: Reached target swap.target - Swaps. May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting fake-hwclock.service - Restore / save the current clock... May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting systemd-journald.service - Journal Service... May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 28 18:53:38 volumio-rpi4-living kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 28 18:53:38 volumio-rpi4-living systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 28 18:53:38 volumio-rpi4-living systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 28 18:53:38 volumio-rpi4-living systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 28 18:53:38 volumio-rpi4-living systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 28 18:53:38 volumio-rpi4-living systemd[1]: modprobe@configfs.service: Deactivated successfully. May 28 18:53:38 volumio-rpi4-living systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 28 18:53:38 volumio-rpi4-living systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 28 18:53:38 volumio-rpi4-living systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 28 18:53:38 volumio-rpi4-living systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 28 18:53:38 volumio-rpi4-living systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 28 18:53:38 volumio-rpi4-living systemd[1]: modprobe@fuse.service: Deactivated successfully. May 28 18:53:38 volumio-rpi4-living systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 28 18:53:38 volumio-rpi4-living systemd[1]: Finished fake-hwclock.service - Restore / save the current clock. May 28 18:53:38 volumio-rpi4-living systemd[1]: modprobe@loop.service: Deactivated successfully. May 28 18:53:38 volumio-rpi4-living systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 28 18:53:38 volumio-rpi4-living systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 28 18:53:38 volumio-rpi4-living systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 28 18:53:38 volumio-rpi4-living systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 28 18:53:38 volumio-rpi4-living systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 28 18:53:38 volumio-rpi4-living systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 28 18:53:38 volumio-rpi4-living systemd[1]: dynamicswap.service: Deactivated successfully. May 28 18:53:38 volumio-rpi4-living kernel: i2c_dev: i2c /dev entries driver May 28 18:53:38 volumio-rpi4-living systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 28 18:53:38 volumio-rpi4-living systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 28 18:53:38 volumio-rpi4-living systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). May 28 18:53:38 volumio-rpi4-living systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting systemd-random-seed.service - Load/Save Random Seed... May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting systemd-sysusers.service - Create System Users... May 28 18:53:38 volumio-rpi4-living systemd[1]: modprobe@drm.service: Deactivated successfully. May 28 18:53:38 volumio-rpi4-living systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 28 18:53:38 volumio-rpi4-living systemd[1]: Finished systemd-random-seed.service - Load/Save Random Seed. May 28 18:53:38 volumio-rpi4-living systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). May 28 18:53:38 volumio-rpi4-living systemd-journald[325]: Journal started May 28 18:53:38 volumio-rpi4-living systemd-journald[325]: Runtime Journal (/run/log/journal/7b283d09ccb59f23a46ed69d6830299d) is 3.7M, max 30.0M, 26.2M free. May 28 18:53:38 volumio-rpi4-living systemd-modules-load[330]: Inserted module 'i2c_dev' May 28 18:53:38 volumio-rpi4-living fake-hwclock[335]: Wed May 28 15:53:38 UTC 2025 May 28 18:53:38 volumio-rpi4-living systemd[1]: Started systemd-journald.service - Journal Service. May 28 18:53:38 volumio-rpi4-living systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 28 18:53:38 volumio-rpi4-living systemd[1]: Finished systemd-sysusers.service - Create System Users. May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 28 18:53:38 volumio-rpi4-living systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting ifupdown-pre.service - Helper to synchronize boot up for ifupdown... May 28 18:53:38 volumio-rpi4-living systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 28 18:53:38 volumio-rpi4-living systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 28 18:53:38 volumio-rpi4-living systemd[1]: Finished ifupdown-pre.service - Helper to synchronize boot up for ifupdown. May 28 18:53:38 volumio-rpi4-living systemd-udevd[352]: Using default interface naming scheme 'v252'. May 28 18:53:38 volumio-rpi4-living systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting plymouth-start.service - Show Plymouth Boot Screen... May 28 18:53:38 volumio-rpi4-living systemd[1]: Started plymouth-start.service - Show Plymouth Boot Screen. May 28 18:53:38 volumio-rpi4-living systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). May 28 18:53:38 volumio-rpi4-living systemd[1]: Started systemd-ask-password-plymouth.path - Forward Password Requests to Plymouth Directory Watch. May 28 18:53:38 volumio-rpi4-living systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 28 18:53:38 volumio-rpi4-living systemd[1]: Reached target paths.target - Path Units. May 28 18:53:38 volumio-rpi4-living (udev-worker)[365]: Network interface NamePolicy= disabled on kernel command line. May 28 18:53:38 volumio-rpi4-living kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 May 28 18:53:38 volumio-rpi4-living kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem May 28 18:53:38 volumio-rpi4-living systemd[1]: Found device dev-disk-by\x2duuid-B901\x2d874B.device - /dev/disk/by-uuid/B901-874B. May 28 18:53:38 volumio-rpi4-living kernel: mc: Linux media interface: v0.10 May 28 18:53:38 volumio-rpi4-living systemd[1]: Starting systemd-fsck@dev-disk-by\x2duuid-B901\x2d874B.service - File System Check on /dev/disk/by-uuid/B901-874B... May 28 18:53:38 volumio-rpi4-living systemd[1]: Started systemd-fsckd.service - File System Check Daemon to report status. May 28 18:53:38 volumio-rpi4-living kernel: videodev: Linux video capture interface: v2.00 May 28 18:53:38 volumio-rpi4-living systemd[1]: Expecting device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0... May 28 18:53:38 volumio-rpi4-living kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. May 28 18:53:38 volumio-rpi4-living kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver May 28 18:53:38 volumio-rpi4-living kernel: [vc_sm_connected_init]: start May 28 18:53:38 volumio-rpi4-living kernel: [vc_sm_connected_init]: installed successfully May 28 18:53:38 volumio-rpi4-living systemd[1]: Found device sys-subsystem-net-devices-eth0.device - /sys/subsystem/net/devices/eth0. May 28 18:53:38 volumio-rpi4-living kernel: rpi-hevc-dec feb00000.codec: Device registered as /dev/video19 May 28 18:53:38 volumio-rpi4-living kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database May 28 18:53:38 volumio-rpi4-living kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. May 28 18:53:39 volumio-rpi4-living kernel: Bluetooth: Core ver 2.22 May 28 18:53:39 volumio-rpi4-living kernel: NET: Registered PF_BLUETOOTH protocol family May 28 18:53:39 volumio-rpi4-living kernel: Bluetooth: HCI device and connection manager initialized May 28 18:53:39 volumio-rpi4-living kernel: Bluetooth: HCI socket layer initialized May 28 18:53:39 volumio-rpi4-living kernel: Bluetooth: L2CAP socket layer initialized May 28 18:53:39 volumio-rpi4-living kernel: Bluetooth: SCO socket layer initialized May 28 18:53:39 volumio-rpi4-living kernel: input: raspberrypi-ts as /devices/platform/soc/soc:firmware/soc:firmware:touchscreen/input/input0 May 28 18:53:39 volumio-rpi4-living systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. May 28 18:53:39 volumio-rpi4-living kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. May 28 18:53:39 volumio-rpi4-living kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored May 28 18:53:39 volumio-rpi4-living kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. May 28 18:53:39 volumio-rpi4-living kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. May 28 18:53:39 volumio-rpi4-living kernel: Bluetooth: HCI UART driver ver 2.3 May 28 18:53:39 volumio-rpi4-living kernel: Bluetooth: HCI UART protocol H4 registered May 28 18:53:39 volumio-rpi4-living kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered May 28 18:53:39 volumio-rpi4-living kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' May 28 18:53:39 volumio-rpi4-living kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' May 28 18:53:39 volumio-rpi4-living kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' May 28 18:53:39 volumio-rpi4-living kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' May 28 18:53:39 volumio-rpi4-living kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. May 28 18:53:39 volumio-rpi4-living kernel: Bluetooth: HCI UART protocol Broadcom registered May 28 18:53:39 volumio-rpi4-living kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator May 28 18:53:39 volumio-rpi4-living kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-audio bcm2835-audio: card created with 8 channels May 28 18:53:39 volumio-rpi4-living systemd[1]: Mounting tmp.mount - /tmp... May 28 18:53:39 volumio-rpi4-living systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller May 28 18:53:39 volumio-rpi4-living systemd[1]: Mounting var-log.mount - /var/log... May 28 18:53:39 volumio-rpi4-living systemd[1]: Mounting var-spool-cups.mount - /var/spool/cups... May 28 18:53:39 volumio-rpi4-living systemd[1]: Mounted tmp.mount - /tmp. May 28 18:53:39 volumio-rpi4-living systemd[1]: Mounted var-log.mount - /var/log. May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp May 28 18:53:39 volumio-rpi4-living systemd[1]: Mounted var-spool-cups.mount - /var/spool/cups. May 28 18:53:39 volumio-rpi4-living systemd[1]: Mounting var-spool-cups-tmp.mount - /var/spool/cups/tmp... May 28 18:53:39 volumio-rpi4-living systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp May 28 18:53:39 volumio-rpi4-living systemd[1]: Mounted var-spool-cups-tmp.mount - /var/spool/cups/tmp. May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 May 28 18:53:39 volumio-rpi4-living kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image May 28 18:53:39 volumio-rpi4-living systemd-fsck[417]: fsck.fat 4.2 (2021-01-31) May 28 18:53:39 volumio-rpi4-living systemd-fsck[417]: /config.txt May 28 18:53:39 volumio-rpi4-living systemd-fsck[417]: Contains a free cluster (294299). Assuming EOF. May 28 18:53:39 volumio-rpi4-living systemd-fsck[417]: /config.txt May 28 18:53:39 volumio-rpi4-living systemd-fsck[417]: File size is 270 bytes, cluster chain length is 0 bytes. May 28 18:53:39 volumio-rpi4-living systemd-fsck[417]: Truncating file to 0 bytes. May 28 18:53:39 volumio-rpi4-living systemd-fsck[417]: Reclaimed 1 unused cluster (512 bytes) in 1 chain. May 28 18:53:39 volumio-rpi4-living systemd-fsck[417]: *** Filesystem was changed *** May 28 18:53:39 volumio-rpi4-living systemd-fsck[417]: Writing changes. May 28 18:53:39 volumio-rpi4-living systemd-fsck[417]: /dev/mmcblk0p1: 425 files, 180028/491992 clusters May 28 18:53:39 volumio-rpi4-living systemd[1]: Finished systemd-fsck@dev-disk-by\x2duuid-B901\x2d874B.service - File System Check on /dev/disk/by-uuid/B901-874B. May 28 18:53:39 volumio-rpi4-living systemd[1]: boot.mount: Directory /boot to mount over is not empty, mounting anyway. May 28 18:53:39 volumio-rpi4-living systemd-journald[325]: Runtime Journal (/run/log/journal/7b283d09ccb59f23a46ed69d6830299d) is 3.7M, max 30.0M, 26.2M free. May 28 18:53:39 volumio-rpi4-living systemd[1]: Mounting boot.mount - /boot... May 28 18:53:39 volumio-rpi4-living systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 28 18:53:39 volumio-rpi4-living systemd-journald[325]: Received client request to flush runtime journal. May 28 18:53:39 volumio-rpi4-living systemd[1]: Mounted boot.mount - /boot. May 28 18:53:39 volumio-rpi4-living systemd[1]: Reached target local-fs.target - Local File Systems. May 28 18:53:39 volumio-rpi4-living systemd[1]: Started ifup@eth0.service - ifup for eth0. May 28 18:53:39 volumio-rpi4-living systemd[1]: Starting networking.service - Raise network interfaces... May 28 18:53:39 volumio-rpi4-living systemd[1]: Starting plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data... May 28 18:53:39 volumio-rpi4-living systemd[1]: Starting systemd-binfmt.service - Set Up Additional Binary Formats... May 28 18:53:39 volumio-rpi4-living systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). May 28 18:53:39 volumio-rpi4-living systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 28 18:53:39 volumio-rpi4-living systemd[1]: Finished plymouth-read-write.service - Tell Plymouth To Write Out Runtime Data. May 28 18:53:39 volumio-rpi4-living systemd[1]: Received SIGRTMIN+20 from PID 197 (plymouthd). May 28 18:53:39 volumio-rpi4-living systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 485 (systemd-binfmt) May 28 18:53:39 volumio-rpi4-living ifup[496]: command failed: No such device (-19) May 28 18:53:39 volumio-rpi4-living sh[497]: command failed: No such device (-19) May 28 18:53:39 volumio-rpi4-living systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 28 18:53:39 volumio-rpi4-living sh[523]: dhcpcd-9.4.1 starting May 28 18:53:39 volumio-rpi4-living dhcpcd[523]: dhcpcd-9.4.1 starting May 28 18:53:39 volumio-rpi4-living dhcpcd[533]: DUID 00:01:00:01:2f:c2:e8:04:dc:a6:32:33:a7:9c May 28 18:53:39 volumio-rpi4-living sh[523]: DUID 00:01:00:01:2f:c2:e8:04:dc:a6:32:33:a7:9c May 28 18:53:39 volumio-rpi4-living kernel: Bluetooth: hci0: BCM: chip id 107 May 28 18:53:39 volumio-rpi4-living kernel: Bluetooth: hci0: BCM: features 0x2f May 28 18:53:39 volumio-rpi4-living kernel: Bluetooth: hci0: BCM4345C0 May 28 18:53:39 volumio-rpi4-living kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 May 28 18:53:39 volumio-rpi4-living kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.raspberrypi,4-model-b.hcd' Patch May 28 18:53:39 volumio-rpi4-living systemd[1]: Started haveged.service - Entropy Daemon based on the HAVEGE algorithm. May 28 18:53:39 volumio-rpi4-living systemd[1]: Starting rpcbind.service - RPC bind portmap service... May 28 18:53:39 volumio-rpi4-living systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 28 18:53:39 volumio-rpi4-living kernel: 8021q: 802.1Q VLAN Support v1.8 May 28 18:53:39 volumio-rpi4-living kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 May 28 18:53:39 volumio-rpi4-living kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 May 28 18:53:39 volumio-rpi4-living kernel: usbcore: registered new interface driver brcmfmac May 28 18:53:39 volumio-rpi4-living systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 28 18:53:39 volumio-rpi4-living ifup[550]: command failed: No such device (-19) May 28 18:53:39 volumio-rpi4-living haveged[526]: haveged: command socket is listening at fd 3 May 28 18:53:39 volumio-rpi4-living dhcpcd[533]: eth0: waiting for carrier May 28 18:53:39 volumio-rpi4-living sh[523]: eth0: waiting for carrier May 28 18:53:39 volumio-rpi4-living kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) May 28 18:53:39 volumio-rpi4-living kernel: bcmgenet fd580000.ethernet eth0: Link is Down May 28 18:53:39 volumio-rpi4-living systemd[1]: Started rpcbind.service - RPC bind portmap service. May 28 18:53:39 volumio-rpi4-living kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) May 28 18:53:39 volumio-rpi4-living kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Aug 29 2023 01:47:08 version 7.45.265 (28bca26 CY) FWID 01-b677b91b May 28 18:53:39 volumio-rpi4-living systemd[1]: Reached target rpcbind.target - RPC Port Mapper. May 28 18:53:39 volumio-rpi4-living systemd[1]: Created slice system-systemd\x2dbacklight.slice - Slice /system/systemd-backlight. May 28 18:53:39 volumio-rpi4-living systemd[1]: Starting systemd-backlight@backlight:rpi_backlight.service - Load/Save Screen Backlight Brightness of backlight:rpi_backlight... May 28 18:53:39 volumio-rpi4-living systemd[1]: Listening on systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. May 28 18:53:39 volumio-rpi4-living systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... May 28 18:53:39 volumio-rpi4-living kernel: usbcore: registered new interface driver snd-usb-audio May 28 18:53:39 volumio-rpi4-living systemd[1]: Finished systemd-backlight@backlight:rpi_backlight.service - Load/Save Screen Backlight Brightness of backlight:rpi_backlight. May 28 18:53:39 volumio-rpi4-living (udev-worker)[356]: hci0: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1. May 28 18:53:40 volumio-rpi4-living (udev-worker)[363]: Network interface NamePolicy= disabled on kernel command line. May 28 18:53:40 volumio-rpi4-living systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. May 28 18:53:40 volumio-rpi4-living systemd[1]: systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch was skipped because of an unmet condition check (ConditionPathExists=!/run/plymouth/pid). May 28 18:53:40 volumio-rpi4-living systemd[1]: dev-hugepages.mount - Huge Pages File System was skipped because of an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages). May 28 18:53:40 volumio-rpi4-living (udev-worker)[365]: mouse0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 28 18:53:40 volumio-rpi4-living systemd[1]: Mounting proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System... May 28 18:53:40 volumio-rpi4-living systemd[1]: Mounting run-rpc_pipefs.mount - RPC Pipe File System... May 28 18:53:40 volumio-rpi4-living systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 28 18:53:40 volumio-rpi4-living systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 28 18:53:40 volumio-rpi4-living systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 28 18:53:40 volumio-rpi4-living systemd[1]: systemd-firstboot.service - First Boot Wizard was skipped because of an unmet condition check (ConditionFirstBoot=yes). May 28 18:53:40 volumio-rpi4-living systemd[1]: first-boot-complete.target - First Boot Complete was skipped because of an unmet condition check (ConditionFirstBoot=yes). May 28 18:53:40 volumio-rpi4-living systemd[1]: systemd-machine-id-commit.service - Commit a transient machine-id on disk was skipped because of an unmet condition check (ConditionPathIsMountPoint=/etc/machine-id). May 28 18:53:40 volumio-rpi4-living systemd[1]: Mounted run-rpc_pipefs.mount - RPC Pipe File System. May 28 18:53:40 volumio-rpi4-living systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 28 18:53:40 volumio-rpi4-living systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 28 18:53:40 volumio-rpi4-living systemd[1]: Mounted proc-sys-fs-binfmt_misc.mount - Arbitrary Executable File Formats File System. May 28 18:53:40 volumio-rpi4-living systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 28 18:53:40 volumio-rpi4-living systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 28 18:53:40 volumio-rpi4-living systemd[1]: modprobe@loop.service: Deactivated successfully. May 28 18:53:40 volumio-rpi4-living systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 28 18:53:40 volumio-rpi4-living systemd[1]: Finished systemd-binfmt.service - Set Up Additional Binary Formats. May 28 18:53:40 volumio-rpi4-living systemd[1]: Reached target rpc_pipefs.target. May 28 18:53:40 volumio-rpi4-living systemd[1]: rpc-gssd.service - RPC security service for NFS client and server was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). May 28 18:53:40 volumio-rpi4-living systemd[1]: Reached target nfs-client.target - NFS client services. May 28 18:53:40 volumio-rpi4-living systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 28 18:53:40 volumio-rpi4-living systemd[1]: Reached target remote-fs.target - Remote File Systems. May 28 18:53:40 volumio-rpi4-living systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 28 18:53:40 volumio-rpi4-living systemd[1]: Reached target sysinit.target - System Initialization. May 28 18:53:40 volumio-rpi4-living systemd[1]: Started apt-daily.timer - Daily apt download activities. May 28 18:53:40 volumio-rpi4-living systemd[1]: Started apt-daily-upgrade.timer - Daily apt upgrade and clean activities. May 28 18:53:40 volumio-rpi4-living systemd[1]: Started dpkg-db-backup.timer - Daily dpkg database backup timer. May 28 18:53:40 volumio-rpi4-living systemd[1]: Started e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. May 28 18:53:40 volumio-rpi4-living systemd[1]: Started fstrim.timer - Discard unused blocks once a week. May 28 18:53:40 volumio-rpi4-living systemd[1]: Started ntpsec-rotate-stats.timer - Rotate ntpd stats daily. May 28 18:53:40 volumio-rpi4-living systemd[1]: Started setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. May 28 18:53:40 volumio-rpi4-living systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 28 18:53:40 volumio-rpi4-living systemd[1]: Reached target timers.target - Timer Units. May 28 18:53:40 volumio-rpi4-living systemd[1]: Listening on avahi-daemon.socket - Avahi mDNS/DNS-SD Stack Activation Socket. May 28 18:53:40 volumio-rpi4-living systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 28 18:53:40 volumio-rpi4-living systemd[1]: Listening on triggerhappy.socket. May 28 18:53:40 volumio-rpi4-living systemd[1]: Reached target sockets.target - Socket Units. May 28 18:53:40 volumio-rpi4-living systemd[1]: systemd-pcrphase-sysinit.service - TPM2 PCR Barrier (Initialization) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 28 18:53:40 volumio-rpi4-living systemd[1]: Reached target basic.target - Basic System. May 28 18:53:40 volumio-rpi4-living (udev-worker)[364]: event0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 28 18:53:40 volumio-rpi4-living systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... May 28 18:53:40 volumio-rpi4-living systemd[1]: Starting bluetooth.service - Bluetooth service... May 28 18:53:40 volumio-rpi4-living systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 28 18:53:40 volumio-rpi4-living systemd[1]: Starting e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots... May 28 18:53:40 volumio-rpi4-living systemd[1]: getty-static.service - getty on tty2-tty6 if dbus and logind are not available was skipped because of an unmet condition check (ConditionPathExists=!/usr/bin/dbus-daemon). May 28 18:53:40 volumio-rpi4-living systemd[1]: headless_wireless.service - Copy user wpa_supplicant.conf was skipped because of an unmet condition check (ConditionPathExists=/boot/wpa_supplicant.conf). May 28 18:53:40 volumio-rpi4-living systemd[1]: Started iptables.service - Volumio Iptables Module. May 28 18:53:40 volumio-rpi4-living systemd[1]: Starting loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... May 28 18:53:40 volumio-rpi4-living kernel: Bluetooth: hci0: BCM: features 0x2f May 28 18:53:40 volumio-rpi4-living kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+-0190 May 28 18:53:40 volumio-rpi4-living kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0382 May 28 18:53:40 volumio-rpi4-living systemd[1]: Starting rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates... May 28 18:53:40 volumio-rpi4-living systemd[1]: Starting systemd-logind.service - User Login Management... May 28 18:53:40 volumio-rpi4-living systemd[1]: systemd-pcrphase.service - TPM2 PCR Barrier (User) was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). May 28 18:53:40 volumio-rpi4-living systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 28 18:53:40 volumio-rpi4-living avahi-daemon[651]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). May 28 18:53:40 volumio-rpi4-living avahi-daemon[651]: Successfully dropped root privileges. May 28 18:53:40 volumio-rpi4-living avahi-daemon[651]: avahi-daemon 0.8 starting up. May 28 18:53:40 volumio-rpi4-living systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... May 28 18:53:40 volumio-rpi4-living systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 28 18:53:40 volumio-rpi4-living systemd[1]: Started volumio-remote-updater.service - Volumio Remote OTA Updater. May 28 18:53:40 volumio-rpi4-living systemd[1]: Starting volumio_rfkill_unblock.service - Manage nl80211 modules blocking state... May 28 18:53:40 volumio-rpi4-living systemd[1]: volumiossh.service - Volumio SSH enabler was skipped because of an unmet condition check (ConditionPathExistsGlob=/boot/ssh{,.txt}). May 28 18:53:40 volumio-rpi4-living systemd[1]: Starting wireless.service - Wireless Services... May 28 18:53:40 volumio-rpi4-living thd[664]: Unable to parse trigger line: May 28 18:53:40 volumio-rpi4-living thd[664]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle May 28 18:53:40 volumio-rpi4-living thd[664]: Unable to parse trigger line: May 28 18:53:40 volumio-rpi4-living thd[664]: Unable to parse trigger line: May 28 18:53:40 volumio-rpi4-living thd[664]: Unable to parse trigger line: May 28 18:53:40 volumio-rpi4-living thd[664]: Unable to parse trigger line: May 28 18:53:40 volumio-rpi4-living thd[664]: Unable to parse trigger line: May 28 18:53:40 volumio-rpi4-living thd[664]: Unable to parse trigger line: May 28 18:53:40 volumio-rpi4-living thd[664]: Found socket passed from systemd May 28 18:53:40 volumio-rpi4-living systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. May 28 18:53:40 volumio-rpi4-living volumio_rfkill_unblock.sh[668]: Volumio WiFi Soft Blocked check script May 28 18:53:40 volumio-rpi4-living volumio_rfkill_unblock.sh[668]: Check if rfkill is available May 28 18:53:40 volumio-rpi4-living volumio_rfkill_unblock.sh[668]: Check if rfkill listed devices are already unblocked May 28 18:53:40 volumio-rpi4-living systemd[1]: e2scrub_reap.service: Deactivated successfully. May 28 18:53:40 volumio-rpi4-living systemd[1]: Finished e2scrub_reap.service - Remove Stale Online ext4 Metadata Check Snapshots. May 28 18:53:40 volumio-rpi4-living haveged[526]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 10.2.1 CTV); collect: 128K May 28 18:53:40 volumio-rpi4-living haveged[526]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14748/63356 May 28 18:53:40 volumio-rpi4-living haveged[526]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00005 May 28 18:53:40 volumio-rpi4-living haveged[526]: haveged: fills: 0, generated: 0 May 28 18:53:40 volumio-rpi4-living volumio_rfkill_unblock.sh[668]: Wi-Fi is already unblocked. May 28 18:53:40 volumio-rpi4-living systemd[1]: Finished networking.service - Raise network interfaces. May 28 18:53:40 volumio-rpi4-living systemd[1]: Finished volumio_rfkill_unblock.service - Manage nl80211 modules blocking state. May 28 18:53:40 volumio-rpi4-living systemd-logind[662]: New seat seat0. May 28 18:53:40 volumio-rpi4-living systemd[1]: Condition check resulted in sys-subsystem-net-devices-wlan0.device - /sys/subsystem/net/devices/wlan0 being skipped. May 28 18:53:40 volumio-rpi4-living systemd[1]: alsa-state.service - Manage Sound Card State (restore and store) was skipped because of an unmet condition check (ConditionPathExists=/etc/alsa/state-daemon.conf). May 28 18:53:40 volumio-rpi4-living systemd[1]: Starting alsa-restore.service - Save/Restore Sound Card State... May 28 18:53:40 volumio-rpi4-living systemd[1]: Starting dhcpcd.service - DHCP Client Daemon on all interfaces... May 28 18:53:40 volumio-rpi4-living systemd[1]: Started ifup@wlan0.service - ifup for wlan0. May 28 18:53:40 volumio-rpi4-living alsactl[688]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 28 18:53:40 volumio-rpi4-living bluetoothd[652]: Bluetooth daemon 5.72 May 28 18:53:40 volumio-rpi4-living systemd[1]: Finished alsa-restore.service - Save/Restore Sound Card State. May 28 18:53:40 volumio-rpi4-living systemd[1]: Reached target sound.target - Sound Card. May 28 18:53:40 volumio-rpi4-living kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 May 28 18:53:40 volumio-rpi4-living volumio-remote-updater[667]: Volumio OTA Client: 1.7.0 May 28 18:53:40 volumio-rpi4-living volumio-remote-updater[667]: Error: No active session May 28 18:53:40 volumio-rpi4-living volumio-remote-updater[667]: [2025-05-28 18:53:40] [info] asio async_connect error: asio.system:111 (Connection refused) May 28 18:53:40 volumio-rpi4-living volumio-remote-updater[667]: [2025-05-28 18:53:40] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) May 28 18:53:40 volumio-rpi4-living volumio-remote-updater[667]: [2025-05-28 18:53:40] [error] handle_connect error: Connection refused May 28 18:53:40 volumio-rpi4-living kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 28 18:53:40 volumio-rpi4-living dhcpcd[694]: dhcpcd-9.4.1 starting May 28 18:53:40 volumio-rpi4-living dhcpcd[722]: dev: loaded udev May 28 18:53:41 volumio-rpi4-living dhcpcd[722]: DUID 00:01:00:01:2f:c2:e8:04:dc:a6:32:33:a7:9c May 28 18:53:41 volumio-rpi4-living systemd[1]: Started dbus.service - D-Bus System Message Bus. May 28 18:53:41 volumio-rpi4-living avahi-daemon[651]: Successfully called chroot(). May 28 18:53:41 volumio-rpi4-living avahi-daemon[651]: Successfully dropped remaining capabilities. May 28 18:53:41 volumio-rpi4-living bluetoothd[652]: Starting SDP server May 28 18:53:41 volumio-rpi4-living bluetoothd[652]: Excluding (cli) wiimote May 28 18:53:41 volumio-rpi4-living bluetoothd[652]: Excluding (cli) sap May 28 18:53:41 volumio-rpi4-living bluetoothd[652]: Excluding (cli) midi May 28 18:53:41 volumio-rpi4-living bluetoothd[652]: Excluding (cli) battery May 28 18:53:41 volumio-rpi4-living systemd[1]: Started dhcpcd.service - DHCP Client Daemon on all interfaces. May 28 18:53:41 volumio-rpi4-living avahi-daemon[651]: Loading service file /services/volumio.service. May 28 18:53:41 volumio-rpi4-living loadcpufreq[656]: Loading cpufreq kernel modules...done (none). May 28 18:53:41 volumio-rpi4-living dbus-daemon[653]: [system] Successfully activated service 'org.freedesktop.systemd1' May 28 18:53:41 volumio-rpi4-living (udev-worker)[355]: pcmC5D0p: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. May 28 18:53:41 volumio-rpi4-living avahi-daemon[651]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. May 28 18:53:41 volumio-rpi4-living avahi-daemon[651]: New relevant interface lo.IPv4 for mDNS. May 28 18:53:41 volumio-rpi4-living avahi-daemon[651]: Network interface enumeration completed. May 28 18:53:41 volumio-rpi4-living avahi-daemon[651]: Registering new address record for 127.0.0.1 on lo.IPv4. May 28 18:53:41 volumio-rpi4-living upmpdcli[665]: Could not open config: /tmp/upmpdcli.conf May 28 18:53:41 volumio-rpi4-living systemd[1]: Starting wpa_supplicant.service - WPA supplicant... May 28 18:53:41 volumio-rpi4-living dhcpcd[722]: eth0: waiting for carrier May 28 18:53:41 volumio-rpi4-living dhcpcd[722]: wlan0: waiting for carrier May 28 18:53:41 volumio-rpi4-living systemd[1]: Started loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. May 28 18:53:41 volumio-rpi4-living systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 28 18:53:41 volumio-rpi4-living kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 28 18:53:41 volumio-rpi4-living kernel: Bluetooth: BNEP filters: protocol multicast May 28 18:53:41 volumio-rpi4-living kernel: Bluetooth: BNEP socket layer initialized May 28 18:53:41 volumio-rpi4-living systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 28 18:53:41 volumio-rpi4-living systemd[1]: iptables.service: Deactivated successfully. May 28 18:53:41 volumio-rpi4-living bluetoothd[652]: Bluetooth management interface 1.23 initialized May 28 18:53:41 volumio-rpi4-living dbus-daemon[653]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.2' (uid=0 pid=652 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") May 28 18:53:41 volumio-rpi4-living bluetoothd[652]: Battery Provider Manager created May 28 18:53:41 volumio-rpi4-living kernel: Bluetooth: MGMT ver 1.23 May 28 18:53:41 volumio-rpi4-living systemd[1]: Started bluetooth.service - Bluetooth service. May 28 18:53:41 volumio-rpi4-living systemd[1]: Started systemd-logind.service - User Login Management. May 28 18:53:41 volumio-rpi4-living systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. May 28 18:53:41 volumio-rpi4-living kernel: NET: Registered PF_ALG protocol family May 28 18:53:41 volumio-rpi4-living systemd[1]: Starting bluealsa.service - BlueALSA service... May 28 18:53:41 volumio-rpi4-living sh[805]: wlan0=wlan0 May 28 18:53:41 volumio-rpi4-living systemd[1]: Starting cpufrequtils.service - LSB: set CPUFreq kernel parameters... May 28 18:53:41 volumio-rpi4-living systemd[1]: Starting systemd-hostnamed.service - Hostname Service... May 28 18:53:41 volumio-rpi4-living kernel: cryptd: max_cpu_qlen set to 1000 May 28 18:53:41 volumio-rpi4-living wpa_supplicant[766]: Successfully initialized wpa_supplicant May 28 18:53:41 volumio-rpi4-living systemd[1]: Started wpa_supplicant.service - WPA supplicant. May 28 18:53:41 volumio-rpi4-living systemd[1]: Reached target network.target - Network. May 28 18:53:41 volumio-rpi4-living systemd[1]: Reached target network-online.target - Network is Online. May 28 18:53:41 volumio-rpi4-living cpufrequtils[811]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. May 28 18:53:41 volumio-rpi4-living systemd[1]: Starting ifplugd.service - LSB: Brings up/down network automatically... May 28 18:53:41 volumio-rpi4-living systemd[1]: Starting nmbd.service - Samba NMB Daemon... May 28 18:53:41 volumio-rpi4-living systemd[1]: Starting ntpsec.service - Network Time Service... May 28 18:53:41 volumio-rpi4-living systemd[1]: Starting rc-local.service - /etc/rc.local Compatibility... May 28 18:53:41 volumio-rpi4-living systemd[1]: Starting rpc-statd-notify.service - Notify NFS peers of a restart... May 28 18:53:41 volumio-rpi4-living systemd[1]: Starting samba-ad-dc.service - Samba AD Daemon... May 28 18:53:41 volumio-rpi4-living sm-notify[846]: Version 2.6.2 starting May 28 18:53:41 volumio-rpi4-living systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 28 18:53:41 volumio-rpi4-living bluetoothd[652]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled May 28 18:53:41 volumio-rpi4-living bluetoothd[652]: bap: Operation not supported (95) May 28 18:53:41 volumio-rpi4-living bluetoothd[652]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16 May 28 18:53:41 volumio-rpi4-living systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 28 18:53:41 volumio-rpi4-living systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 28 18:53:41 volumio-rpi4-living dbus-daemon[653]: [system] Successfully activated service 'org.freedesktop.hostname1' May 28 18:53:41 volumio-rpi4-living systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. May 28 18:53:41 volumio-rpi4-living systemd[1]: Starting welcome.service - Show a welcome message on console... May 28 18:53:41 volumio-rpi4-living systemd[1]: Started cpufrequtils.service - LSB: set CPUFreq kernel parameters. May 28 18:53:41 volumio-rpi4-living systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. May 28 18:53:41 volumio-rpi4-living systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. May 28 18:53:41 volumio-rpi4-living systemd[1]: Started systemd-hostnamed.service - Hostname Service. May 28 18:53:41 volumio-rpi4-living systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 28 18:53:41 volumio-rpi4-living dbus-daemon[653]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.6' (uid=0 pid=860 comm="timedatectl show --property=NTPSynchronized --valu") May 28 18:53:41 volumio-rpi4-living systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... May 28 18:53:41 volumio-rpi4-living welcome[864]: Resolved ip:[0] May 28 18:53:41 volumio-rpi4-living systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 28 18:53:41 volumio-rpi4-living ifplugd(eth0)[884]: ifplugd 0.28 initializing. May 28 18:53:41 volumio-rpi4-living ifplugd(eth0)[884]: Using interface eth0/DC:A6:32:33:A7:9C with driver (version: 6.12.27-v7l+) May 28 18:53:41 volumio-rpi4-living ifplugd(eth0)[884]: Using detection mode: SIOCETHTOOL May 28 18:53:41 volumio-rpi4-living ifplugd(eth0)[884]: Initialization complete, link beat not detected. May 28 18:53:41 volumio-rpi4-living ntpd[862]: INIT: ntpd ntpsec-1.2.2: Starting May 28 18:53:41 volumio-rpi4-living ntp-systemd-wrapper[862]: 2025-05-28T18:53:41 ntpd[862]: INIT: ntpd ntpsec-1.2.2: Starting May 28 18:53:41 volumio-rpi4-living ntp-systemd-wrapper[862]: 2025-05-28T18:53:41 ntpd[862]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec May 28 18:53:41 volumio-rpi4-living ntpd[862]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec May 28 18:53:41 volumio-rpi4-living systemd[1]: Started ntpsec.service - Network Time Service. May 28 18:53:41 volumio-rpi4-living ntpd[886]: INIT: precision = 0.426 usec (-21) May 28 18:53:41 volumio-rpi4-living ntpd[886]: INIT: successfully locked into RAM May 28 18:53:41 volumio-rpi4-living ntpd[886]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf May 28 18:53:41 volumio-rpi4-living ntpd[886]: CONFIG: restrict nopeer ignored May 28 18:53:41 volumio-rpi4-living ntpd[886]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 28 18:53:41 volumio-rpi4-living ntpd[886]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2025-12-28T00:00Z last=2017-01-01T00:00Z ofs=37 May 28 18:53:41 volumio-rpi4-living ntpd[886]: INIT: Using SO_TIMESTAMPNS(ns) May 28 18:53:41 volumio-rpi4-living ntpd[886]: IO: Listen and drop on 0 v6wildcard [::]:123 May 28 18:53:41 volumio-rpi4-living ntpd[886]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 28 18:53:41 volumio-rpi4-living ntpd[886]: IO: Listen normally on 2 lo 127.0.0.1:123 May 28 18:53:41 volumio-rpi4-living ntpd[886]: IO: Listening on routing socket on fd #19 for interface updates May 28 18:53:41 volumio-rpi4-living ifplugd[828]: Network Interface Plugging Daemon...start eth0...done. May 28 18:53:41 volumio-rpi4-living systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. May 28 18:53:41 volumio-rpi4-living systemd[1]: Finished welcome.service - Show a welcome message on console. May 28 18:53:41 volumio-rpi4-living ntpd[886]: INIT: This system has a 32-bit time_t. May 28 18:53:41 volumio-rpi4-living ntpd[886]: INIT: This ntpd will fail on 2038-01-19T03:14:07Z. May 28 18:53:41 volumio-rpi4-living ntpd[886]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 28 18:53:41 volumio-rpi4-living ntpd[886]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 May 28 18:53:41 volumio-rpi4-living ntpd[886]: INIT: Running with OpenSSL 3.0.16 11 Feb 2025, 30000100 May 28 18:53:41 volumio-rpi4-living ntpd[886]: NTSc: Using system default root certificates. May 28 18:53:41 volumio-rpi4-living avahi-daemon[651]: Server startup complete. Host name is volumio-rpi4-living.local. Local service cookie is 3675517772. May 28 18:53:41 volumio-rpi4-living dbus-daemon[653]: [system] Successfully activated service 'org.freedesktop.timedate1' May 28 18:53:41 volumio-rpi4-living systemd[1]: Started systemd-timedated.service - Time & Date Service. May 28 18:53:41 volumio-rpi4-living bash[851]: Time is already synchronized. May 28 18:53:41 volumio-rpi4-living systemd[1]: setdatetime-helper.service: Deactivated successfully. May 28 18:53:41 volumio-rpi4-living systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 28 18:53:42 volumio-rpi4-living ntpd[886]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory May 28 18:53:42 volumio-rpi4-living rpi-eeprom-update[657]: BOOTLOADER: up to date May 28 18:53:42 volumio-rpi4-living rpi-eeprom-update[657]: CURRENT: Thu May 8 15:21:35 UTC 2025 (1746717695) May 28 18:53:42 volumio-rpi4-living rpi-eeprom-update[657]: LATEST: Thu May 8 15:21:35 UTC 2025 (1746717695) May 28 18:53:42 volumio-rpi4-living rpi-eeprom-update[657]: RELEASE: default (/usr/lib/firmware/raspberrypi/bootloader-2711/default) May 28 18:53:42 volumio-rpi4-living rpi-eeprom-update[657]: Use raspi-config to change the release. May 28 18:53:42 volumio-rpi4-living rpi-eeprom-update[657]: VL805_FW: Dedicated VL805 EEPROM May 28 18:53:42 volumio-rpi4-living rpi-eeprom-update[657]: VL805: up to date May 28 18:53:42 volumio-rpi4-living rpi-eeprom-update[657]: CURRENT: 000138c0 May 28 18:53:42 volumio-rpi4-living rpi-eeprom-update[657]: LATEST: 000138c0 May 28 18:53:42 volumio-rpi4-living systemd[1]: Finished rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates. May 28 18:53:42 volumio-rpi4-living bluealsa[806]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa May 28 18:53:42 volumio-rpi4-living bluealsa[806]: main.c:663: Starting main dispatching loop May 28 18:53:42 volumio-rpi4-living systemd[1]: Started bluealsa.service - BlueALSA service. May 28 18:53:42 volumio-rpi4-living bluealsa[806]: main.c:118: Acquired D-Bus service name: org.bluealsa May 28 18:53:42 volumio-rpi4-living bluealsa[806]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa May 28 18:53:42 volumio-rpi4-living systemd[1]: Reached target bluetooth.target - Bluetooth Support. May 28 18:53:42 volumio-rpi4-living bluealsa[806]: bluez.c:153: Registering media application: /org/bluez/hci0 May 28 18:53:42 volumio-rpi4-living bluealsa[806]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 May 28 18:53:42 volumio-rpi4-living bluealsa[806]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 May 28 18:53:42 volumio-rpi4-living bluealsa[806]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 May 28 18:53:42 volumio-rpi4-living bluealsa[806]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 May 28 18:53:42 volumio-rpi4-living bluetoothd[652]: Endpoint registered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/source/1 May 28 18:53:42 volumio-rpi4-living bluetoothd[652]: Endpoint registered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/sink/2 May 28 18:53:42 volumio-rpi4-living bluetoothd[652]: Endpoint registered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/source/2 May 28 18:53:42 volumio-rpi4-living bluetoothd[652]: Endpoint registered: sender=:1.8 path=/org/bluez/hci0/A2DP/SBC/sink/1 May 28 18:53:42 volumio-rpi4-living avahi-daemon[651]: Service "Volumio-rpi4-living" (/services/volumio.service) successfully established. May 28 18:53:42 volumio-rpi4-living systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. May 28 18:53:42 volumio-rpi4-living systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. May 28 18:53:43 volumio-rpi4-living ntpd[886]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 28 18:53:43 volumio-rpi4-living ntpd[886]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 28 18:53:43 volumio-rpi4-living ntpd[886]: DNS: dns_check: DNS error: -11, System error May 28 18:53:43 volumio-rpi4-living ntpd[886]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 May 28 18:53:43 volumio-rpi4-living nmbd[938]: [2025/05/28 18:53:43.285130, 0] ../../source3/nmbd/nmbd.c:901(main) May 28 18:53:43 volumio-rpi4-living nmbd[938]: nmbd version 4.17.8-Raspbian started. May 28 18:53:43 volumio-rpi4-living nmbd[938]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 28 18:53:43 volumio-rpi4-living nmbd[938]: [2025/05/28 18:53:43.290657, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) May 28 18:53:43 volumio-rpi4-living nmbd[938]: started asyncdns process 939 May 28 18:53:43 volumio-rpi4-living nmbd[938]: [2025/05/28 18:53:43.292112, 0] ../../lib/util/become_daemon.c:150(daemon_status) May 28 18:53:43 volumio-rpi4-living nmbd[938]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... May 28 18:53:43 volumio-rpi4-living nmbd[938]: [2025/05/28 18:53:43.293413, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) May 28 18:53:43 volumio-rpi4-living nmbd[938]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). May 28 18:53:43 volumio-rpi4-living dhcpcd[533]: eth0: carrier acquired May 28 18:53:43 volumio-rpi4-living sh[523]: eth0: carrier acquired May 28 18:53:43 volumio-rpi4-living dhcpcd[722]: eth0: carrier acquired May 28 18:53:43 volumio-rpi4-living kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx May 28 18:53:43 volumio-rpi4-living dhcpcd[533]: eth0: IAID 32:33:a7:9c May 28 18:53:43 volumio-rpi4-living dhcpcd[722]: eth0: IAID 32:33:a7:9c May 28 18:53:43 volumio-rpi4-living sh[523]: eth0: IAID 32:33:a7:9c May 28 18:53:43 volumio-rpi4-living dhcpcd[722]: eth0: adding address fe80::c326:4718:2a85:20f3 May 28 18:53:43 volumio-rpi4-living sh[523]: eth0: adding address fe80::c326:4718:2a85:20f3 May 28 18:53:43 volumio-rpi4-living dhcpcd[722]: ipv6_addaddr1: Permission denied May 28 18:53:43 volumio-rpi4-living dhcpcd[533]: eth0: adding address fe80::c326:4718:2a85:20f3 May 28 18:53:43 volumio-rpi4-living sh[523]: ipv6_addaddr1: Permission denied May 28 18:53:43 volumio-rpi4-living dhcpcd[533]: ipv6_addaddr1: Permission denied May 28 18:53:44 volumio-rpi4-living ntpd[886]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 May 28 18:53:44 volumio-rpi4-living ntpd[886]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 May 28 18:53:44 volumio-rpi4-living ntpd[886]: DNS: dns_check: DNS error: -11, System error May 28 18:53:44 volumio-rpi4-living ntpd[886]: DNS: dns_take_status: 1.debian.pool.ntp.org=>error, 12 May 28 18:53:44 volumio-rpi4-living sudo[950]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 May 28 18:53:44 volumio-rpi4-living sudo[950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 18:53:44 volumio-rpi4-living sudo[950]: pam_unix(sudo:session): session closed for user root May 28 18:53:44 volumio-rpi4-living sudo[953]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down May 28 18:53:44 volumio-rpi4-living sudo[953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 18:53:44 volumio-rpi4-living dhcpcd[533]: eth0: soliciting an IPv6 router May 28 18:53:44 volumio-rpi4-living sh[523]: eth0: soliciting an IPv6 router May 28 18:53:44 volumio-rpi4-living sudo[953]: pam_unix(sudo:session): session closed for user root May 28 18:53:44 volumio-rpi4-living dhcpcd[722]: eth0: soliciting an IPv6 router May 28 18:53:44 volumio-rpi4-living ifplugd(eth0)[884]: Link beat detected. May 28 18:53:44 volumio-rpi4-living sudo[961]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 28 18:53:44 volumio-rpi4-living sudo[961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 18:53:44 volumio-rpi4-living sudo[961]: pam_unix(sudo:session): session closed for user root May 28 18:53:44 volumio-rpi4-living kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 28 18:53:44 volumio-rpi4-living sudo[963]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 28 18:53:44 volumio-rpi4-living sudo[963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 18:53:44 volumio-rpi4-living sudo[963]: pam_unix(sudo:session): session closed for user root May 28 18:53:44 volumio-rpi4-living ifplugd(eth0)[884]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 28 18:53:44 volumio-rpi4-living sudo[971]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 28 18:53:44 volumio-rpi4-living sudo[971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 18:53:44 volumio-rpi4-living sudo[971]: pam_unix(sudo:session): session closed for user root May 28 18:53:44 volumio-rpi4-living ifplugd(eth0)[884]: client: ifup: waiting for lock on /run/network/ifstate.eth0 May 28 18:53:44 volumio-rpi4-living sudo[978]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 28 18:53:44 volumio-rpi4-living sudo[978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 18:53:45 volumio-rpi4-living systemd[1]: systemd-rfkill.service: Deactivated successfully. May 28 18:53:45 volumio-rpi4-living ntpd[886]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 May 28 18:53:45 volumio-rpi4-living ntpd[886]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 May 28 18:53:45 volumio-rpi4-living ntpd[886]: DNS: dns_check: DNS error: -11, System error May 28 18:53:45 volumio-rpi4-living ntpd[886]: DNS: dns_take_status: 2.debian.pool.ntp.org=>error, 12 May 28 18:53:45 volumio-rpi4-living dhcpcd[533]: eth0: rebinding lease of 192.168.1.191 May 28 18:53:45 volumio-rpi4-living sh[523]: eth0: rebinding lease of 192.168.1.191 May 28 18:53:45 volumio-rpi4-living dhcpcd[722]: eth0: rebinding lease of 192.168.1.191 May 28 18:53:45 volumio-rpi4-living dhcpcd[533]: eth0: probing address 192.168.1.191/24 May 28 18:53:45 volumio-rpi4-living sh[523]: eth0: probing address 192.168.1.191/24 May 28 18:53:45 volumio-rpi4-living dhcpcd[722]: eth0: probing address 192.168.1.191/24 May 28 18:53:45 volumio-rpi4-living volumio-remote-updater[667]: [2025-05-28 18:53:45] [info] asio async_connect error: asio.system:111 (Connection refused) May 28 18:53:45 volumio-rpi4-living volumio-remote-updater[667]: [2025-05-28 18:53:45] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) May 28 18:53:45 volumio-rpi4-living volumio-remote-updater[667]: [2025-05-28 18:53:45] [error] handle_connect error: Connection refused May 28 18:53:46 volumio-rpi4-living ntpd[886]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 May 28 18:53:46 volumio-rpi4-living ntpd[886]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 May 28 18:53:46 volumio-rpi4-living ntpd[886]: DNS: dns_check: DNS error: -11, System error May 28 18:53:46 volumio-rpi4-living ntpd[886]: DNS: dns_take_status: 3.debian.pool.ntp.org=>error, 12 May 28 18:53:47 volumio-rpi4-living sudo[978]: pam_unix(sudo:session): session closed for user root May 28 18:53:47 volumio-rpi4-living sudo[990]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 28 18:53:47 volumio-rpi4-living sudo[990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 18:53:47 volumio-rpi4-living sudo[990]: pam_unix(sudo:session): session closed for user root May 28 18:53:47 volumio-rpi4-living sudo[992]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set RO May 28 18:53:47 volumio-rpi4-living sudo[992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 18:53:47 volumio-rpi4-living sudo[992]: pam_unix(sudo:session): session closed for user root May 28 18:53:47 volumio-rpi4-living sudo[999]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 May 28 18:53:47 volumio-rpi4-living sudo[999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 18:53:47 volumio-rpi4-living sudo[999]: pam_unix(sudo:session): session closed for user root May 28 18:53:47 volumio-rpi4-living sudo[1001]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down May 28 18:53:47 volumio-rpi4-living sudo[1001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 18:53:48 volumio-rpi4-living sudo[1001]: pam_unix(sudo:session): session closed for user root May 28 18:53:48 volumio-rpi4-living avahi-daemon[651]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 28 18:53:48 volumio-rpi4-living avahi-daemon[651]: New relevant interface wlan0.IPv4 for mDNS. May 28 18:53:48 volumio-rpi4-living avahi-daemon[651]: Registering new address record for 192.168.211.1 on wlan0.IPv4. May 28 18:53:48 volumio-rpi4-living kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 28 18:53:48 volumio-rpi4-living systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... May 28 18:53:48 volumio-rpi4-living systemd[1]: welcome.service: Deactivated successfully. May 28 18:53:48 volumio-rpi4-living systemd[1]: Stopped welcome.service - Show a welcome message on console. May 28 18:53:48 volumio-rpi4-living systemd[1]: Stopping welcome.service - Show a welcome message on console... May 28 18:53:48 volumio-rpi4-living systemd[1]: Starting welcome.service - Show a welcome message on console... May 28 18:53:48 volumio-rpi4-living welcome[1009]: Resolved ip:[1] 192.168.211.1 May 28 18:53:48 volumio-rpi4-living systemd[1]: Finished welcome.service - Show a welcome message on console. May 28 18:53:48 volumio-rpi4-living systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 28 18:53:48 volumio-rpi4-living systemd[1]: Started nmbd.service - Samba NMB Daemon. May 28 18:53:48 volumio-rpi4-living systemd[1]: Starting winbind.service - Samba Winbind Daemon... May 28 18:53:48 volumio-rpi4-living dhcpcd[722]: wlan0: carrier acquired May 28 18:53:48 volumio-rpi4-living hostapd[1008]: wlan0: interface state UNINITIALIZED->ENABLED May 28 18:53:48 volumio-rpi4-living hostapd[1008]: wlan0: AP-ENABLED May 28 18:53:48 volumio-rpi4-living dhcpcd[722]: wlan0: IAID 32:33:a7:9d May 28 18:53:48 volumio-rpi4-living dhcpcd[722]: wlan0: adding address fe80::1b2a:5b7b:8421:f23c May 28 18:53:48 volumio-rpi4-living dhcpcd[722]: ipv6_addaddr1: Permission denied May 28 18:53:49 volumio-rpi4-living systemd[1]: Started hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. May 28 18:53:49 volumio-rpi4-living systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... May 28 18:53:49 volumio-rpi4-living iwconfig[1025]: Error for wireless request "Set Power Management" (8B2C) : May 28 18:53:49 volumio-rpi4-living iwconfig[1025]: SET failed on device wlan0 ; Invalid argument. May 28 18:53:49 volumio-rpi4-living systemd[1]: wireless.service: Deactivated successfully. May 28 18:53:49 volumio-rpi4-living systemd[1]: Started wireless.service - Wireless Services. May 28 18:53:49 volumio-rpi4-living systemd[1]: wireless.service: Consumed 22.617s CPU time. May 28 18:53:49 volumio-rpi4-living systemd[1]: Started volumio.service - Volumio Backend Module. May 28 18:53:49 volumio-rpi4-living systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... May 28 18:53:49 volumio-rpi4-living systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. May 28 18:53:49 volumio-rpi4-living systemd[1]: Received SIGRTMIN+21 from PID 197 (plymouthd). May 28 18:53:49 volumio-rpi4-living systemd[1]: Received SIGRTMIN+21 from PID 197 (plymouthd). May 28 18:53:49 volumio-rpi4-living systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. May 28 18:53:49 volumio-rpi4-living systemd[1]: Started getty@tty1.service - Getty on tty1. May 28 18:53:49 volumio-rpi4-living systemd[1]: Reached target getty.target - Login Prompts. May 28 18:53:49 volumio-rpi4-living systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. May 28 18:53:49 volumio-rpi4-living volumio-cpu-tweak[1030]: Setting RT Priority for mpd May 28 18:53:49 volumio-rpi4-living volumio-cpu-tweak[1046]: pid 35's current scheduling policy: SCHED_OTHER May 28 18:53:49 volumio-rpi4-living volumio-cpu-tweak[1046]: pid 35's current scheduling priority: 0 May 28 18:53:49 volumio-rpi4-living volumio-cpu-tweak[1030]: Setting MPD Affinity May 28 18:53:49 volumio-rpi4-living volumio-cpu-tweak[1047]: pid 3's current affinity mask: f May 28 18:53:49 volumio-rpi4-living volumio-cpu-tweak[1030]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 28 18:53:49 volumio-rpi4-living volumio-cpu-tweak[1030]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 28 18:53:49 volumio-rpi4-living dnsmasq[1052]: started, version 2.90 cachesize 150 May 28 18:53:49 volumio-rpi4-living dnsmasq[1052]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile May 28 18:53:49 volumio-rpi4-living dnsmasq-dhcp[1052]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d May 28 18:53:49 volumio-rpi4-living dnsmasq[1052]: reading /etc/resolv.conf May 28 18:53:49 volumio-rpi4-living dnsmasq[1052]: using nameserver 208.67.222.222#53 May 28 18:53:49 volumio-rpi4-living dnsmasq[1052]: using nameserver 208.67.220.220#53 May 28 18:53:49 volumio-rpi4-living dnsmasq[1052]: read /etc/hosts - 6 names May 28 18:53:49 volumio-rpi4-living systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. May 28 18:53:49 volumio-rpi4-living systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 28 18:53:49 volumio-rpi4-living systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. May 28 18:53:49 volumio-rpi4-living winbindd[1017]: [2025/05/28 18:53:49.445629, 0] ../../source3/winbindd/winbindd.c:1440(main) May 28 18:53:49 volumio-rpi4-living winbindd[1017]: winbindd version 4.17.8-Raspbian started. May 28 18:53:49 volumio-rpi4-living winbindd[1017]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 28 18:53:49 volumio-rpi4-living winbindd[1017]: [2025/05/28 18:53:49.464097, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) May 28 18:53:49 volumio-rpi4-living winbindd[1017]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 28 18:53:49 volumio-rpi4-living systemd[1]: Started winbind.service - Samba Winbind Daemon. May 28 18:53:49 volumio-rpi4-living dhcpcd[533]: eth0: leased 192.168.1.191 for 86400 seconds May 28 18:53:49 volumio-rpi4-living sh[523]: eth0: leased 192.168.1.191 for 86400 seconds May 28 18:53:49 volumio-rpi4-living sh[523]: eth0: adding route to 192.168.1.0/24 May 28 18:53:49 volumio-rpi4-living sh[523]: eth0: adding default route via 192.168.1.1 May 28 18:53:49 volumio-rpi4-living avahi-daemon[651]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.191. May 28 18:53:49 volumio-rpi4-living avahi-daemon[651]: New relevant interface eth0.IPv4 for mDNS. May 28 18:53:49 volumio-rpi4-living dhcpcd[533]: eth0: adding route to 192.168.1.0/24 May 28 18:53:49 volumio-rpi4-living avahi-daemon[651]: Registering new address record for 192.168.1.191 on eth0.IPv4. May 28 18:53:49 volumio-rpi4-living dhcpcd[533]: eth0: adding default route via 192.168.1.1 May 28 18:53:49 volumio-rpi4-living systemd[1]: Starting smbd.service - Samba SMB Daemon... May 28 18:53:49 volumio-rpi4-living systemd[1]: welcome.service: Deactivated successfully. May 28 18:53:49 volumio-rpi4-living dnsmasq[1052]: reading /etc/resolv.conf May 28 18:53:49 volumio-rpi4-living systemd[1]: Stopped welcome.service - Show a welcome message on console. May 28 18:53:49 volumio-rpi4-living dnsmasq[1052]: using nameserver 192.168.1.1#53 May 28 18:53:49 volumio-rpi4-living systemd[1]: Stopping welcome.service - Show a welcome message on console... May 28 18:53:49 volumio-rpi4-living dnsmasq[1052]: using nameserver 208.67.222.222#53 May 28 18:53:49 volumio-rpi4-living dnsmasq[1052]: using nameserver 208.67.220.220#53 May 28 18:53:49 volumio-rpi4-living systemd[1]: Starting welcome.service - Show a welcome message on console... May 28 18:53:49 volumio-rpi4-living welcome[1076]: Resolved ip:[2] 192.168.1.191 192.168.211.1 May 28 18:53:49 volumio-rpi4-living sh[523]: forked to background, child pid 532 May 28 18:53:49 volumio-rpi4-living systemd[1]: Finished welcome.service - Show a welcome message on console. May 28 18:53:49 volumio-rpi4-living systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 28 18:53:49 volumio-rpi4-living dhcpcd[722]: wlan0: soliciting an IPv6 router May 28 18:53:49 volumio-rpi4-living sh[1123]: eth0=eth0 May 28 18:53:49 volumio-rpi4-living ifplugd(eth0)[884]: client: ifup: interface eth0 already configured May 28 18:53:50 volumio-rpi4-living ifplugd(eth0)[884]: Program executed successfully. May 28 18:53:50 volumio-rpi4-living smbd[1113]: [2025/05/28 18:53:50.042792, 0] ../../source3/smbd/server.c:1741(main) May 28 18:53:50 volumio-rpi4-living smbd[1113]: smbd version 4.17.8-Raspbian started. May 28 18:53:50 volumio-rpi4-living smbd[1113]: Copyright Andrew Tridgell and the Samba Team 1992-2022 May 28 18:53:50 volumio-rpi4-living dhcpcd[722]: eth0: leased 192.168.1.191 for 86400 seconds May 28 18:53:50 volumio-rpi4-living systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. May 28 18:53:50 volumio-rpi4-living systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... May 28 18:53:50 volumio-rpi4-living systemd[1]: welcome.service: Deactivated successfully. May 28 18:53:50 volumio-rpi4-living systemd[1]: Stopped welcome.service - Show a welcome message on console. May 28 18:53:50 volumio-rpi4-living systemd[1]: Stopping welcome.service - Show a welcome message on console... May 28 18:53:50 volumio-rpi4-living dhcpcd[722]: eth0: adding route to 192.168.1.0/24 May 28 18:53:50 volumio-rpi4-living dhcpcd[722]: eth0: adding default route via 192.168.1.1 May 28 18:53:50 volumio-rpi4-living systemd[1]: Starting welcome.service - Show a welcome message on console... May 28 18:53:50 volumio-rpi4-living welcome[1138]: Resolved ip:[2] 192.168.1.191 192.168.211.1 May 28 18:53:50 volumio-rpi4-living systemd[1]: Finished welcome.service - Show a welcome message on console. May 28 18:53:50 volumio-rpi4-living systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 28 18:53:50 volumio-rpi4-living dhcpcd[722]: wlan0: soliciting a DHCP lease May 28 18:53:50 volumio-rpi4-living systemd[1]: Started smbd.service - Samba SMB Daemon. May 28 18:53:50 volumio-rpi4-living systemd[1]: Reached target multi-user.target - Multi-User System. May 28 18:53:50 volumio-rpi4-living systemd[1]: Reached target graphical.target - Graphical Interface. May 28 18:53:50 volumio-rpi4-living systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... May 28 18:53:50 volumio-rpi4-living systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. May 28 18:53:50 volumio-rpi4-living systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. May 28 18:53:50 volumio-rpi4-living systemd[1]: Startup finished in 12.479s (kernel) + 13.931s (userspace) = 26.410s. May 28 18:53:51 volumio-rpi4-living ntpd[886]: IO: Listen normally on 3 eth0 192.168.1.191:123 May 28 18:53:51 volumio-rpi4-living ntpd[886]: IO: Listen normally on 4 wlan0 192.168.211.1:123 May 28 18:53:51 volumio-rpi4-living ntpd[886]: IO: new interface(s) found: waking up resolver May 28 18:53:51 volumio-rpi4-living ntpd[886]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 May 28 18:53:51 volumio-rpi4-living ntpd[886]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 May 28 18:53:51 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 44.32.77.9 May 28 18:53:51 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 44.32.133.7 May 28 18:53:51 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 44.32.77.11 May 28 18:53:51 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 44.32.77.16 May 28 18:53:51 volumio-rpi4-living ntpd[886]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 May 28 18:53:52 volumio-rpi4-living ntpd[886]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: ------------------------------------------- May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: ----- Volumio3 ---- May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: ------------------------------------------- May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: ----- System startup ---- May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: ------------------------------------------- May 28 18:53:52 volumio-rpi4-living ntpd[886]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 May 28 18:53:52 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 44.32.77.17 May 28 18:53:52 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 77.81.64.51 May 28 18:53:52 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 44.32.133.9 May 28 18:53:52 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 44.32.77.12 May 28 18:53:52 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 2606:4700:f1::1 May 28 18:53:52 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 2a0d:8140:6000::12 May 28 18:53:52 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 2a0d:8140:6000::13 May 28 18:53:52 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 2a0d:8140:6000::14 May 28 18:53:52 volumio-rpi4-living ntpd[886]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: MYVOLUMIO Environment detected May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin music_service/airplay_emulation. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin music_service/inputs. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin music_service/mpd. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin music_service/webradio. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin music_service/example_plugin. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin music_service/last_100. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin music_service/upnp_browser. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin audio_interface/outputs. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin audio_interface/upnp. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin audio_interface/alsa_controller. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin miscellanea/alarm-clock. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin miscellanea/albumart. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin miscellanea/appearance. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin miscellanea/wizard. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin miscellanea/my_music. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin user_interface/websocket. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin user_interface/mpdemulation. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin user_interface/rest_api. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin system_controller/updater_comm. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin system_controller/network. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin system_controller/networkfs. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin system_controller/services. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin system_controller/system. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin system_controller/i2s_dacs. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin system_controller/volumiodiscovery. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Found new core plugin system_controller/volumio_command_line_client. Adding it May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Plugin folders cleanup May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Scanning into folder /volumio/app/plugins/ May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Scanning category audio_interface May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Scanning category miscellanea May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Scanning category music_service May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Scanning category plugins.json May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Scanning category system_controller May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Scanning category user_interface May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Scanning into folder /data/plugins/ May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Scanning category user_interface May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Plugin folders cleanup completed May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: ------------------------------------------- May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: ----- Core plugins startup ---- May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: ------------------------------------------- May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Loading plugins from folder /volumio/app/plugins/ May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Adding plugin upnp to MyMusic Plugins May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Adding plugin airplay_emulation to MyMusic Plugins May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Adding plugin upnp_browser to MyMusic Plugins May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Loading plugins from folder /data/plugins/ May 28 18:53:52 volumio-rpi4-living volumio[1028]: info: Loading plugin "system"... May 28 18:53:53 volumio-rpi4-living volumio[1028]: info: Loading plugin "appearance"... May 28 18:53:53 volumio-rpi4-living ntpd[886]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 May 28 18:53:53 volumio-rpi4-living ntpd[886]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 May 28 18:53:53 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 162.159.200.1 May 28 18:53:53 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 44.32.77.14 May 28 18:53:53 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 45.134.48.236 May 28 18:53:53 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 162.159.200.123 May 28 18:53:53 volumio-rpi4-living ntpd[886]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 May 28 18:53:53 volumio-rpi4-living volumio-remote-updater[667]: [2025-05-28 18:53:53] [connect] Successful connection May 28 18:53:54 volumio-rpi4-living ntpd[886]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 28 18:53:54 volumio-rpi4-living ntpd[886]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 28 18:53:54 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 44.32.77.8 May 28 18:53:54 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 44.32.77.10 May 28 18:53:54 volumio-rpi4-living ntpd[886]: DNS: Pool skipping: 44.32.133.7 May 28 18:53:54 volumio-rpi4-living ntpd[886]: DNS: Pool taking: 44.32.133.8 May 28 18:53:54 volumio-rpi4-living ntpd[886]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: Loading plugin "network"... May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: Refreshing Cached IP Addresses May 28 18:53:54 volumio-rpi4-living sudo[1174]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 28 18:53:54 volumio-rpi4-living sudo[1174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: Loading plugin "services"... May 28 18:53:54 volumio-rpi4-living sudo[1176]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 18:53:54 volumio-rpi4-living sudo[1176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 18:53:54 volumio-rpi4-living sudo[1174]: pam_unix(sudo:session): session closed for user root May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: Loading plugin "alsa_controller"... May 28 18:53:54 volumio-rpi4-living sudo[1176]: pam_unix(sudo:session): session closed for user root May 28 18:53:54 volumio-rpi4-living sudo[1180]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 28 18:53:54 volumio-rpi4-living sudo[1180]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: Loading plugin "wizard"... May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: Loading plugin "networkfs"... May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: Starting Udev Watcher for removable devices May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: Ignoring mount for partition: boot May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: Ignoring mount for partition: volumio May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: Ignoring mount for partition: volumio_data May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: Loading plugin "volumio_command_line_client"... May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: Loading plugin "upnp"... May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: [1748447634421] Starting Upmpd Daemon May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: Loading plugin "my_music"... May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: Loading plugin "mpd"... May 28 18:53:54 volumio-rpi4-living volumio[1028]: info: Loading plugin "upnp_browser"... May 28 18:53:55 volumio-rpi4-living dhcpcd[722]: wlan0: probing for an IPv4LL address May 28 18:53:55 volumio-rpi4-living volumio[1028]: info: Loading plugin "alarm-clock"... May 28 18:53:55 volumio-rpi4-living volumio[1028]: info: Loading plugin "airplay_emulation"... May 28 18:53:55 volumio-rpi4-living volumio[1028]: info: Starting Shairport Sync May 28 18:53:55 volumio-rpi4-living volumio[1028]: info: Loading plugin "last_100"... May 28 18:53:55 volumio-rpi4-living volumio[1028]: info: Loading plugin "webradio"... May 28 18:53:55 volumio-rpi4-living volumio[1028]: info: Loading plugin "i2s_dacs"... May 28 18:53:55 volumio-rpi4-living volumio[1028]: info: I2S DAC not set, start Auto-detection May 28 18:53:55 volumio-rpi4-living volumio[1028]: info: Loading plugin "volumiodiscovery"... May 28 18:53:55 volumio-rpi4-living volumio[1028]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 28 18:53:55 volumio-rpi4-living node[1028]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 28 18:53:55 volumio-rpi4-living volumio[1028]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 18:53:55 volumio-rpi4-living node[1028]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 18:53:55 volumio-rpi4-living volumio[1028]: *** WARNING *** For more information see May 28 18:53:55 volumio-rpi4-living node[1028]: *** WARNING *** For more information see May 28 18:53:55 volumio-rpi4-living volumio[1028]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 28 18:53:55 volumio-rpi4-living node[1028]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 28 18:53:55 volumio-rpi4-living volumio[1028]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 18:53:55 volumio-rpi4-living node[1028]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 18:53:55 volumio-rpi4-living volumio[1028]: *** WARNING *** For more information see May 28 18:53:55 volumio-rpi4-living node[1028]: *** WARNING *** For more information see May 28 18:53:55 volumio-rpi4-living volumio[1028]: info: Applying required configuration parameters for plugin volumiodiscovery May 28 18:53:55 volumio-rpi4-living volumio[1028]: info: Discovery: Started advertising with name: Volumio-rpi4-living May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: Loading plugin "outputs"... May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: Loading plugin "albumart"... May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: Plugin example_plugin is not enabled May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: Loading plugin "inputs"... May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: Loading plugin "updater_comm"... May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: Plugin mpdemulation is not enabled May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: Loading plugin "rest_api"... May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: Loading plugin "websocket"... May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: Starting Socket.io Server version 1.7.4 May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: Plugin touch_display is not enabled May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: Loading i18n strings for locale en May 28 18:53:56 volumio-rpi4-living volumio[1028]: Updating browse sources language May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 18:53:56 volumio-rpi4-living volumio[1210]: Forking 3 albumart workers May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::initPlayerControls May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 18:53:56 volumio-rpi4-living volumio[1028]: Express server listening on port 3000 May 28 18:53:56 volumio-rpi4-living volumio[1028]: [Metrics] WebUI: 5s 108.91ms May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: CoreStateMachine::resetVolumioState May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: CoreStateMachine::getcurrentVolume May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioRetrievevolume May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: Cannot read play queue from file May 28 18:53:56 volumio-rpi4-living volumio[1028]: info: Volumio Network Manager: Network status updated: 1 May 28 18:53:57 volumio-rpi4-living volumio[1028]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.117 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 28 19:40:16 volumio-rpi4-living ntpd[886]: CLOCK: time stepped by 2779.212956 May 28 19:40:16 volumio-rpi4-living ntpd[886]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 28 19:40:16 volumio-rpi4-living volumio-remote-updater[667]: [2025-05-28 19:40:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1748447633 101 May 28 19:40:16 volumio-rpi4-living volumio[1028]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 May 28 19:40:16 volumio-rpi4-living volumio[1028]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.117 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: VolumeController:: Volume=100 Mute =false May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CoreStateMachine::pushState May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CorePlayQueue::getTrack 0 May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioPushState May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CoreStateMachine::updateTrackBlock May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CorePlayQueue::getTrackBlock May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioRetrievevolume May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CoreStateMachine::setRepeat null single undefined May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CoreStateMachine::pushState May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CorePlayQueue::getTrack 0 May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioPushState May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CoreStateMachine::setRandom null May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CoreStateMachine::pushState May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CorePlayQueue::getTrack 0 May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioPushState May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: Setting Device type: Raspberry PI May 28 19:40:16 volumio-rpi4-living volumio[1028]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.117 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03111 May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: Discovery: adding 49ba780b-f6f9-4019-b88f-5cc5d38ae33e May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: Discovery: Found device Volumio-rpi4-living May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetState May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CorePlayQueue::getTrack 0 May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: Discovery: this is already registered, 49ba780b-f6f9-4019-b88f-5cc5d38ae33e May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: Discovery: Found device Volumio-rpi4-living May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetState May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CorePlayQueue::getTrack 0 May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: Discovery: this is already registered, 49ba780b-f6f9-4019-b88f-5cc5d38ae33e May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: Discovery: Found device Volumio-rpi4-living May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetState May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CorePlayQueue::getTrack 0 May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: VolumeController:: Volume=100 Mute =false May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CoreStateMachine::pushState May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CorePlayQueue::getTrack 0 May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioPushState May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: Completed loading Core Plugins May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: Preparing to generate the ALSA configuration file May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: Asound.conf file unchanged, so no further update is needed May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: Output device has changed, restarting MPD May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: Output device has changed, restarting Shairport Sync May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:16 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:17 volumio-rpi4-living sudo[1269]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 28 19:40:17 volumio-rpi4-living sudo[1271]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 28 19:40:17 volumio-rpi4-living sudo[1269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:17 volumio-rpi4-living sudo[1271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:17 volumio-rpi4-living sudo[1269]: pam_unix(sudo:session): session closed for user root May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: ___________ START PLUGINS ___________ May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: ControllerMpd::onStart: Initializing MPD May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: Creating MPD Configuration file May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: [1748450417142] CoreMusicLibrary::Adding element Media Servers May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 19:40:17 volumio-rpi4-living systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 28 19:40:17 volumio-rpi4-living sudo[1180]: pam_unix(sudo:session): session closed for user root May 28 19:40:17 volumio-rpi4-living systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 28 19:40:17 volumio-rpi4-living systemd[1]: Starting mpd.service - Music Player Daemon... May 28 19:40:17 volumio-rpi4-living setdatetime-helper.sh[1278]: Time is not synchronized. Attempting to sync... May 28 19:40:17 volumio-rpi4-living sudo[1281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 28 19:40:17 volumio-rpi4-living sudo[1280]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 28 19:40:17 volumio-rpi4-living sudo[1280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:17 volumio-rpi4-living sudo[1281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:17 volumio-rpi4-living sudo[1281]: pam_unix(sudo:session): session closed for user root May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:17 volumio-rpi4-living sudo[1285]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 28 19:40:17 volumio-rpi4-living sudo[1285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 19:40:17 volumio-rpi4-living sudo[1295]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 28 19:40:17 volumio-rpi4-living sudo[1285]: pam_unix(sudo:session): session closed for user root May 28 19:40:17 volumio-rpi4-living sudo[1298]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Wed, 28 May 2025 16:40:17 GMT#015' May 28 19:40:17 volumio-rpi4-living sudo[1298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 19:40:17 volumio-rpi4-living setdatetime-helper.sh[1300]: Wed May 28 19:40:17 EEST 2025 May 28 19:40:17 volumio-rpi4-living setdatetime-helper.sh[1278]: Time synchronized successfully. May 28 19:40:17 volumio-rpi4-living sudo[1298]: pam_unix(sudo:session): session closed for user root May 28 19:40:17 volumio-rpi4-living systemd-journald[325]: Time jumped backwards, rotating. May 28 19:40:17 volumio-rpi4-living systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 28 19:40:17 volumio-rpi4-living systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 28 19:40:17 volumio-rpi4-living systemd[1]: mpd.service: Deactivated successfully. May 28 19:40:17 volumio-rpi4-living systemd[1]: Stopped mpd.service - Music Player Daemon. May 28 19:40:17 volumio-rpi4-living systemd[1]: mpd.socket: Deactivated successfully. May 28 19:40:17 volumio-rpi4-living systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 28 19:40:17 volumio-rpi4-living systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 28 19:40:17 volumio-rpi4-living systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 28 19:40:17 volumio-rpi4-living systemd[1]: Starting mpd.service - Music Player Daemon... May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: [1748450417129] CoreMusicLibrary::Adding element Last_100 May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: [1748450417154] CoreMusicLibrary::Adding element Webradio May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 19:40:17 volumio-rpi4-living sudo[1303]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 28 19:40:17 volumio-rpi4-living sudo[1303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 19:40:17 volumio-rpi4-living sudo[1308]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 28 19:40:17 volumio-rpi4-living sudo[1303]: pam_unix(sudo:session): session closed for user root May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: Initializing BBC Radios May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:17 volumio-rpi4-living volumio[1028]: info: Volumio Calling Home May 28 19:40:18 volumio-rpi4-living volumio[1221]: Starting albumart workers May 28 19:40:18 volumio-rpi4-living dhcpcd[722]: wlan0: using IPv4LL address 169.254.41.79 May 28 19:40:18 volumio-rpi4-living avahi-daemon[651]: Registering new address record for 169.254.41.79 on wlan0.IPv4. May 28 19:40:18 volumio-rpi4-living volumio[1220]: Starting albumart workers May 28 19:40:18 volumio-rpi4-living dhcpcd[722]: wlan0: adding route to 169.254.0.0/16 May 28 19:40:18 volumio-rpi4-living systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 28 19:40:18 volumio-rpi4-living systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 28 19:40:18 volumio-rpi4-living systemd[1]: welcome.service: Deactivated successfully. May 28 19:40:18 volumio-rpi4-living systemd[1]: Stopped welcome.service - Show a welcome message on console. May 28 19:40:18 volumio-rpi4-living systemd[1]: Stopping welcome.service - Show a welcome message on console... May 28 19:40:18 volumio-rpi4-living systemd[1]: Starting welcome.service - Show a welcome message on console... May 28 19:40:18 volumio-rpi4-living welcome[1336]: Resolved ip:[3] 192.168.1.191 192.168.211.1 169.254.41.79 May 28 19:40:18 volumio-rpi4-living systemd[1]: Finished welcome.service - Show a welcome message on console. May 28 19:40:18 volumio-rpi4-living systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 28 19:40:19 volumio-rpi4-living volumio[1222]: Starting albumart workers May 28 19:40:19 volumio-rpi4-living volumio[1028]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.117 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 28 19:40:19 volumio-rpi4-living ntpd[886]: IO: Listen normally on 5 wlan0 169.254.41.79:123 May 28 19:40:19 volumio-rpi4-living ntpd[886]: IO: new interface(s) found: waking up resolver May 28 19:40:20 volumio-rpi4-living volumio[1028]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.117 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: MPD Permissions set May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: MPD Permissions set May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: Volumio called home May 28 19:40:20 volumio-rpi4-living volumio[1028]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:20 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:21 volumio-rpi4-living volumio[1028]: info: Starting Shairport Sync May 28 19:40:21 volumio-rpi4-living volumio[1028]: info: Starting Shairport Sync May 28 19:40:21 volumio-rpi4-living volumio[1028]: info: Starting Shairport Sync May 28 19:40:21 volumio-rpi4-living sudo[1380]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 28 19:40:21 volumio-rpi4-living sudo[1380]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:21 volumio-rpi4-living sudo[1378]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 28 19:40:21 volumio-rpi4-living sudo[1376]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 28 19:40:21 volumio-rpi4-living systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 28 19:40:21 volumio-rpi4-living systemd[1]: shairport-sync.service: Deactivated successfully. May 28 19:40:21 volumio-rpi4-living systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 28 19:40:21 volumio-rpi4-living systemd[1]: shairport-sync.service: Consumed 1.612s CPU time. May 28 19:40:21 volumio-rpi4-living sudo[1378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:21 volumio-rpi4-living sudo[1376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:21 volumio-rpi4-living systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 28 19:40:21 volumio-rpi4-living sudo[1380]: pam_unix(sudo:session): session closed for user root May 28 19:40:21 volumio-rpi4-living systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 28 19:40:21 volumio-rpi4-living systemd[1]: shairport-sync.service: Deactivated successfully. May 28 19:40:21 volumio-rpi4-living systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 28 19:40:21 volumio-rpi4-living systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 28 19:40:21 volumio-rpi4-living sudo[1376]: pam_unix(sudo:session): session closed for user root May 28 19:40:21 volumio-rpi4-living sudo[1378]: pam_unix(sudo:session): session closed for user root May 28 19:40:21 volumio-rpi4-living volumio[1028]: info: Shairport-Sync Started May 28 19:40:21 volumio-rpi4-living volumio[1028]: Error adding Membership: Error: addMembership EINVAL May 28 19:40:21 volumio-rpi4-living volumio[1028]: info: Shairport-Sync Started May 28 19:40:21 volumio-rpi4-living volumio[1028]: info: Shairport-Sync Started May 28 19:40:21 volumio-rpi4-living mpd[1309]: 2025-05-28T19:40:21 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 28 19:40:21 volumio-rpi4-living systemd[1]: Started mpd.service - Music Player Daemon. May 28 19:40:21 volumio-rpi4-living sudo[1271]: pam_unix(sudo:session): session closed for user root May 28 19:40:21 volumio-rpi4-living sudo[1280]: pam_unix(sudo:session): session closed for user root May 28 19:40:21 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetState May 28 19:40:21 volumio-rpi4-living volumio[1028]: info: CorePlayQueue::getTrack 0 May 28 19:40:21 volumio-rpi4-living volumio[1028]: info: Completed starting Core Plugins May 28 19:40:21 volumio-rpi4-living volumio[1028]: info: ------------------------------------------- May 28 19:40:21 volumio-rpi4-living volumio[1028]: info: ----- MyVolumio plugins startup ---- May 28 19:40:21 volumio-rpi4-living volumio[1028]: info: ------------------------------------------- May 28 19:40:21 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Fetching plans data.... May 28 19:40:21 volumio-rpi4-living volumio[1028]: error: MPD error: The expression evaluated to a falsy value: May 28 19:40:21 volumio-rpi4-living volumio[1028]: assert.ok(self.idling) May 28 19:40:21 volumio-rpi4-living volumio[1028]: error: The expression evaluated to a falsy value: May 28 19:40:21 volumio-rpi4-living volumio[1028]: assert.ok(self.idling) May 28 19:40:22 volumio-rpi4-living volumio[1028]: error: updateQueue error: null May 28 19:40:22 volumio-rpi4-living volumio[1028]: info: MPD running with PID1309 May 28 19:40:22 volumio-rpi4-living volumio[1028]: ,establishing connection May 28 19:40:22 volumio-rpi4-living volumio[1028]: error: updateQueue error: null May 28 19:40:23 volumio-rpi4-living sudo[1426]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 28 19:40:23 volumio-rpi4-living sudo[1426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:23 volumio-rpi4-living sudo[1426]: pam_unix(sudo:session): session closed for user root May 28 19:40:23 volumio-rpi4-living sudo[1428]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 19:40:23 volumio-rpi4-living sudo[1428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:23 volumio-rpi4-living sudo[1428]: pam_unix(sudo:session): session closed for user root May 28 19:40:23 volumio-rpi4-living sudo[1430]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 28 19:40:23 volumio-rpi4-living sudo[1430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:23 volumio-rpi4-living systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 28 19:40:23 volumio-rpi4-living sudo[1430]: pam_unix(sudo:session): session closed for user root May 28 19:40:23 volumio-rpi4-living volumio[1028]: info: Upmpdcli Daemon Started May 28 19:40:23 volumio-rpi4-living nmbd[938]: [2025/05/28 19:40:23.699022, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) May 28 19:40:23 volumio-rpi4-living nmbd[938]: register_name_response: Answer name <00> differs from question name <00>. May 28 19:40:23 volumio-rpi4-living nmbd[938]: [2025/05/28 19:40:23.699357, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) May 28 19:40:23 volumio-rpi4-living nmbd[938]: register_name_response: Answer name <03> differs from question name <03>. May 28 19:40:23 volumio-rpi4-living nmbd[938]: [2025/05/28 19:40:23.699581, 0] ../../source3/nmbd/nmbd_nameregister.c:73(register_name_response) May 28 19:40:23 volumio-rpi4-living nmbd[938]: register_name_response: Answer name <20> differs from question name <20>. May 28 19:40:26 volumio-rpi4-living volumio[1028]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 28 19:40:28 volumio-rpi4-living systemd[1]: systemd-fsckd.service: Deactivated successfully. May 28 19:40:28 volumio-rpi4-living volumio[1028]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.117 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: Received Get System Info May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: Discovery: Getting this device information May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetState May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CorePlayQueue::getTrack 0 May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetState May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CorePlayQueue::getTrack 0 May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetVisibleSources May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetState May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CorePlayQueue::getTrack 0 May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: Received Get System Info May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: Discovery: Getting this device information May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetState May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CorePlayQueue::getTrack 0 May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetState May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CorePlayQueue::getTrack 0 May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: Listing playlists May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 28 19:40:29 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 28 19:40:30 volumio-rpi4-living systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: Adding plugin bluetooth to MyMusic Plugins May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: Adding plugin multiroom to MyMusic Plugins May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: Adding plugin metavolumio to MyMusic Plugins May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: Adding plugin cd_controller to MyMusic Plugins May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: Adding plugin smart_inputs to MyMusic Plugins May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: Adding plugin tidalconnect to MyMusic Plugins May 28 19:40:30 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 28 19:40:34 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 28 19:40:34 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 28 19:40:34 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:34 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:34 volumio-rpi4-living volumio[1028]: info: Starting MyVolumio Remote Streaming Endpoints May 28 19:40:34 volumio-rpi4-living volumio[1028]: info: MyVolumio login type: Token May 28 19:40:34 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 28 19:40:34 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 28 19:40:36 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 28 19:40:36 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 28 19:40:36 volumio-rpi4-living volumio[1028]: info: Streaming services startup May 28 19:40:36 volumio-rpi4-living volumio[1028]: info: Starting Streaming Daemon May 28 19:40:36 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 28 19:40:36 volumio-rpi4-living sudo[1470]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 28 19:40:36 volumio-rpi4-living sudo[1470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:36 volumio-rpi4-living sudo[1470]: pam_unix(sudo:session): session closed for user root May 28 19:40:36 volumio-rpi4-living volumio[1028]: error: Cannot start Volumio Streaming Daemon May 28 19:40:36 volumio-rpi4-living volumio[1028]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 28 19:40:36 volumio-rpi4-living volumio[1028]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 28 19:40:37 volumio-rpi4-living volumio[1028]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 28 19:40:38 volumio-rpi4-living volumio[1028]: info: MyVolumio token set successfully May 28 19:40:38 volumio-rpi4-living volumio[1028]: info: MYVOLUMIO: Adding device May 28 19:40:38 volumio-rpi4-living volumio[1028]: info: MYVOLUMIO: Evaluating Server May 28 19:40:38 volumio-rpi4-living volumio[1028]: info: MyVolumio Plan changed: premium May 28 19:40:38 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 28 19:40:38 volumio-rpi4-living volumio[1028]: info: Removing browser output: myVolumio user plan is not superstar May 28 19:40:38 volumio-rpi4-living volumio[1028]: info: Removing audio output: May 28 19:40:38 volumio-rpi4-living volumio[1028]: info: MYVOLUMIO: Adding device May 28 19:40:38 volumio-rpi4-living volumio[1028]: info: MYVOLUMIO: Evaluating Server May 28 19:40:38 volumio-rpi4-living volumio[1028]: info: Remote config written successfully May 28 19:40:38 volumio-rpi4-living volumio[1028]: info: Starting Tunnel 1 May 28 19:40:38 volumio-rpi4-living volumio[1028]: info: Starting Tunnel Connection Checker May 28 19:40:38 volumio-rpi4-living volumio[1028]: info: Completed starting MyVolumio Plugin May 28 19:40:39 volumio-rpi4-living volumio[1028]: info: MYVolumio Device enabled May 28 19:40:39 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 28 19:40:39 volumio-rpi4-living volumio[1028]: info: MyVolumio status changed May 28 19:40:39 volumio-rpi4-living volumio[1028]: info: Streaming services startup May 28 19:40:39 volumio-rpi4-living volumio[1028]: info: Starting Streaming Daemon May 28 19:40:39 volumio-rpi4-living sudo[1509]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 28 19:40:39 volumio-rpi4-living sudo[1509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:39 volumio-rpi4-living sudo[1509]: pam_unix(sudo:session): session closed for user root May 28 19:40:39 volumio-rpi4-living volumio[1028]: info: Setting Geolocation for MyVolumio to eu7 May 28 19:40:39 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:39 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:39 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:39 volumio-rpi4-living volumio[1028]: error: Cannot start Volumio Streaming Daemon May 28 19:40:39 volumio-rpi4-living volumio[1028]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 28 19:40:39 volumio-rpi4-living volumio[1028]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 28 19:40:39 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 28 19:40:39 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! May 28 19:40:39 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 28 19:40:40 volumio-rpi4-living volumio[1028]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart May 28 19:40:40 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 28 19:40:42 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom May 28 19:40:42 volumio-rpi4-living sudo[1515]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 28 19:40:42 volumio-rpi4-living sudo[1515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:42 volumio-rpi4-living sudo[1515]: pam_unix(sudo:session): session closed for user root May 28 19:40:42 volumio-rpi4-living volumio[1028]: info: MRS: MultiRoom plugin initialized May 28 19:40:42 volumio-rpi4-living volumio[1028]: info: MRS: STOPPING SNAPCLIENT May 28 19:40:42 volumio-rpi4-living volumio[1028]: info: MRS: Snap server stop May 28 19:40:42 volumio-rpi4-living volumio[1028]: info: MRS: STOPPING volumioStreaming May 28 19:40:42 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 28 19:40:42 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 28 19:40:42 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 28 19:40:42 volumio-rpi4-living sudo[1537]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 28 19:40:42 volumio-rpi4-living sudo[1539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 28 19:40:42 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 28 19:40:42 volumio-rpi4-living sudo[1535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 28 19:40:42 volumio-rpi4-living sudo[1535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 28 19:40:43 volumio-rpi4-living sudo[1537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:43 volumio-rpi4-living sudo[1534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 28 19:40:43 volumio-rpi4-living sudo[1534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:43 volumio-rpi4-living sudo[1539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:43 volumio-rpi4-living sudo[1539]: pam_unix(sudo:session): session closed for user root May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 28 19:40:43 volumio-rpi4-living sudo[1537]: pam_unix(sudo:session): session closed for user root May 28 19:40:43 volumio-rpi4-living sudo[1534]: pam_unix(sudo:session): session closed for user root May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: Preparing to generate the ALSA configuration file May 28 19:40:43 volumio-rpi4-living sudo[1535]: pam_unix(sudo:session): session closed for user root May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: Updating MyVolumio device info May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: Setting Geolocation for MyVolumio to eu7 May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: Successfully Added MyVolumio device May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: MRS: Removed streaming files May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: MRS: volumioStreaming STOPPED May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: MRS: SNAPSERVER STOPPED May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: MRS: SNAPCLIENT STOPPED May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: Asound.conf file unchanged, so no further update is needed May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: Output device has changed, restarting MPD May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: Output device has changed, restarting Shairport Sync May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:43 volumio-rpi4-living sudo[1561]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 28 19:40:43 volumio-rpi4-living sudo[1561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:43 volumio-rpi4-living sudo[1563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 28 19:40:43 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 28 19:40:43 volumio-rpi4-living sudo[1561]: pam_unix(sudo:session): session closed for user root May 28 19:40:43 volumio-rpi4-living sudo[1563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:44 volumio-rpi4-living volumio[1028]: ------------------------------------ BT MESSAGE: [FUNC] onStart May 28 19:40:44 volumio-rpi4-living volumio[1028]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service May 28 19:40:44 volumio-rpi4-living volumio[1028]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp May 28 19:40:44 volumio-rpi4-living volumio[1028]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ May 28 19:40:44 volumio-rpi4-living volumio[1028]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: MRS: Pushing multiroomSync output for this device May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: MRS: Pushing multiroomSync output May 28 19:40:44 volumio-rpi4-living systemd[1]: Stopping mpd.service - Music Player Daemon... May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Adding audio output: May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Adding audio output: May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Adding METAVOLUMIO REST API Endpoints May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 28 19:40:44 volumio-rpi4-living bluetoothd[652]: Path / reserved for Adv Monitor app :1.22 May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Preparing CD Folders May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Adding CD REST API Endpoints May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Starting UDEV Watcher for CD May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Detecting CD presence with UDEV May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 28 19:40:44 volumio-rpi4-living bluetoothd[652]: Adv Monitor app :1.22 disconnected from D-Bus May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Adding inputs REST Endpoints May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Scanning Audio Inputs May 28 19:40:44 volumio-rpi4-living systemd[1]: mpd.service: Deactivated successfully. May 28 19:40:44 volumio-rpi4-living systemd[1]: Stopped mpd.service - Music Player Daemon. May 28 19:40:44 volumio-rpi4-living systemd[1]: mpd.service: Consumed 3.197s CPU time. May 28 19:40:44 volumio-rpi4-living systemd[1]: mpd.socket: Deactivated successfully. May 28 19:40:44 volumio-rpi4-living systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 28 19:40:44 volumio-rpi4-living systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Checking against Known Cards name May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Adding Server instance for streaming May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 28 19:40:44 volumio-rpi4-living volumio[1028]: error: Hi Res Audio Failed Login: Missing Login Data May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Adding HIGHRESAUDIO REST API Endpoints May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Stopping AccessToken refresher cron May 28 19:40:44 volumio-rpi4-living systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 28 19:40:44 volumio-rpi4-living systemd[1]: Starting mpd.service - Music Player Daemon... May 28 19:40:44 volumio-rpi4-living sudo[1582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 28 19:40:44 volumio-rpi4-living sudo[1582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: AccessToken refresher cron started May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Stopping AccessToken refresher cron for QOBUZ May 28 19:40:44 volumio-rpi4-living sudo[1583]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 28 19:40:44 volumio-rpi4-living sudo[1583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 19:40:44 volumio-rpi4-living sudo[1583]: pam_unix(sudo:session): session closed for user root May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: AccessToken refresher cron started for QOBUZ May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Adding TIDAL REST API Endpoints May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Adding QOBUZ REST API Endpoints May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: Updating MyVolumio device info May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:44 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:44 volumio-rpi4-living sudo[1582]: pam_unix(sudo:session): session closed for user root May 28 19:40:45 volumio-rpi4-living volumio[1028]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on May 28 19:40:45 volumio-rpi4-living volumio[1028]: info: MPD Permissions set May 28 19:40:45 volumio-rpi4-living upmpdcli[1590]: writing RSA key May 28 19:40:45 volumio-rpi4-living sudo[1589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 28 19:40:45 volumio-rpi4-living sudo[1589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:45 volumio-rpi4-living systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. May 28 19:40:45 volumio-rpi4-living sudo[1589]: pam_unix(sudo:session): session closed for user root May 28 19:40:45 volumio-rpi4-living volumiobt[1600]: INFO [BTSTART] Ensuring Bluetooth directory exists... May 28 19:40:45 volumio-rpi4-living sudo[1601]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth May 28 19:40:45 volumio-rpi4-living sudo[1601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:45 volumio-rpi4-living sudo[1601]: pam_unix(sudo:session): session closed for user root May 28 19:40:45 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:45 volumio-rpi4-living sudo[1603]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth May 28 19:40:45 volumio-rpi4-living sudo[1603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:45 volumio-rpi4-living sudo[1603]: pam_unix(sudo:session): session closed for user root May 28 19:40:45 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:45 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:45 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:45 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:45 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:45 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:45 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:45 volumio-rpi4-living volumiobt[1607]: INFO [BTSTART] Powering on Bluetooth if needed... May 28 19:40:45 volumio-rpi4-living bluetoothd[652]: Adv Monitor app :1.23 disconnected from D-Bus May 28 19:40:45 volumio-rpi4-living volumiobt[1612]: INFO [BTSTART] Making Bluetooth discoverable and pairable... May 28 19:40:45 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:45 volumio-rpi4-living volumiobt[1613]: [75B blob data] May 28 19:40:45 volumio-rpi4-living volumiobt[1613]: [bluetooth]# pairable on May 28 19:40:45 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:45 volumio-rpi4-living bluetoothd[652]: Adv Monitor app :1.24 disconnected from D-Bus May 28 19:40:45 volumio-rpi4-living volumiobt[1613]: [bluetooth]# May 28 19:40:45 volumio-rpi4-living volumiobt[1617]: INFO [BTSTART] Registering Bluetooth agent... May 28 19:40:45 volumio-rpi4-living volumio[1028]: info: Successfully Updated MyVolumio device May 28 19:40:45 volumio-rpi4-living bluetoothd[652]: Adv Monitor app :1.25 disconnected from D-Bus May 28 19:40:45 volumio-rpi4-living volumio[1028]: info: Successfully Added MyVolumio device May 28 19:40:45 volumio-rpi4-living volumiobt[1619]: No agent is registered May 28 19:40:45 volumio-rpi4-living bluetoothd[652]: Adv Monitor app :1.26 disconnected from D-Bus May 28 19:40:45 volumio-rpi4-living volumiobt[1620]: INFO [BTSTART] Agent registered successfully. May 28 19:40:45 volumio-rpi4-living volumiobt[1621]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... May 28 19:40:46 volumio-rpi4-living volumio[1028]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully May 28 19:40:46 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioStop May 28 19:40:46 volumio-rpi4-living volumio[1028]: info: CoreStateMachine::stop May 28 19:40:46 volumio-rpi4-living volumio[1028]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 19:40:46 volumio-rpi4-living volumio[1028]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart May 28 19:40:46 volumio-rpi4-living volumio[1028]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 19:40:46 volumio-rpi4-living sudo[1624]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 28 19:40:46 volumio-rpi4-living sudo[1624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:46 volumio-rpi4-living mpd[1586]: 2025-05-28T19:40:46 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 28 19:40:46 volumio-rpi4-living systemd[1]: Started mpd.service - Music Player Daemon. May 28 19:40:46 volumio-rpi4-living sudo[1563]: pam_unix(sudo:session): session closed for user root May 28 19:40:46 volumio-rpi4-living systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 28 19:40:46 volumio-rpi4-living systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 28 19:40:46 volumio-rpi4-living volumio[1028]: info: Successfully Updated MyVolumio device May 28 19:40:46 volumio-rpi4-living systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. May 28 19:40:46 volumio-rpi4-living sudo[1624]: pam_unix(sudo:session): session closed for user root May 28 19:40:46 volumio-rpi4-living autossh[1629]: port set to 0, monitoring disabled May 28 19:40:46 volumio-rpi4-living autossh[1629]: starting ssh (count 1) May 28 19:40:46 volumio-rpi4-living autossh[1629]: ssh child pid is 1632 May 28 19:40:46 volumio-rpi4-living volumiobt[1622]: INFO:a2dp-agent:Connecting to system bus. May 28 19:40:46 volumio-rpi4-living volumiobt[1622]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 May 28 19:40:46 volumio-rpi4-living volumiobt[1622]: INFO:a2dp-agent:Enabling infinite discovery on adapter. May 28 19:40:46 volumio-rpi4-living volumiobt[1622]: INFO:a2dp-agent:Registering agent. May 28 19:40:46 volumio-rpi4-living volumiobt[1622]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... May 28 19:40:46 volumio-rpi4-living volumiobt[1622]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. May 28 19:40:46 volumio-rpi4-living volumio[1028]: info: Starting Shairport Sync May 28 19:40:46 volumio-rpi4-living volumiossh-tunnel[1632]: Warning: Permanently added '[eu7.myvolumio.org]:2222' (RSA) to the list of known hosts. May 28 19:40:46 volumio-rpi4-living volumio[1028]: info: Remote SSH Started May 28 19:40:46 volumio-rpi4-living sudo[1634]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 28 19:40:46 volumio-rpi4-living systemd[1]: systemd-timedated.service: Deactivated successfully. May 28 19:40:46 volumio-rpi4-living sudo[1634]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:46 volumio-rpi4-living systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 28 19:40:46 volumio-rpi4-living systemd[1]: shairport-sync.service: Deactivated successfully. May 28 19:40:46 volumio-rpi4-living systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 28 19:40:46 volumio-rpi4-living systemd[1]: shairport-sync.service: Consumed 1.655s CPU time. May 28 19:40:47 volumio-rpi4-living systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 28 19:40:47 volumio-rpi4-living sudo[1634]: pam_unix(sudo:session): session closed for user root May 28 19:40:47 volumio-rpi4-living volumio[1028]: info: Shairport-Sync Started May 28 19:40:47 volumio-rpi4-living volumio[1028]: error: updateQueue error: null May 28 19:40:47 volumio-rpi4-living volumio[1028]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 28 19:40:47 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 19:40:47 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:47 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 19:40:47 volumio-rpi4-living volumio-remote-updater[667]: No test mode May 28 19:40:47 volumio-rpi4-living volumio-remote-updater[667]: Alpha test mode enabled May 28 19:40:47 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 28 19:40:47 volumio-rpi4-living volumio[1028]: info: TidalConnect service stoped! May 28 19:40:47 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetState May 28 19:40:48 volumio-rpi4-living volumio[1028]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 28 19:40:48 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 28 19:40:48 volumio-rpi4-living volumio[1028]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.MOJnSA90pkPyvDP7mYtoMTOHBL23.53d8493208beabb2d066cb23cfd85dc8.state.status' May 28 19:40:48 volumio-rpi4-living volumio[1028]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 28 19:40:48 volumio-rpi4-living volumio[1028]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 28 19:40:48 volumio-rpi4-living sudo[1666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 28 19:40:48 volumio-rpi4-living sudo[1666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:40:48 volumio-rpi4-living systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 28 19:40:48 volumio-rpi4-living sudo[1666]: pam_unix(sudo:session): session closed for user root May 28 19:40:48 volumio-rpi4-living vtcs[1669]: /usr/bin/vtcs: error while loading shared libraries: libssl.so.1.1: cannot open shared object file: No such file or directory May 28 19:40:48 volumio-rpi4-living systemd[1]: vtcs.service: Main process exited, code=exited, status=127/n/a May 28 19:40:48 volumio-rpi4-living systemd[1]: vtcs.service: Failed with result 'exit-code'. May 28 19:40:48 volumio-rpi4-living systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 1. May 28 19:40:48 volumio-rpi4-living systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 28 19:40:48 volumio-rpi4-living systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 28 19:40:48 volumio-rpi4-living vtcs[1672]: /usr/bin/vtcs: error while loading shared libraries: libssl.so.1.1: cannot open shared object file: No such file or directory May 28 19:40:48 volumio-rpi4-living systemd[1]: vtcs.service: Main process exited, code=exited, status=127/n/a May 28 19:40:48 volumio-rpi4-living systemd[1]: vtcs.service: Failed with result 'exit-code'. May 28 19:40:49 volumio-rpi4-living systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 2. May 28 19:40:49 volumio-rpi4-living systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 28 19:40:49 volumio-rpi4-living systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 28 19:40:49 volumio-rpi4-living vtcs[1674]: /usr/bin/vtcs: error while loading shared libraries: libssl.so.1.1: cannot open shared object file: No such file or directory May 28 19:40:49 volumio-rpi4-living systemd[1]: vtcs.service: Main process exited, code=exited, status=127/n/a May 28 19:40:49 volumio-rpi4-living systemd[1]: vtcs.service: Failed with result 'exit-code'. May 28 19:40:49 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 28 19:40:49 volumio-rpi4-living volumio[1028]: info: MRS: Getting audio outputs on start May 28 19:40:49 volumio-rpi4-living volumio[1028]: info: MRS: Requesting all other devices output May 28 19:40:49 volumio-rpi4-living volumio[1028]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 28 19:40:49 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetState May 28 19:40:49 volumio-rpi4-living systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 3. May 28 19:40:49 volumio-rpi4-living systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 28 19:40:49 volumio-rpi4-living systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 28 19:40:49 volumio-rpi4-living vtcs[1676]: /usr/bin/vtcs: error while loading shared libraries: libssl.so.1.1: cannot open shared object file: No such file or directory May 28 19:40:49 volumio-rpi4-living systemd[1]: vtcs.service: Main process exited, code=exited, status=127/n/a May 28 19:40:49 volumio-rpi4-living systemd[1]: vtcs.service: Failed with result 'exit-code'. May 28 19:40:49 volumio-rpi4-living systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 4. May 28 19:40:49 volumio-rpi4-living systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 28 19:40:49 volumio-rpi4-living systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 28 19:40:49 volumio-rpi4-living vtcs[1678]: /usr/bin/vtcs: error while loading shared libraries: libssl.so.1.1: cannot open shared object file: No such file or directory May 28 19:40:49 volumio-rpi4-living systemd[1]: vtcs.service: Main process exited, code=exited, status=127/n/a May 28 19:40:49 volumio-rpi4-living systemd[1]: vtcs.service: Failed with result 'exit-code'. May 28 19:40:50 volumio-rpi4-living systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 5. May 28 19:40:50 volumio-rpi4-living systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 28 19:40:50 volumio-rpi4-living systemd[1]: vtcs.service: Start request repeated too quickly. May 28 19:40:50 volumio-rpi4-living systemd[1]: vtcs.service: Failed with result 'exit-code'. May 28 19:40:50 volumio-rpi4-living systemd[1]: Failed to start vtcs.service - Volumio Tidal Connect Service. May 28 19:40:51 volumio-rpi4-living volumio[1028]: info: TidalConnect service started! May 28 19:40:51 volumio-rpi4-living volumio[1028]: [Metrics] CommandRouter: 40s 431.57ms May 28 19:40:51 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumiosetStartupVolume May 28 19:40:51 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:51 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 19:40:51 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::Close All Modals sent May 28 19:40:51 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::Close All Modals sent May 28 19:40:52 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 28 19:40:52 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 28 19:40:52 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 28 19:40:54 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:54 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 19:40:54 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 28 19:40:54 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 28 19:40:54 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 28 19:40:54 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetBrowseSources May 28 19:40:54 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 19:40:57 volumio-rpi4-living kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1 May 28 19:40:57 volumio-rpi4-living kernel: xhci_hcd 0000:01:00.0: @0000000428d160a0 00000000 00000000 0e000000 02028000 May 28 19:40:58 volumio-rpi4-living volumio[1028]: info: BOOT COMPLETED May 28 19:41:28 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 28 19:41:28 volumio-rpi4-living systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 28 19:41:28 volumio-rpi4-living dbus-daemon[653]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.31' (uid=0 pid=1741 comm="timedatectl show --property=NTPSynchronized --valu") May 28 19:41:28 volumio-rpi4-living systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 28 19:41:29 volumio-rpi4-living dbus-daemon[653]: [system] Successfully activated service 'org.freedesktop.timedate1' May 28 19:41:29 volumio-rpi4-living systemd[1]: Started systemd-timedated.service - Time & Date Service. May 28 19:41:29 volumio-rpi4-living setdatetime-helper.sh[1740]: Time is not synchronized. Attempting to sync... May 28 19:41:29 volumio-rpi4-living sudo[1749]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Wed, 28 May 2025 16:41:29 GMT#015' May 28 19:41:29 volumio-rpi4-living sudo[1749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 19:41:29 volumio-rpi4-living setdatetime-helper.sh[1750]: Wed May 28 19:41:29 EEST 2025 May 28 19:41:29 volumio-rpi4-living systemd-journald[325]: Time jumped backwards, rotating. May 28 19:41:29 volumio-rpi4-living sudo[1749]: pam_unix(sudo:session): session closed for user root May 28 19:41:29 volumio-rpi4-living systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 28 19:41:29 volumio-rpi4-living setdatetime-helper.sh[1740]: Time synchronized successfully. May 28 19:41:29 volumio-rpi4-living systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 28 19:41:58 volumio-rpi4-living systemd[1]: systemd-timedated.service: Deactivated successfully. May 28 19:42:11 volumio-rpi4-living volumio[1028]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.180 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: Received Get System Info May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: Discovery: Getting this device information May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetState May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetState May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetVisibleSources May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetState May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: Received Get System Info May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: Discovery: Getting this device information May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetState May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetState May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: Listing playlists May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 28 19:42:11 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 28 19:42:15 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 28 19:42:15 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 28 19:42:21 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 28 19:42:22 volumio-rpi4-living volumio[1028]: info: Enabling plugin touch_display May 28 19:42:22 volumio-rpi4-living volumio[1028]: info: Loading plugin "touch_display"... May 28 19:42:23 volumio-rpi4-living volumio[1028]: info: PLUGIN START: touch_display May 28 19:42:23 volumio-rpi4-living volumio[1028]: info: Loading i18n strings for locale en May 28 19:42:23 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 19:42:23 volumio-rpi4-living sudo[1827]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service May 28 19:42:23 volumio-rpi4-living sudo[1827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:42:23 volumio-rpi4-living volumio[1028]: info: touch_display: Backlight interface detected. May 28 19:42:23 volumio-rpi4-living sudo[1830]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 28 19:42:23 volumio-rpi4-living systemd[1]: Stopping getty@tty1.service - Getty on tty1... May 28 19:42:23 volumio-rpi4-living systemd[1]: getty@tty1.service: Deactivated successfully. May 28 19:42:23 volumio-rpi4-living systemd[1]: Stopped getty@tty1.service - Getty on tty1. May 28 19:42:23 volumio-rpi4-living sudo[1832]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service May 28 19:42:23 volumio-rpi4-living volumio[1028]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 28 19:42:23 volumio-rpi4-living sudo[1827]: pam_unix(sudo:session): session closed for user root May 28 19:42:23 volumio-rpi4-living sudo[1832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:42:23 volumio-rpi4-living sudo[1830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:42:23 volumio-rpi4-living systemd[1]: Reloading. May 28 19:42:23 volumio-rpi4-living volumio[1028]: info: touch_display: systemctl stop getty@tty1.service succeeded. May 28 19:42:23 volumio-rpi4-living sudo[1837]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness May 28 19:42:23 volumio-rpi4-living sudo[1837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:42:23 volumio-rpi4-living volumio[1028]: info: touch_display: Raspberry Pi Foundation touch screen detected. May 28 19:42:23 volumio-rpi4-living sudo[1837]: pam_unix(sudo:session): session closed for user root May 28 19:42:23 volumio-rpi4-living sudo[1855]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf May 28 19:42:23 volumio-rpi4-living sudo[1855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:42:23 volumio-rpi4-living sudo[1855]: pam_unix(sudo:session): session closed for user root May 28 19:42:24 volumio-rpi4-living volumio[1028]: error: touch_display: Error setting file permissions for /etc/X11/xorg.conf.d/99-vc4.conf: Error: Command failed: /usr/bin/sudo /bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf May 28 19:42:24 volumio-rpi4-living volumio[1028]: /bin/chmod: cannot access '/etc/X11/xorg.conf.d/99-vc4.conf': No such file or directory May 28 19:42:24 volumio-rpi4-living volumio[1028]: info: touch_display: File permissions for backlight brightness control set. May 28 19:42:24 volumio-rpi4-living systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 28 19:42:24 volumio-rpi4-living systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 28 19:42:24 volumio-rpi4-living systemd[1]: Reloading. May 28 19:42:24 volumio-rpi4-living sudo[1830]: pam_unix(sudo:session): session closed for user root May 28 19:42:24 volumio-rpi4-living volumio[1028]: info: touch_display: systemctl daemon-reload succeeded. May 28 19:42:24 volumio-rpi4-living sudo[1874]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 28 19:42:24 volumio-rpi4-living sudo[1874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:42:25 volumio-rpi4-living systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 28 19:42:25 volumio-rpi4-living systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 28 19:42:25 volumio-rpi4-living sudo[1832]: pam_unix(sudo:session): session closed for user root May 28 19:42:25 volumio-rpi4-living sudo[1874]: pam_unix(sudo:session): session closed for user root May 28 19:42:25 volumio-rpi4-living volumio[1028]: info: touch_display: systemctl disable getty@tty1.service succeeded. May 28 19:42:25 volumio-rpi4-living volumio[1028]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service May 28 19:42:25 volumio-rpi4-living volumio[1028]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. May 28 19:42:25 volumio-rpi4-living volumio[1028]: info: Error: Error May 28 19:42:28 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 28 19:42:40 volumio-rpi4-living systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 28 19:42:40 volumio-rpi4-living dbus-daemon[653]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.33' (uid=0 pid=1908 comm="timedatectl show --property=NTPSynchronized --valu") May 28 19:42:40 volumio-rpi4-living systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 28 19:42:40 volumio-rpi4-living dbus-daemon[653]: [system] Successfully activated service 'org.freedesktop.timedate1' May 28 19:42:40 volumio-rpi4-living systemd[1]: Started systemd-timedated.service - Time & Date Service. May 28 19:42:40 volumio-rpi4-living setdatetime-helper.sh[1907]: Time is not synchronized. Attempting to sync... May 28 19:42:40 volumio-rpi4-living sudo[1916]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Wed, 28 May 2025 16:42:40 GMT#015' May 28 19:42:40 volumio-rpi4-living sudo[1916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 28 19:42:40 volumio-rpi4-living setdatetime-helper.sh[1917]: Wed May 28 19:42:40 EEST 2025 May 28 19:42:40 volumio-rpi4-living setdatetime-helper.sh[1907]: Time synchronized successfully. May 28 19:42:40 volumio-rpi4-living sudo[1916]: pam_unix(sudo:session): session closed for user root May 28 19:42:40 volumio-rpi4-living systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 28 19:42:40 volumio-rpi4-living systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 28 19:42:40 volumio-rpi4-living systemd-journald[325]: Time jumped backwards, rotating. May 28 19:42:52 volumio-rpi4-living volumio[1028]: verbose: New Socket.io Connection to 192.168.1.191 from 192.168.1.180 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3.1 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 8 May 28 19:42:52 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetState May 28 19:42:52 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::volumioGetQueue May 28 19:42:52 volumio-rpi4-living volumio[1028]: info: CoreStateMachine::getQueue May 28 19:42:52 volumio-rpi4-living volumio[1028]: info: CorePlayQueue::getQueue May 28 19:42:52 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid May 28 19:42:52 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel May 28 19:42:52 volumio-rpi4-living volumio[1028]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] May 28 19:42:52 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction May 28 19:42:52 volumio-rpi4-living volumio[1028]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs May 28 19:42:52 volumio-rpi4-living volumio[1028]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] May 28 19:42:52 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection May 28 19:42:55 volumio-rpi4-living volumio[1028]: info: CALLMETHOD: system_controller system sendBugReport [object Object] May 28 19:42:55 volumio-rpi4-living volumio[1028]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport May 28 19:42:56 volumio-rpi4-living sudo[1974]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig May 28 19:42:56 volumio-rpi4-living sudo[1974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 19:42:56 volumio-rpi4-living sudo[1974]: pam_unix(sudo:session): session closed for user root May 28 19:43:07 volumio-rpi4-living sudo[2083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 May 28 19:43:07 volumio-rpi4-living sudo[2083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)