Tue Jan 30 18:32:29 CET 2024 Description="'NAS showing NaN MB after update to 3.601'" # cat /proc/version --------------- Linux version 6.1.70-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1712 SMP Tue Jan 2 18:29:06 GMT 2024 # cat /etc/os-release --------------- PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e5e6090399db628f1586eb4951e3592507f5fd38" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="50b5825b3acc4c58b5033699bcdbbd219d6a0836" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 19 Jan 2024 06:17:59 PM CET" VOLUMIO_VERSION="3.601" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="36b9da4b4411039910a860c6fa7331da" # ifconfig --------------- eth0: flags=4099 mtu 1500 ether dc:a6:32:6c:7a:fc 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 1220 bytes 492863 (481.3 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 1220 bytes 492863 (481.3 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 wlan0: flags=4163 mtu 1500 inet 192.168.178.227 netmask 255.255.255.0 broadcast 192.168.178.255 ether dc:a6:32:6c:7a:fd txqueuelen 1000 (Ethernet) RX packets 3586 bytes 1439189 (1.3 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 2504 bytes 486343 (474.9 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 # sudo iwconfig --------------- lo no wireless extensions. eth0 no wireless extensions. wlan0 IEEE 802.11 ESSID:"Ziggy" Mode:Managed Frequency:5.24 GHz Access Point: AC:22:05:46:79:F1 Bit Rate=325 Mb/s Tx-Power=31 dBm Retry short limit:7 RTS thr:off Fragment thr:off Encryption key:off Power Management:off Link Quality=50/70 Signal level=-60 dBm Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0 Tx excessive retries:0 Invalid misc:0 Missed beacon:0 # 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: D10s [D10s], 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 152a:8750 Thesycon Systemsoftware & Consulting GmbH 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 2: Dev 3, If 0, Class=Audio, Driver=snd-usb-audio, 480M |__ Port 2: Dev 3, If 1, Class=Audio, Driver=snd-usb-audio, 480M |__ Port 2: 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.01 iManufacturer 3 iProduct 2 iSerial 1 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 152a:8750 Thesycon Systemsoftware & Consulting GmbH Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 2.00 bDeviceClass 239 Miscellaneous Device bDeviceSubClass 2 bDeviceProtocol 1 Interface Association bMaxPacketSize0 64 idVendor 0x152a Thesycon Systemsoftware & Consulting GmbH idProduct 0x8750 bcdDevice 1.04 iManufacturer 1 iProduct 3 iSerial 0 bNumConfigurations 2 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x011c bNumInterfaces 3 bConfigurationValue 1 iConfiguration 0 bmAttributes 0x80 (Bus Powered) MaxPower 0mA 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 0 bInterfaceClass 1 Audio bInterfaceSubClass 1 Control Device bInterfaceProtocol 32 iInterface 3 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 11 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) 0x00000000 bmaControls(2) 0x00000000 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 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 11 AudioStreaming Interface Descriptor: bLength 6 bDescriptorType 36 bDescriptorSubtype 2 (FORMAT_TYPE) bFormatType 1 (FORMAT_TYPE_I) bSubslotSize 4 bBitResolution 32 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x01 EP 1 OUT bmAttributes 5 Transfer Type Isochronous Synch Type Asynchronous Usage Type Data wMaxPacketSize 0x0188 1x 392 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 1 bAlternateSetting 2 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 11 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 0x0188 1x 392 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 1 bAlternateSetting 3 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 0x80000000 bNrChannels 2 bmChannelConfig 0x00000000 iChannelNames 11 AudioStreaming Interface Descriptor: bLength 6 bDescriptorType 36 bDescriptorSubtype 2 (FORMAT_TYPE) bFormatType 1 (FORMAT_TYPE_I) bSubslotSize 4 bBitResolution 32 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x01 EP 1 OUT bmAttributes 5 Transfer Type Isochronous Synch Type Asynchronous Usage Type Data wMaxPacketSize 0x0188 1x 392 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 10 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 0x011c bNumInterfaces 3 bConfigurationValue 1 iConfiguration 0 bmAttributes 0x80 (Bus Powered) MaxPower 0mA 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 0 bInterfaceClass 1 Audio bInterfaceSubClass 1 Control Device bInterfaceProtocol 32 iInterface 3 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 11 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) 0x00000000 bmaControls(2) 0x00000000 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 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 11 AudioStreaming Interface Descriptor: bLength 6 bDescriptorType 36 bDescriptorSubtype 2 (FORMAT_TYPE) bFormatType 1 (FORMAT_TYPE_I) bSubslotSize 4 bBitResolution 32 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x01 EP 1 OUT bmAttributes 5 Transfer Type Isochronous Synch Type Asynchronous Usage Type Data wMaxPacketSize 0x0188 1x 392 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 1 bAlternateSetting 2 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 11 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 0x0188 1x 392 bytes bInterval 1 AudioStreaming Endpoint Descriptor: bLength 8 bDescriptorType 37 bDescriptorSubtype Couldn't open device, some information will be missing 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 1 bAlternateSetting 3 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 0x80000000 bNrChannels 2 bmChannelConfig 0x00000000 iChannelNames 11 AudioStreaming Interface Descriptor: bLength 6 bDescriptorType 36 bDescriptorSubtype 2 (FORMAT_TYPE) bFormatType 1 (FORMAT_TYPE_I) bSubslotSize 4 bBitResolution 32 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x01 EP 1 OUT bmAttributes 5 Transfer Type Isochronous Synch Type Asynchronous Usage Type Data wMaxPacketSize 0x0188 1x 392 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 10 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 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 iSerial 0 bNumConfigurations 1 Configuration Descriptor: bLength 9 bDescriptorType Couldn't open device, some information will be missing 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.01 iManufacturer 3 iProduct 2 iSerial 1 bNumConfigurations 1 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x0019 bNumInterfaces 1 bConfigurationValue 1 iConfiguration 0 bmAttributes 0xe0 Self Powered Remote Wakeup MaxPower 0mA Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 1 bInterfaceClass 9 Hub bInterfaceSubClass 0 bInterfaceProtocol 0 Full speed (or root) hub iInterface 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 3 Transfer Type Interrupt Synch Type None Usage Type Data wMaxPacketSize 0x0004 1x 4 bytes bInterval 12 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 1 18:26 ? 00:00:05 /sbin/init root 2 0 0 18:26 ? 00:00:00 [kthreadd] root 3 2 0 18:26 ? 00:00:00 [rcu_gp] root 4 2 0 18:26 ? 00:00:00 [rcu_par_gp] root 5 2 0 18:26 ? 00:00:00 [slub_flushwq] root 6 2 0 18:26 ? 00:00:00 [netns] root 7 2 0 18:26 ? 00:00:00 [kworker/0:0-events] root 9 2 0 18:26 ? 00:00:01 [kworker/u8:0-events_unbound] root 10 2 0 18:26 ? 00:00:00 [mm_percpu_wq] root 11 2 0 18:26 ? 00:00:00 [rcu_tasks_rude_kthread] root 12 2 0 18:26 ? 00:00:00 [rcu_tasks_trace_kthread] root 13 2 0 18:26 ? 00:00:00 [ksoftirqd/0] root 14 2 0 18:26 ? 00:00:00 [rcu_sched] root 15 2 0 18:26 ? 00:00:00 [migration/0] root 16 2 0 18:26 ? 00:00:00 [cpuhp/0] root 17 2 0 18:26 ? 00:00:00 [cpuhp/1] root 18 2 0 18:26 ? 00:00:00 [migration/1] root 19 2 0 18:26 ? 00:00:00 [ksoftirqd/1] root 20 2 0 18:26 ? 00:00:00 [kworker/1:0-events] root 22 2 0 18:26 ? 00:00:00 [cpuhp/2] root 23 2 0 18:26 ? 00:00:00 [migration/2] root 24 2 0 18:26 ? 00:00:00 [ksoftirqd/2] root 27 2 0 18:26 ? 00:00:00 [cpuhp/3] root 28 2 0 18:26 ? 00:00:00 [migration/3] root 29 2 0 18:26 ? 00:00:00 [ksoftirqd/3] root 30 2 0 18:26 ? 00:00:00 [kworker/3:0-events] root 32 2 0 18:26 ? 00:00:00 [kdevtmpfs] root 33 2 0 18:26 ? 00:00:00 [inet_frag_wq] root 34 2 0 18:26 ? 00:00:00 [kworker/0:1-events_freezable] root 35 2 0 18:26 ? 00:00:00 [kauditd] root 36 2 0 18:26 ? 00:00:00 [khungtaskd] root 37 2 0 18:26 ? 00:00:01 [kworker/u8:1-loop0] root 38 2 0 18:26 ? 00:00:00 [oom_reaper] root 39 2 0 18:26 ? 00:00:00 [writeback] root 40 2 0 18:26 ? 00:00:00 [kcompactd0] root 41 2 0 18:26 ? 00:00:00 [kintegrityd] root 42 2 0 18:26 ? 00:00:00 [kblockd] root 43 2 0 18:26 ? 00:00:00 [blkcg_punt_bio] root 44 2 0 18:26 ? 00:00:00 [watchdogd] root 45 2 0 18:26 ? 00:00:00 [kworker/u8:2-events_unbound] root 47 2 0 18:26 ? 00:00:00 [kworker/2:1-events] root 48 2 0 18:26 ? 00:00:00 [kworker/1:1H-kblockd] root 49 2 0 18:26 ? 00:00:00 [rpciod] root 50 2 0 18:26 ? 00:00:00 [xprtiod] root 51 2 0 18:26 ? 00:00:00 [kswapd0] root 52 2 0 18:26 ? 00:00:00 [nfsiod] root 53 2 0 18:26 ? 00:00:00 [kthrotld] root 55 2 0 18:26 ? 00:00:00 [kworker/1:2-events_power_efficient] root 59 2 0 18:26 ? 00:00:00 [kworker/3:1-events] root 60 2 0 18:26 ? 00:00:00 [hwrng] root 61 2 0 18:26 ? 00:00:00 [iscsi_conn_clea] root 62 2 0 18:26 ? 00:00:00 [nvme-wq] root 63 2 0 18:26 ? 00:00:00 [nvme-reset-wq] root 64 2 0 18:26 ? 00:00:00 [nvme-delete-wq] root 66 2 0 18:26 ? 00:00:00 [DWC Notificatio] root 67 2 0 18:26 ? 00:00:00 [uas] root 68 2 0 18:26 ? 00:00:00 [kworker/3:2-events] root 69 2 0 18:26 ? 00:00:00 [vchiq-slot/0] root 70 2 0 18:26 ? 00:00:00 [vchiq-recy/0] root 71 2 0 18:26 ? 00:00:00 [vchiq-sync/0] root 72 2 0 18:26 ? 00:00:00 [zswap-shrink] root 73 2 0 18:26 ? 00:00:00 [kworker/u9:0-brcmf_wq/mmc1:0001:1] root 95 2 0 18:26 ? 00:00:00 [sdhci] root 96 2 0 18:26 ? 00:00:00 [irq/52-mmc0] root 97 2 0 18:26 ? 00:00:00 [kworker/3:3-events] root 98 2 0 18:26 ? 00:00:00 [kworker/3:4-mm_percpu_wq] root 106 2 0 18:26 ? 00:00:00 [kworker/3:1H-kblockd] root 107 2 0 18:26 ? 00:00:00 [mmc_complete] root 108 2 0 18:26 ? 00:00:00 [kworker/0:1H-kblockd] root 128 2 0 18:26 ? 00:00:00 [kworker/1:2H-kblockd] root 132 2 0 18:26 ? 00:00:00 [kworker/0:2H-mmc_complete] root 133 2 0 18:26 ? 00:00:00 [jbd2/mmcblk0p2-8] root 134 2 0 18:26 ? 00:00:00 [ext4-rsv-conver] root 136 2 0 18:26 ? 00:00:00 [kworker/2:1H-kblockd] root 168 2 0 18:26 ? 00:00:00 [kworker/0:2-events_freezable] root 169 2 0 18:26 ? 00:00:00 [jbd2/mmcblk0p3-8] root 170 2 0 18:26 ? 00:00:00 [ext4-rsv-conver] root 178 2 0 18:26 ? 00:00:00 [kworker/2:2H-kblockd] root 179 2 0 18:26 ? 00:00:00 [mld] root 180 2 0 18:26 ? 00:00:00 [ipv6_addrconf] root 207 1 0 18:26 ? 00:00:00 /lib/systemd/systemd-journald root 226 2 0 18:26 ? 00:00:00 [kworker/3:2H-kblockd] root 231 1 0 18:26 ? 00:00:00 /lib/systemd/systemd-udevd root 279 2 0 18:26 ? 00:00:00 [vchiq-keep/0] root 282 2 0 18:26 ? 00:00:00 [SMIO] root 314 2 0 18:26 ? 00:00:00 [mmal-vchiq] root 319 2 0 18:26 ? 00:00:00 [mmal-vchiq] root 322 2 0 18:26 ? 00:00:00 [irq/54-feb10000.codec] root 324 2 0 18:26 ? 00:00:00 [mmal-vchiq] root 328 2 0 18:26 ? 00:00:00 [mmal-vchiq] root 330 2 0 18:26 ? 00:00:00 [mmal-vchiq] root 334 2 0 18:26 ? 00:00:00 [mmal-vchiq] root 338 2 0 18:26 ? 00:00:00 [mmal-vchiq] root 347 2 0 18:26 ? 00:00:00 [cfg80211] root 370 2 0 18:26 ? 00:00:00 [brcmf_wq/mmc1:0] root 372 2 0 18:26 ? 00:00:00 [brcmf_wdog/mmc1:0001:1] root 376 2 0 18:26 ? 00:00:00 [kworker/u9:2-hci0] root 403 2 0 18:26 ? 00:00:00 [kworker/2:3-mm_percpu_wq] root 443 1 0 18:26 ? 00:00:02 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 root 458 2 0 18:26 ? 00:00:00 [kworker/3:5-events] _rpc 462 1 0 18:26 ? 00:00:00 /sbin/rpcbind -f -w message+ 483 1 0 18:26 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 486 2 0 18:26 ? 00:00:00 [kworker/0:4-events_freezable] root 507 1 0 18:26 ? 00:00:00 /lib/systemd/systemd-logind nobody 513 1 0 18:26 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* root 523 1 0 18:26 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon avahi 528 1 0 18:26 ? 00:00:00 avahi-daemon: running [sibermusic.local] root 529 1 0 18:26 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant volumio 530 1 0 18:26 ? 00:00:00 /usr/local/bin/volumio-remote-updater avahi 578 528 0 18:26 ? 00:00:00 avahi-daemon: chroot helper root 590 1 0 18:26 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap shairpo+ 620 1 0 18:26 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf root 625 2 0 18:26 ? 00:00:00 [kworker/u8:4-loop0] root 626 1 0 18:26 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 649 1 0 18:26 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I root 652 1 0 18:26 ? 00:00:00 /usr/sbin/sshd -D ntp 654 1 0 18:26 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 root 678 1 0 18:26 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux root 682 626 0 18:26 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 767 1 0 18:27 ? 00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0 root 779 1 0 18:27 ? 00:00:00 /sbin/dhcpcd root 845 1 0 18:27 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 857 845 0 18:27 ? 00:00:00 winbindd: domain child [SIBERMUSIC] volumio 859 1 7 18:27 ? 00:00:22 /usr/bin/node /volumio/index.js root 861 1 0 18:27 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 878 861 0 18:27 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 879 861 0 18:27 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 880 845 0 18:27 ? 00:00:00 winbindd: idmap child root 881 845 0 18:27 ? 00:00:00 winbindd: domain child [BUILTIN] root 883 861 0 18:27 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 917 2 0 18:27 ? 00:00:00 [cifsiod] root 918 2 0 18:27 ? 00:00:00 [smb3decryptd] root 919 2 0 18:27 ? 00:00:00 [cifsfileinfoput] root 920 2 0 18:27 ? 00:00:00 [cifsoplockd] root 921 2 0 18:27 ? 00:00:00 [deferredclose] root 923 2 0 18:27 ? 00:00:00 [cifsd] volumio 938 859 0 18:27 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 947 938 0 18:27 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 948 938 0 18:27 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 954 938 0 18:27 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1014 1 0 18:27 ? 00:00:00 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf volumio 1127 1 0 18:27 ? 00:00:00 /bin/sh /bin/start-go-liberspot.sh volumio 1129 1127 0 18:27 ? 00:00:00 /usr/bin/go-librespot -config_path /tmp/go-librespot-config.yml -credentials_path /data/configuration/music_service/spop/spotifycredentials.json volumio 1138 1 0 18:27 ? 00:00:00 /bin/sh /usr/bin/startx /etc/X11/Xsession /opt/volumiokiosk.sh -- -nocursor volumio 1165 1138 0 18:27 ? 00:00:00 xinit /etc/X11/Xsession /opt/volumiokiosk.sh -- /usr/bin/X :0 -nocursor -auth /tmp/serverauth.E2gqtoBe8X root 1166 1165 0 18:27 tty2 00:00:00 /usr/lib/xorg/Xorg :0 -nocursor -auth /tmp/serverauth.E2gqtoBe8X root 1209 2 0 18:27 ? 00:00:00 [rc0] root 1341 1 0 18:27 ? 00:00:00 /usr/sbin/lircd --nodaemon root 1344 1341 0 18:27 ? 00:00:00 [sh] volumio 1346 1165 0 18:27 ? 00:00:00 /bin/bash /opt/volumiokiosk.sh root 1382 1 0 18:27 ? 00:00:00 /usr/bin/irexec /etc/lirc/irexec.lircrc volumio 1404 1346 0 18:27 ? 00:00:00 /usr/bin/ssh-agent /opt/volumiokiosk.sh volumio 1413 1346 0 18:27 ? 00:00:00 /usr/bin/openbox --startup /usr/lib/arm-linux-gnueabihf/openbox-autostart OPENBOX volumio 1414 1346 0 18:27 ? 00:00:02 /usr/lib/chromium-browser/chromium-browser-v7 --enable-pinch --simulate-outdated-no-au=Tue, 31 Dec 2099 23:59:59 GMT --force-device-scale-factor=0.9 --disable-pinch --kiosk --no-first-run --noerrdialogs --disable-3d-apis --disable-breakpad --disable-crash-reporter --disable-infobars --disable-session-crashed-bubble --disable-translate --user-data-dir=/data/volumiokiosk http://localhost:4004 volumio 1485 1 0 18:27 ? 00:00:00 /usr/lib/chromium-browser/crashpad_handler --monitor-self --monitor-self-annotation=ptype=crashpad-handler --database=/home/volumio/.config/chromium/Crash Reports --metrics-dir=/data/volumiokiosk --annotation=channel=Built on Raspbian , running on Raspbian 10 --annotation=lsb-release=Raspbian GNU/Linux 10 (buster) --annotation=plat=Linux --annotation=prod=Chrome_Linux --annotation=ver=92.0.4515.98 --initial-client-fd=7 --shared-client-connection volumio 1487 1 0 18:27 ? 00:00:00 /usr/lib/chromium-browser/crashpad_handler --no-periodic-tasks --monitor-self-annotation=ptype=crashpad-handler --database=/home/volumio/.config/chromium/Crash Reports --annotation=channel=Built on Raspbian , running on Raspbian 10 --annotation=lsb-release=Raspbian GNU/Linux 10 (buster) --annotation=plat=Linux --annotation=prod=Chrome_Linux --annotation=ver=92.0.4515.98 --initial-client-fd=4 --shared-client-connection volumio 1491 1414 0 18:27 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser-v7 --type=zygote --no-zygote-sandbox --force-device-scale-factor=0.9 --enable-crashpad --crashpad-handler-pid=0 --enable-crash-reporter=,Built on Raspbian , running on Raspbian 10 --noerrdialogs --user-data-dir=/data/volumiokiosk --enable-crashpad volumio 1492 1414 0 18:27 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser-v7 --type=zygote --force-device-scale-factor=0.9 --enable-crashpad --crashpad-handler-pid=0 --enable-crash-reporter=,Built on Raspbian , running on Raspbian 10 --noerrdialogs --user-data-dir=/data/volumiokiosk --enable-crashpad volumio 1494 1492 0 18:27 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser-v7 --type=zygote --force-device-scale-factor=0.9 --enable-crashpad --crashpad-handler-pid=0 --enable-crash-reporter=,Built on Raspbian , running on Raspbian 10 --noerrdialogs --user-data-dir=/data/volumiokiosk --enable-crashpad volumio 1523 1414 0 18:27 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser-v7 --type=utility --utility-sub-type=network.mojom.NetworkService --field-trial-handle=7505580978751396216,593127659249537140,131072 --lang=en-US --service-sandbox-type=none --enable-crashpad --crashpad-handler-pid=0 --enable-crash-reporter=,Built on Raspbian , running on Raspbian 10 --noerrdialogs --user-data-dir=/data/volumiokiosk --shared-files=v8_context_snapshot_data:100 --enable-crashpad volumio 1538 1491 0 18:27 ? 00:00:01 /usr/lib/chromium-browser/chromium-browser-v7 --type=gpu-process --field-trial-handle=7505580978751396216,593127659249537140,131072 --disable-breakpad --enable-crashpad --crashpad-handler-pid=0 --enable-crash-reporter=,Built on Raspbian , running on Raspbian 10 --noerrdialogs --user-data-dir=/data/volumiokiosk --gpu-preferences=UAAAAAAAAAAgAAAQAAAAAAAAAAAAAAAAAABgAAAAAAAwAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAQAAANgAAAAAAAAA2AAAAAAAAABIAAAACAAAAEAAAAAAAAAASAAAAAAAAABQAAAAAAAAAFgAAAAAAAAAYAAAAAAAAABoAAAAAAAAAHAAAAAAAAAAeAAAAAAAAAAQAAAAAAAAAAAAAAAMAAAAEAAAAAAAAAAAAAAADQAAABAAAAAAAAAAAQAAAAwAAAAQAAAAAAAAAAEAAAANAAAAEAAAAAAAAAAEAAAADAAAABAAAAAAAAAABAAAAA0AAAAQAAAAAAAAAAcAAAAMAAAAEAAAAAAAAAAHAAAADQAAAAgAAAAAAAAACAAAAAAAAAA= --use-gl=swiftshader-webgl --shared-files volumio 1541 1494 0 18:27 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser-v7 --type=utility --utility-sub-type=storage.mojom.StorageService --field-trial-handle=7505580978751396216,593127659249537140,131072 --lang=en-US --service-sandbox-type=utility --enable-crashpad --crashpad-handler-pid=0 --enable-crash-reporter=,Built on Raspbian , running on Raspbian 10 --noerrdialogs --user-data-dir=/data/volumiokiosk --shared-files=v8_context_snapshot_data:100 volumio 1546 1538 0 18:27 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser-v7 --type=broker volumio 1567 1494 0 18:27 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser-v7 --type=renderer --disable-breakpad --force-device-scale-factor=0.9 --field-trial-handle=7505580978751396216,593127659249537140,131072 --disable-gpu-compositing --lang=en-US --enable-crashpad --crashpad-handler-pid=0 --enable-crash-reporter=,Built on Raspbian , running on Raspbian 10 --noerrdialogs --user-data-dir=/data/volumiokiosk --disable-3d-apis --origin-trial-disabled-features=WebGPU --num-raster-threads=1 --renderer-client-id=7 --no-v8-untrusted-code-mitigations --shared-files=v8_context_snapshot_data:100 volumio 1568 1494 2 18:27 ? 00:00:08 /usr/lib/chromium-browser/chromium-browser-v7 --type=renderer --disable-breakpad --force-device-scale-factor=0.9 --field-trial-handle=7505580978751396216,593127659249537140,131072 --disable-gpu-compositing --lang=en-US --enable-crashpad --crashpad-handler-pid=0 --enable-crash-reporter=,Built on Raspbian , running on Raspbian 10 --noerrdialogs --user-data-dir=/data/volumiokiosk --disable-3d-apis --origin-trial-disabled-features=WebGPU --num-raster-threads=1 --renderer-client-id=6 --no-v8-untrusted-code-mitigations --shared-files=v8_context_snapshot_data:100 volumio 1882 859 0 18:28 ? 00:00:00 /usr/bin/node /myvolumio/plugins/music_service/streaming_services/streamingProxy.js root 2013 2 0 18:28 ? 00:00:00 [kworker/3:6] mpd 2015 1 0 18:28 ? 00:00:00 /usr/bin/mpd --systemd volumio 2051 1 0 18:28 ? 00:00:00 /bin/sh /bin/bt/btstart.sh start volumio 2073 1 0 18:28 ? 00:00:00 /bin/sh /tmp/sshtunnel.sh volumio 2075 1 0 18:28 ? 00:00:00 /usr/bin/pulseaudio --high-priority=true volumio 2076 2073 0 18:28 ? 00:00:00 /usr/lib/autossh/autossh -M 0 -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R h0PZQsB39AURSlylidLe37OavVF3-88dd99399252828aa992fa9b2610eee3.eu2.myvolumio.org:8091:localhost:3000 eu2.myvolumio.org rtkit 2079 1 0 18:28 ? 00:00:00 /usr/lib/rtkit/rtkit-daemon volumio 2080 2076 0 18:28 ? 00:00:00 /usr/bin/ssh -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R h0PZQsB39AURSlylidLe37OavVF3-88dd99399252828aa992fa9b2610eee3.eu2.myvolumio.org:8091:localhost:3000 eu2.myvolumio.org root 2083 1 0 18:28 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug root 2105 2 0 18:28 ? 00:00:00 [krfcommd] volumio 2109 2051 0 18:28 ? 00:00:00 /usr/bin/python /bin/bt/simple-agent -c NoInputNoOutput volumio 2160 859 0 18:32 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'NAS showing NaN MB after update to 3.601' volumio 2161 2160 0 18:32 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js NAS showing NaN MB after update to 3.601 volumio 2189 2161 0 18:32 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 2190 2189 0 18:32 ? 00:00:00 ps -ef # df -h --------------- df: /mnt/NAS/sibernas: Resource temporarily unavailable Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.5G 1.1G 1.3G 47% /imgpart /dev/loop0 489M 489M 0 100% /static overlay 4.7G 2.7G 1.8G 60% / devtmpfs 1.9G 0 1.9G 0% /dev tmpfs 1.9G 19M 1.9G 1% /dev/shm tmpfs 1.9G 5.3M 1.9G 1% /run tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 1.9G 0 1.9G 0% /sys/fs/cgroup tmpfs 1.9G 0 1.9G 0% /var/spool/cups tmpfs 1.9G 448K 1.9G 1% /tmp tmpfs 20M 44K 20M 1% /var/log tmpfs 1.9G 0 1.9G 0% /var/spool/cups/tmp /dev/mmcblk0p1 92M 66M 27M 72% /boot # mount --------------- /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) sysfs on /sys type sysfs (rw,nosuid,nodev,noexec,relatime) proc on /proc type proc (rw,relatime) devtmpfs on /dev type devtmpfs (rw,nosuid,size=1925084k,nr_inodes=146414,mode=755) securityfs on /sys/kernel/security type securityfs (rw,nosuid,nodev,noexec,relatime) tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev,noexec) devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000) tmpfs on /run type tmpfs (rw,nosuid,nodev,mode=755) tmpfs on /run/lock type tmpfs (rw,nosuid,nodev,noexec,relatime,size=5120k) tmpfs on /sys/fs/cgroup type tmpfs (ro,nosuid,nodev,noexec,mode=755) cgroup2 on /sys/fs/cgroup/unified type cgroup2 (rw,nosuid,nodev,noexec,relatime,nsdelegate) cgroup on /sys/fs/cgroup/systemd type cgroup (rw,nosuid,nodev,noexec,relatime,xattr,name=systemd) pstore on /sys/fs/pstore type pstore (rw,nosuid,nodev,noexec,relatime) bpf on /sys/fs/bpf type bpf (rw,nosuid,nodev,noexec,relatime,mode=700) cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) cgroup on /sys/fs/cgroup/net_cls,net_prio type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls,net_prio) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) cgroup on /sys/fs/cgroup/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event) cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=45,pgrp=1,timeout=0,minproto=5,maxproto=5,direct) debugfs on /sys/kernel/debug type debugfs (rw,relatime) sunrpc on /run/rpc_pipefs type rpc_pipefs (rw,relatime) mqueue on /dev/mqueue type mqueue (rw,relatime) configfs on /sys/kernel/config type configfs (rw,relatime) tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755) tmpfs on /tmp 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) none on /cpusets type cgroup (rw,relatime,cpuset) //192.168.178.10/sibernas on /mnt/NAS/sibernas type cifs (ro,relatime,vers=3.1.1,cache=strict,username=pi,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.178.10,file_mode=0666,dir_mode=0777,iocharset=utf8,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,echo_interval=60,actimeo=1,closetimeo=1) # 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:139 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:4713 0.0.0.0:* LISTEN 2075/pulseaudio tcp 0 0 0.0.0.0:111 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:22 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:49152 0.0.0.0:* LISTEN 1014/upmpdcli tcp 0 0 0.0.0.0:49149 0.0.0.0:* LISTEN 1014/upmpdcli 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 192.168.178.227:47634 84.247.14.230:80 ESTABLISHED 859/node tcp 0 0 192.168.178.227:56904 185.14.187.123:2222 ESTABLISHED 2080/ssh tcp 0 0 192.168.178.227:45342 192.168.178.73:3000 ESTABLISHED 859/node tcp 0 0 127.0.0.1:60994 127.0.0.1:3000 TIME_WAIT - tcp 0 0 127.0.0.1:35844 127.0.0.1:6599 ESTABLISHED 1014/upmpdcli tcp 0 0 192.168.178.227:58166 142.251.36.3:443 ESTABLISHED 1523/volumiokiosk - tcp 0 0 192.168.178.227:45078 34.158.0.131:4070 ESTABLISHED 1129/go-librespot tcp 0 0 127.0.0.1:35852 127.0.0.1:6599 ESTABLISHED 1014/upmpdcli tcp 0 0 127.0.0.1:36200 127.0.0.1:9879 ESTABLISHED 859/node tcp 0 0 192.168.178.227:56734 172.217.168.202:443 ESTABLISHED 1523/volumiokiosk - tcp 0 0 192.168.178.227:33748 35.190.39.113:443 ESTABLISHED 859/node tcp 0 0 127.0.0.1:46012 127.0.0.1:3000 ESTABLISHED 859/node tcp 0 0 127.0.0.1:50238 127.0.0.1:3000 ESTABLISHED 1523/volumiokiosk - tcp 0 0 192.168.178.227:38280 35.186.224.34:443 ESTABLISHED 1129/go-librespot tcp 0 0 127.0.0.1:45984 127.0.0.1:3000 ESTABLISHED 530/volumio-remote- tcp 0 0 127.0.0.1:39682 127.0.0.1:3000 ESTABLISHED 859/node tcp 0 0 127.0.0.1:39726 127.0.0.1:3000 ESTABLISHED 859/node tcp 0 0 192.168.178.227:40494 142.250.27.84:443 TIME_WAIT - tcp 0 0 127.0.0.1:59398 127.0.0.1:3000 ESTABLISHED 859/node tcp 0 0 127.0.0.1:59426 127.0.0.1:3000 ESTABLISHED 859/node tcp 0 0 192.168.178.227:44138 192.168.178.10:445 ESTABLISHED - tcp6 0 0 :::9879 :::* LISTEN 1129/go-librespot tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::3245 :::* LISTEN 1882/node tcp6 0 0 :::4713 :::* LISTEN 2075/pulseaudio tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::22 :::* LISTEN - tcp6 0 0 :::6599 :::* LISTEN 859/node tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 859/node tcp6 0 0 :::3001 :::* LISTEN 938/node tcp6 0 0 :::3005 :::* LISTEN 859/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::4004 :::* LISTEN 859/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:50238 ESTABLISHED 859/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:35844 ESTABLISHED 859/node tcp6 0 0 192.168.178.227:3000 192.168.178.123:61479 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:39682 ESTABLISHED 859/node tcp6 0 0 192.168.178.227:3000 192.168.178.73:60498 ESTABLISHED 859/node tcp6 0 0 192.168.178.227:3000 192.168.178.123:61476 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:46012 ESTABLISHED 859/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:59398 ESTABLISHED 859/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:39726 ESTABLISHED 859/node tcp6 0 0 192.168.178.227:3000 192.168.178.123:61488 ESTABLISHED 859/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:59426 ESTABLISHED 859/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:45984 ESTABLISHED 859/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:35852 ESTABLISHED 859/node tcp6 0 0 192.168.178.227:3000 192.168.178.123:61477 TIME_WAIT - tcp6 0 0 192.168.178.227:3000 192.168.178.66:57940 ESTABLISHED 859/node tcp6 0 0 127.0.0.1:9879 127.0.0.1:36200 ESTABLISHED 1129/go-librespot # cat /etc/asound.conf --------------- pcm.!default { type empty slave.pcm "volumio" } pcm.volumio { type empty slave.pcm "volumioMultiRoomServer" } # This PCM switches output to the ALSA target named by the file /tmp/multiroom/server/switch.target pcm.volumioMultiRoomServer { type volumioswitch target_file "/tmp/multiroom/server/switch.target" control_fifo "/tmp/multiroom/server/switch.fifo" format_append "true" format_1 "DSD_U32_BE" rate_min 44100 rate_max 384000 } # This PCM switches output to the ALSA target named by the file /tmp/multiroom/client/switch.target pcm.volumioMultiRoomClient { type volumioswitch target_file "/tmp/multiroom/client/switch.target" control_fifo "/tmp/multiroom/client/switch.fifo" } # This PCM performs any necessary rate/format/channel conversion before sending on to the Server FIFO pcm.volumioMultiRoom { type plug slave { pcm "volumioMultiRoomFIFO" rate 48000 format "S16_LE" channels 2 } rate_converter "speexrate_medium" } # Write to the FIFO watched by the Server pcm.volumioMultiRoomFIFO { type volumiofifo fifo "/tmp/multiroom/server/fifo" clear_on_drop false lead_in_frames 16384 } # This pcm is used by pcm.volumioMultiRoomServer and pcm.volumioMultiRoomClient to play audio on the local output pcm.volumioLocalPlayback { type empty slave.pcm "postMultiRoom" } # This pcm is used by pcm.volumioMultiRoomClient to discard audio and allow for faster switching pcm.volumioDiscard { type null } pcm.postMultiRoom { type empty slave.pcm "volumioOutput" } # There is always a plug before the hardware to be safe pcm.volumioOutput { type plug slave.pcm "volumioHw" } pcm.volumioHw { type hw card "D10s" } # cat /var/log/mpd.log --------------- Jan 28 22:56 : zeroconf: No global port, disabling zeroconf Jan 28 22:56 : exception: No such directory Jan 30 18:28 : zeroconf: No global port, disabling zeroconf # cat /tmp/camilladsp.log --------------- cat: /tmp/camilladsp.log: No such file or directory # cat /tmp/cec-client.log --------------- cat: /tmp/cec-client.log: No such file or directory # volumio plugin list --------------- This command will list installed plugins on your device ----------------------- Installed Plugins: [ { prettyName: 'Bandcamp Discover', name: 'bandcamp', category: 'music_service', version: '1.1.0', icon: 'fa-bandcamp', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'Spotify', name: 'spop', category: 'music_service', version: '4.0.4', icon: 'fa-spotify', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'YouTube Music', name: 'ytmusic', category: 'music_service', version: '1.0.0', icon: 'fa-youtube-play', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'IR Remote Controller', name: 'ir_controller', category: 'system_hardware', version: '3.4.11', icon: 'fa-magic', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'Music Services Shield', name: 'music_services_shield', category: 'system_hardware', version: '1.3.1', icon: 'fa-shield', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'Now Playing', name: 'now_playing', category: 'user_interface', version: '0.5.4', icon: 'fa-play-circle', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'Touch Display', name: 'touch_display', category: 'user_interface', version: '3.3.7', icon: 'fa-hand-pointer-o', isManuallyInstalled: false, enabled: true, active: true } ] ------------------------------------------ # cat /data/configuration/plugins.json --------------- { "music_service": { "airplay_emulation": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "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": "STOPPED" }, "enabled": { "type": "boolean", "value": false } }, "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": "STOPPED" }, "enabled": { "type": "boolean", "value": false } }, "bandcamp": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "spop": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "ytmusic": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "audio_interface": { "outputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "alsa_controller": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "bluetooth": { "status": { "type": "string", "value": "STARTED" }, "enabled": { "type": "boolean", "value": true } }, "multiroom": { "status": { "type": "string", "value": "STARTED" } } }, "miscellanea": { "alarm-clock": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "albumart": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "appearance": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "wizard": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "my_music": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "metavolumio": { "status": { "type": "string", "value": "STARTED" } }, "manifestui": { "status": { "type": "string", "value": "STARTED" } } }, "user_interface": { "websocket": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpdemulation": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STARTED" } }, "rest_api": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "touch_display": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "now_playing": { "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" } } }, "system_hardware": { "ir_controller": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "music_services_shield": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } } } # cat /etc/mpd.conf --------------- # Volumio MPD Configuration File # Files and directories ####################################################### music_directory "/var/lib/mpd/music" playlist_directory "/var/lib/mpd/playlists" db_file "/var/lib/mpd/tag_cache" log_file "/var/log/mpd.log" #pid_file "/var/run/mpd/pid" #state_file "/var/lib/mpd/state" #sticker_file "/var/lib/mpd/sticker.sql" ############################################################################### # General music daemon options ################################################ user "mpd" group "audio" bind_to_address "any" #port "6600" log_level "default" #save_absolute_paths_in_playlists "no" #metadata_to_use "artist,album,title,track,name,genre,date,composer,performer,disc" auto_update "no" #auto_update_depth "3" ############################################################################### # Symbolic link behavior ###################################################### follow_outside_symlinks "yes" follow_inside_symlinks "yes" ############################################################################### # Input ####################################################################### # input { plugin "curl" # proxy "proxy.isp.com:8080" # proxy_user "user" # proxy_password "password" } ############################################################################### # Decoder ################################################################ decoder { plugin "faad" enabled "no" } ############################################################################### # Audio Output ################################################################ resampler { plugin "soxr" quality "high" threads "1" } audio_output { type "alsa" name "alsa" device "volumio" dop "no" } 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, 382 ms: OK https://www.googleapis.com, 257 ms: OK https://securetoken.googleapis.com, 299 ms: OK https://myvolumio.firebaseio.com, 412 ms: OK https://functions.volumio.cloud, 362 ms: OK https://oauth-performer.dfs.volumio.org, 235 ms: OK https://browsing-performer.dfs.volumio.org, 340 ms: OK http://cddb.volumio.org, 521 ms: OK https://functions.volumio.cloud, 446 ms: OK http://pushupdates.volumio.org, 320 ms: OK http://plugins.volumio.org, 669 ms: OK https://database.volumio.cloud, 470 ms: OK https://radio-directory.firebaseapp.com, 1801 ms: OK ---------- REMOTE ENDPOINTS TEST OK, all Endpoints are reachable ---------- # cat /data/updatesHistoryFile --------------- --- Fri Oct 20 2023 13:46:04 GMT+0000 (Coordinated Universal Time) {"systemversion":"3.569","builddate":"Fri 20 Oct 2023 03:38:28 PM CEST","variant":"volumio","hardware":"pi"} ------ Sun Jan 28 2024 22:52:35 GMT+0100 (Central European Standard Time) {"systemversion":"3.601","builddate":"Fri 19 Jan 2024 06:17:59 PM CET","variant":"volumio","hardware":"pi"} --- # sudo journalctl -p 7 --------------- -- Logs begin at Sun 2024-01-28 22:55:54 CET, end at Tue 2024-01-30 18:32:33 CET. -- Jan 28 22:55:54 sibermusic kernel: Booting Linux on physical CPU 0x0 Jan 28 22:55:54 sibermusic kernel: Linux version 6.1.70-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1712 SMP Tue Jan 2 18:29:06 GMT 2024 Jan 28 22:55:54 sibermusic kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Jan 28 22:55:54 sibermusic kernel: CPU: div instructions available: patching division code Jan 28 22:55:54 sibermusic kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Jan 28 22:55:54 sibermusic kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.2 Jan 28 22:55:54 sibermusic kernel: random: crng init done Jan 28 22:55:54 sibermusic kernel: Memory policy: Data cache writealloc Jan 28 22:55:54 sibermusic kernel: Reserved memory: created CMA memory pool at 0x000000002a000000, size 64 MiB Jan 28 22:55:54 sibermusic kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Jan 28 22:55:54 sibermusic kernel: Zone ranges: Jan 28 22:55:54 sibermusic kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] Jan 28 22:55:54 sibermusic kernel: Normal empty Jan 28 22:55:54 sibermusic kernel: HighMem [mem 0x0000000030000000-0x00000000fbffffff] Jan 28 22:55:54 sibermusic kernel: Movable zone start for each node Jan 28 22:55:54 sibermusic kernel: Early memory node ranges Jan 28 22:55:54 sibermusic kernel: node 0: [mem 0x0000000000000000-0x000000003b3fffff] Jan 28 22:55:54 sibermusic kernel: node 0: [mem 0x0000000040000000-0x00000000fbffffff] Jan 28 22:55:54 sibermusic kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff] Jan 28 22:55:54 sibermusic kernel: percpu: Embedded 17 pages/cpu s37012 r8192 d24428 u69632 Jan 28 22:55:54 sibermusic kernel: pcpu-alloc: s37012 r8192 d24428 u69632 alloc=17*4096 Jan 28 22:55:54 sibermusic kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jan 28 22:55:54 sibermusic kernel: Built 1 zonelists, mobility grouping on. Total pages: 1011008 Jan 28 22:55:54 sibermusic kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:6C:7A:FC 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=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop 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 Jan 28 22:55:54 sibermusic kernel: Kernel parameter elevator= does not have any effect anymore. Please use sysfs to set IO scheduler for individual devices. Jan 28 22:55:54 sibermusic kernel: Unknown kernel command line parameters "splash imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh bootdelay=5", will be passed to user space. Jan 28 22:55:54 sibermusic kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Jan 28 22:55:54 sibermusic kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Jan 28 22:55:54 sibermusic kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jan 28 22:55:54 sibermusic kernel: software IO TLB: area num 4. Jan 28 22:55:54 sibermusic kernel: software IO TLB: mapped [mem 0x0000000023c90000-0x0000000027c90000] (64MB) Jan 28 22:55:54 sibermusic kernel: Memory: 3850168K/4050944K available (10240K kernel code, 1504K rwdata, 3384K rodata, 2048K init, 638K bss, 135240K reserved, 65536K cma-reserved, 3264512K highmem) Jan 28 22:55:54 sibermusic kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jan 28 22:55:54 sibermusic kernel: ftrace: allocating 37228 entries in 110 pages Jan 28 22:55:54 sibermusic kernel: ftrace: allocated 110 pages with 5 groups Jan 28 22:55:54 sibermusic kernel: trace event string verifier disabled Jan 28 22:55:54 sibermusic kernel: rcu: Hierarchical RCU implementation. Jan 28 22:55:54 sibermusic kernel: Rude variant of Tasks RCU enabled. Jan 28 22:55:54 sibermusic kernel: Tracing variant of Tasks RCU enabled. Jan 28 22:55:54 sibermusic kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Jan 28 22:55:54 sibermusic kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Jan 28 22:55:54 sibermusic kernel: GIC: Using split EOI/Deactivate mode Jan 28 22:55:54 sibermusic kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jan 28 22:55:54 sibermusic kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Jan 28 22:55:54 sibermusic kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Jan 28 22:55:54 sibermusic kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Jan 28 22:55:54 sibermusic kernel: Switching to timer-based delay loop, resolution 18ns Jan 28 22:55:54 sibermusic kernel: Console: colour dummy device 80x30 Jan 28 22:55:54 sibermusic kernel: printk: console [tty1] enabled Jan 28 22:55:54 sibermusic kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Jan 28 22:55:54 sibermusic kernel: CPU: Testing write buffer coherency: ok Jan 28 22:55:54 sibermusic kernel: CPU0: Spectre BHB: enabling loop workaround for all CPUs Jan 28 22:55:54 sibermusic kernel: pid_max: default: 32768 minimum: 301 Jan 28 22:55:54 sibermusic kernel: LSM: Security Framework initializing Jan 28 22:55:54 sibermusic kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Jan 28 22:55:54 sibermusic kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Jan 28 22:55:54 sibermusic kernel: cgroup: Disabling memory control group subsystem Jan 28 22:55:54 sibermusic kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Jan 28 22:55:54 sibermusic kernel: cblist_init_generic: Setting adjustable number of callback queues. Jan 28 22:55:54 sibermusic kernel: cblist_init_generic: Setting shift to 2 and lim to 1. Jan 28 22:55:54 sibermusic kernel: cblist_init_generic: Setting adjustable number of callback queues. Jan 28 22:55:54 sibermusic kernel: cblist_init_generic: Setting shift to 2 and lim to 1. Jan 28 22:55:54 sibermusic kernel: Setting up static identity map for 0x200000 - 0x20003c Jan 28 22:55:54 sibermusic kernel: rcu: Hierarchical SRCU implementation. Jan 28 22:55:54 sibermusic kernel: rcu: Max phase no-delay instances is 1000. Jan 28 22:55:54 sibermusic kernel: smp: Bringing up secondary CPUs ... Jan 28 22:55:54 sibermusic kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Jan 28 22:55:54 sibermusic kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Jan 28 22:55:54 sibermusic kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Jan 28 22:55:54 sibermusic kernel: smp: Brought up 1 node, 4 CPUs Jan 28 22:55:54 sibermusic kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Jan 28 22:55:54 sibermusic kernel: CPU: All CPU(s) started in HYP mode. Jan 28 22:55:54 sibermusic kernel: CPU: Virtualization extensions available. Jan 28 22:55:54 sibermusic kernel: devtmpfs: initialized Jan 28 22:55:54 sibermusic kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Jan 28 22:55:54 sibermusic kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Jan 28 22:55:54 sibermusic kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jan 28 22:55:54 sibermusic kernel: pinctrl core: initialized pinctrl subsystem Jan 28 22:55:54 sibermusic kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jan 28 22:55:54 sibermusic kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Jan 28 22:55:54 sibermusic kernel: audit: initializing netlink subsys (disabled) Jan 28 22:55:54 sibermusic kernel: audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1 Jan 28 22:55:54 sibermusic kernel: thermal_sys: Registered thermal governor 'step_wise' Jan 28 22:55:54 sibermusic kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Jan 28 22:55:54 sibermusic kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Jan 28 22:55:54 sibermusic kernel: Serial: AMBA PL011 UART driver Jan 28 22:55:54 sibermusic kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Jan 28 22:55:54 sibermusic kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2023-10-17T15:39:16, variant start Jan 28 22:55:54 sibermusic kernel: raspberrypi-firmware soc:firmware: Firmware hash is 30f0c5e4d076da3ab4f341d88e7d505760b93ad7 Jan 28 22:55:54 sibermusic kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jan 28 22:55:54 sibermusic kernel: bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1 Jan 28 22:55:54 sibermusic kernel: SCSI subsystem initialized Jan 28 22:55:54 sibermusic kernel: usbcore: registered new interface driver usbfs Jan 28 22:55:54 sibermusic kernel: usbcore: registered new interface driver hub Jan 28 22:55:54 sibermusic kernel: usbcore: registered new device driver usb Jan 28 22:55:54 sibermusic kernel: usb_phy_generic phy: supply vcc not found, using dummy regulator Jan 28 22:55:54 sibermusic kernel: pps_core: LinuxPPS API ver. 1 registered Jan 28 22:55:54 sibermusic kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jan 28 22:55:54 sibermusic kernel: PTP clock support registered Jan 28 22:55:54 sibermusic kernel: vgaarb: loaded Jan 28 22:55:54 sibermusic kernel: clocksource: Switched to clocksource arch_sys_counter Jan 28 22:55:54 sibermusic kernel: VFS: Disk quotas dquot_6.6.0 Jan 28 22:55:54 sibermusic kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Jan 28 22:55:54 sibermusic kernel: FS-Cache: Loaded Jan 28 22:55:54 sibermusic kernel: CacheFiles: Loaded Jan 28 22:55:54 sibermusic kernel: NET: Registered PF_INET protocol family Jan 28 22:55:54 sibermusic kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Jan 28 22:55:54 sibermusic kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) Jan 28 22:55:54 sibermusic kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jan 28 22:55:54 sibermusic kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Jan 28 22:55:54 sibermusic kernel: TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear) Jan 28 22:55:54 sibermusic kernel: TCP: Hash tables configured (established 8192 bind 8192) Jan 28 22:55:54 sibermusic kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Jan 28 22:55:54 sibermusic kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Jan 28 22:55:54 sibermusic kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jan 28 22:55:54 sibermusic kernel: RPC: Registered named UNIX socket transport module. Jan 28 22:55:54 sibermusic kernel: RPC: Registered udp transport module. Jan 28 22:55:54 sibermusic kernel: RPC: Registered tcp transport module. Jan 28 22:55:54 sibermusic kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jan 28 22:55:54 sibermusic kernel: PCI: CLS 0 bytes, default 64 Jan 28 22:55:54 sibermusic kernel: Trying to unpack rootfs image as initramfs... Jan 28 22:55:54 sibermusic kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Jan 28 22:55:54 sibermusic kernel: Initialise system trusted keyrings Jan 28 22:55:54 sibermusic kernel: workingset: timestamp_bits=14 max_order=20 bucket_order=6 Jan 28 22:55:54 sibermusic kernel: zbud: loaded Jan 28 22:55:54 sibermusic kernel: NFS: Registering the id_resolver key type Jan 28 22:55:54 sibermusic kernel: Key type id_resolver registered Jan 28 22:55:54 sibermusic kernel: Key type id_legacy registered Jan 28 22:55:54 sibermusic kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Jan 28 22:55:54 sibermusic kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Jan 28 22:55:54 sibermusic kernel: Key type asymmetric registered Jan 28 22:55:54 sibermusic kernel: Asymmetric key parser 'x509' registered Jan 28 22:55:54 sibermusic kernel: bounce: pool size: 64 pages Jan 28 22:55:54 sibermusic kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Jan 28 22:55:54 sibermusic kernel: io scheduler mq-deadline registered Jan 28 22:55:54 sibermusic kernel: io scheduler kyber registered Jan 28 22:55:54 sibermusic kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Jan 28 22:55:54 sibermusic kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Jan 28 22:55:54 sibermusic kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Jan 28 22:55:54 sibermusic kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000 Jan 28 22:55:54 sibermusic kernel: brcm-pcie fd500000.pcie: setting SCB_ACCESS_EN, READ_UR_MODE, MAX_BURST_SIZE Jan 28 22:55:54 sibermusic kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Jan 28 22:55:54 sibermusic kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jan 28 22:55:54 sibermusic kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Jan 28 22:55:54 sibermusic kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Jan 28 22:55:54 sibermusic kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Jan 28 22:55:54 sibermusic kernel: PCI: bus0: Fast back to back transfers disabled Jan 28 22:55:54 sibermusic kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Jan 28 22:55:54 sibermusic kernel: pci_bus 0000:01: supply vpcie3v3 not found, using dummy regulator Jan 28 22:55:54 sibermusic kernel: pci_bus 0000:01: supply vpcie3v3aux not found, using dummy regulator Jan 28 22:55:54 sibermusic kernel: pci_bus 0000:01: supply vpcie12v not found, using dummy regulator Jan 28 22:55:54 sibermusic kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Jan 28 22:55:54 sibermusic kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Jan 28 22:55:54 sibermusic kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Jan 28 22:55:54 sibermusic kernel: pci 0000:01:00.0: PME# supported from D0 D3cold Jan 28 22:55:54 sibermusic kernel: PCI: bus1: Fast back to back transfers disabled Jan 28 22:55:54 sibermusic kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Jan 28 22:55:54 sibermusic kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Jan 28 22:55:54 sibermusic kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Jan 28 22:55:54 sibermusic kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Jan 28 22:55:54 sibermusic kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Jan 28 22:55:54 sibermusic kernel: pcieport 0000:00:00.0: enabling device (0000 -> 0002) Jan 28 22:55:54 sibermusic kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 45 Jan 28 22:55:54 sibermusic kernel: bcm2708_fb soc:fb: FB found 1 display(s) Jan 28 22:55:54 sibermusic kernel: Console: switching to colour frame buffer device 100x30 Jan 28 22:55:54 sibermusic kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 800x480 Jan 28 22:55:54 sibermusic kernel: iproc-rng200 fe104000.rng: hwrng registered Jan 28 22:55:54 sibermusic kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Jan 28 22:55:54 sibermusic kernel: brd: module loaded Jan 28 22:55:54 sibermusic kernel: loop: module loaded Jan 28 22:55:54 sibermusic kernel: Loading iSCSI transport class v2.0-870. Jan 28 22:55:54 sibermusic kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Jan 28 22:55:54 sibermusic kernel: Freeing initrd memory: 13624K Jan 28 22:55:54 sibermusic kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Jan 28 22:55:54 sibermusic kernel: usbcore: registered new device driver r8152-cfgselector Jan 28 22:55:54 sibermusic kernel: usbcore: registered new interface driver r8152 Jan 28 22:55:54 sibermusic kernel: usbcore: registered new interface driver lan78xx Jan 28 22:55:54 sibermusic kernel: usbcore: registered new interface driver smsc95xx Jan 28 22:55:54 sibermusic kernel: xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142) Jan 28 22:55:54 sibermusic kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Jan 28 22:55:54 sibermusic kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Jan 28 22:55:54 sibermusic kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0b00040000000890 Jan 28 22:55:54 sibermusic kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Jan 28 22:55:54 sibermusic kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Jan 28 22:55:54 sibermusic kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Jan 28 22:55:54 sibermusic kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 Jan 28 22:55:54 sibermusic kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 28 22:55:54 sibermusic kernel: usb usb1: Product: xHCI Host Controller Jan 28 22:55:54 sibermusic kernel: usb usb1: Manufacturer: Linux 6.1.70-v7l+ xhci-hcd Jan 28 22:55:54 sibermusic kernel: usb usb1: SerialNumber: 0000:01:00.0 Jan 28 22:55:54 sibermusic kernel: hub 1-0:1.0: USB hub found Jan 28 22:55:54 sibermusic kernel: hub 1-0:1.0: 1 port detected Jan 28 22:55:54 sibermusic kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.01 Jan 28 22:55:54 sibermusic kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jan 28 22:55:54 sibermusic kernel: usb usb2: Product: xHCI Host Controller Jan 28 22:55:54 sibermusic kernel: usb usb2: Manufacturer: Linux 6.1.70-v7l+ xhci-hcd Jan 28 22:55:54 sibermusic kernel: usb usb2: SerialNumber: 0000:01:00.0 Jan 28 22:55:54 sibermusic kernel: hub 2-0:1.0: USB hub found Jan 28 22:55:54 sibermusic kernel: hub 2-0:1.0: 4 ports detected Jan 28 22:55:54 sibermusic kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Jan 28 22:55:54 sibermusic kernel: dwc_otg: FIQ enabled Jan 28 22:55:54 sibermusic kernel: dwc_otg: NAK holdoff enabled Jan 28 22:55:54 sibermusic kernel: dwc_otg: FIQ split-transaction FSM enabled Jan 28 22:55:54 sibermusic kernel: Module dwc_common_port init Jan 28 22:55:54 sibermusic kernel: usbcore: registered new interface driver uas Jan 28 22:55:54 sibermusic kernel: usbcore: registered new interface driver usb-storage Jan 28 22:55:54 sibermusic kernel: mousedev: PS/2 mouse device common for all mice Jan 28 22:55:54 sibermusic kernel: sdhci: Secure Digital Host Controller Interface driver Jan 28 22:55:54 sibermusic kernel: sdhci: Copyright(c) Pierre Ossman Jan 28 22:55:54 sibermusic kernel: sdhci-pltfm: SDHCI platform and OF driver helper Jan 28 22:55:54 sibermusic kernel: ledtrig-cpu: registered to indicate activity on CPUs Jan 28 22:55:54 sibermusic kernel: hid: raw HID events driver (C) Jiri Kosina Jan 28 22:55:54 sibermusic kernel: usbcore: registered new interface driver usbhid Jan 28 22:55:54 sibermusic kernel: usbhid: USB HID core driver Jan 28 22:55:54 sibermusic kernel: Initializing XFRM netlink socket Jan 28 22:55:54 sibermusic kernel: NET: Registered PF_PACKET protocol family Jan 28 22:55:54 sibermusic kernel: Key type dns_resolver registered Jan 28 22:55:54 sibermusic kernel: Registering SWP/SWPB emulation handler Jan 28 22:55:54 sibermusic kernel: registered taskstats version 1 Jan 28 22:55:54 sibermusic kernel: Loading compiled-in X.509 certificates Jan 28 22:55:54 sibermusic kernel: Key type .fscrypt registered Jan 28 22:55:54 sibermusic kernel: Key type fscrypt-provisioning registered Jan 28 22:55:54 sibermusic kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Jan 28 22:55:54 sibermusic kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Jan 28 22:55:54 sibermusic kernel: fe201000.serial: ttyAMA1 at MMIO 0xfe201000 (irq = 51, base_baud = 0) is a PL011 rev2 Jan 28 22:55:54 sibermusic kernel: serial serial0: tty port ttyAMA1 registered Jan 28 22:55:54 sibermusic kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Jan 28 22:55:54 sibermusic kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Jan 28 22:55:54 sibermusic kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Jan 28 22:55:54 sibermusic kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Jan 28 22:55:54 sibermusic kernel: of_cfs_init Jan 28 22:55:54 sibermusic kernel: of_cfs_init: OK Jan 28 22:55:54 sibermusic kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Jan 28 22:55:54 sibermusic kernel: Freeing unused kernel image (initmem) memory: 2048K Jan 28 22:55:54 sibermusic kernel: Run /init as init process Jan 28 22:55:54 sibermusic kernel: with arguments: Jan 28 22:55:54 sibermusic kernel: /init Jan 28 22:55:54 sibermusic kernel: splash Jan 28 22:55:54 sibermusic kernel: with environment: Jan 28 22:55:54 sibermusic kernel: HOME=/ Jan 28 22:55:54 sibermusic kernel: TERM=linux Jan 28 22:55:54 sibermusic kernel: imgpart=/dev/mmcblk0p2 Jan 28 22:55:54 sibermusic kernel: imgfile=/volumio_current.sqsh Jan 28 22:55:54 sibermusic kernel: bootdelay=5 Jan 28 22:55:54 sibermusic kernel: mmc1: new high speed SDIO card at address 0001 Jan 28 22:55:54 sibermusic kernel: mmc0: new ultra high speed DDR50 SDHC card at address 0007 Jan 28 22:55:54 sibermusic kernel: mmcblk0: mmc0:0007 SD8GB 7.42 GiB Jan 28 22:55:54 sibermusic kernel: mmcblk0: p1 p2 p3 Jan 28 22:55:54 sibermusic kernel: mmcblk0: mmc0:0007 SD8GB 7.42 GiB Jan 28 22:55:54 sibermusic kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Jan 28 22:55:54 sibermusic initramfs: Booting Volumio for BCM2711 Jan 28 22:55:54 sibermusic initramfs: This script mounts rootfs RO with an overlay RW layer. Jan 28 22:55:54 sibermusic kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jan 28 22:55:54 sibermusic initramfs: IMGPART=/dev/mmcblk0p2 Jan 28 22:55:54 sibermusic initramfs: IMGFILE=/volumio_current.sqsh Jan 28 22:55:54 sibermusic initramfs: Boot delay (except first time) will be 5 seconds Jan 28 22:55:54 sibermusic initramfs: /dev/mmcblk0p2: Jan 28 22:55:54 sibermusic kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Jan 28 22:55:54 sibermusic kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Jan 28 22:55:54 sibermusic kernel: usb 1-1: Product: USB2.0 Hub Jan 28 22:55:54 sibermusic kernel: hub 1-1:1.0: USB hub found Jan 28 22:55:54 sibermusic kernel: hub 1-1:1.0: 4 ports detected Jan 28 22:55:54 sibermusic kernel: usb 1-1.2: new high-speed USB device number 3 using xhci_hcd Jan 28 22:55:54 sibermusic kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.04 Jan 28 22:55:54 sibermusic kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jan 28 22:55:54 sibermusic kernel: usb 1-1.2: Product: D10s Jan 28 22:55:54 sibermusic kernel: usb 1-1.2: Manufacturer: Topping Jan 28 22:55:54 sibermusic kernel: EXT4-fs (mmcblk0p2): recovery complete Jan 28 22:55:54 sibermusic kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Jan 28 22:55:54 sibermusic initramfs: Doing a 5 second delay here to give kernel load a headstart Jan 28 22:55:54 sibermusic initramfs: Checking for USB updates Jan 28 22:55:54 sibermusic initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Jan 28 22:55:54 sibermusic kernel: loop0: detected capacity change from 0 to 1001120 Jan 28 22:55:54 sibermusic kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jan 28 22:55:54 sibermusic kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jan 28 22:55:54 sibermusic kernel: EXT4-fs (mmcblk0p3): recovery complete Jan 28 22:55:54 sibermusic kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Jan 28 22:55:54 sibermusic initramfs: With Option: Jan 28 22:55:54 sibermusic initramfs: VOLUMIO_VERSION="3.601" Jan 28 22:55:54 sibermusic initramfs: Finish initramfs, continue booting Volumio Jan 28 22:55:54 sibermusic systemd[1]: System time before build time, advancing clock. Jan 28 22:55:54 sibermusic kernel: NET: Registered PF_INET6 protocol family Jan 28 22:55:54 sibermusic kernel: Segment Routing with IPv6 Jan 28 22:55:54 sibermusic kernel: In-situ OAM (IOAM) with IPv6 Jan 28 22:55:54 sibermusic systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Jan 28 22:55:54 sibermusic systemd[1]: Detected architecture arm. Jan 28 22:55:54 sibermusic systemd[1]: Set hostname to . Jan 28 22:55:54 sibermusic systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 28 22:55:54 sibermusic systemd[1]: Listening on fsck to fsckd communication Socket. Jan 28 22:55:54 sibermusic systemd[1]: Listening on Journal Socket (/dev/log). Jan 28 22:55:54 sibermusic systemd[1]: Listening on udev Kernel Socket. Jan 28 22:55:54 sibermusic systemd[1]: Created slice system-getty.slice. Jan 28 22:55:54 sibermusic systemd[1]: Listening on Journal Socket. Jan 28 22:55:54 sibermusic systemd[1]: Mounting Kernel Debug File System... Jan 28 22:55:54 sibermusic kernel: i2c_dev: i2c /dev entries driver Jan 28 22:55:54 sibermusic systemd-journald[207]: Journal started Jan 28 22:55:54 sibermusic systemd-journald[207]: Runtime journal (/run/log/journal/4ceeebcde4a5f04b591e77826532825c) is 3.7M, max 30.0M, 26.2M free. Jan 28 22:55:54 sibermusic systemd-modules-load[197]: Inserted module 'i2c_dev' Jan 28 22:55:54 sibermusic fake-hwclock[199]: Sun Jan 28 21:55:54 UTC 2024 Jan 28 22:55:54 sibermusic systemd[1]: dynamicswap.service: Succeeded. Jan 28 22:55:54 sibermusic systemd[1]: Started Remount Root and Kernel File Systems. Jan 28 22:55:54 sibermusic systemd[1]: Started Apply Kernel Variables. Jan 28 22:55:54 sibermusic systemd[1]: Starting Create System Users... Jan 28 22:55:54 sibermusic systemd[1]: Starting Load/Save Random Seed... Jan 28 22:55:54 sibermusic systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 28 22:55:54 sibermusic systemd[1]: Started Load/Save Random Seed. Jan 28 22:55:54 sibermusic systemd[1]: Started Create System Users. Jan 28 22:55:54 sibermusic systemd[1]: Starting Create Static Device Nodes in /dev... Jan 28 22:55:54 sibermusic systemd[1]: Started udev Coldplug all Devices. Jan 28 22:55:54 sibermusic systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jan 28 22:55:54 sibermusic systemd[1]: Started Create Static Device Nodes in /dev. Jan 28 22:55:54 sibermusic systemd[1]: Starting udev Kernel Device Manager... Jan 28 22:55:54 sibermusic systemd[1]: Reached target Local File Systems (Pre). Jan 28 22:55:54 sibermusic systemd[1]: Mounting /var/spool/cups... Jan 28 22:55:54 sibermusic systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jan 28 22:55:54 sibermusic systemd[1]: Mounting /tmp... Jan 28 22:55:54 sibermusic systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jan 28 22:55:54 sibermusic systemd[1]: Mounting /var/log... Jan 28 22:55:54 sibermusic systemd[1]: Started Helper to synchronize boot up for ifupdown. Jan 28 22:55:54 sibermusic systemd[1]: Mounted /var/spool/cups. Jan 28 22:55:54 sibermusic systemd[1]: Mounted /tmp. Jan 28 22:55:54 sibermusic systemd[1]: Mounted /var/log. Jan 28 22:55:54 sibermusic systemd[1]: Starting Flush Journal to Persistent Storage... Jan 28 22:55:54 sibermusic systemd-udevd[231]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jan 28 22:55:54 sibermusic systemd[1]: Mounting /var/spool/cups/tmp... Jan 28 22:55:54 sibermusic systemd[1]: Mounted /var/spool/cups/tmp. Jan 28 22:55:54 sibermusic systemd-journald[207]: Runtime journal (/run/log/journal/4ceeebcde4a5f04b591e77826532825c) is 3.7M, max 30.0M, 26.2M free. Jan 28 22:55:54 sibermusic systemd[1]: Started Flush Journal to Persistent Storage. Jan 28 22:55:54 sibermusic systemd[1]: Started udev Kernel Device Manager. Jan 28 22:55:54 sibermusic systemd[1]: Starting Show Plymouth Boot Screen... Jan 28 22:55:54 sibermusic systemd[1]: Received SIGRTMIN+20 from PID 257 (plymouthd). Jan 28 22:55:54 sibermusic systemd[1]: Started Show Plymouth Boot Screen. Jan 28 22:55:54 sibermusic systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jan 28 22:55:54 sibermusic systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 28 22:55:54 sibermusic systemd[1]: Reached target Local Encrypted Volumes. Jan 28 22:55:54 sibermusic systemd[1]: Reached target Paths. Jan 28 22:55:54 sibermusic systemd-udevd[252]: Using default interface naming scheme 'v240'. Jan 28 22:55:54 sibermusic systemd-udevd[252]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 28 22:55:54 sibermusic kernel: mc: Linux media interface: v0.10 Jan 28 22:55:54 sibermusic kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jan 28 22:55:55 sibermusic kernel: rpi-gpiomem fe200000.gpiomem: window base 0xfe200000 size 0x00001000 Jan 28 22:55:55 sibermusic kernel: rpi-gpiomem fe200000.gpiomem: initialised 1 regions as /dev/gpiomem Jan 28 22:55:55 sibermusic kernel: videodev: Linux video capture interface: v2.00 Jan 28 22:55:55 sibermusic kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jan 28 22:55:55 sibermusic kernel: [vc_sm_connected_init]: start Jan 28 22:55:55 sibermusic kernel: [vc_sm_connected_init]: installed successfully Jan 28 22:55:55 sibermusic kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 28 22:55:55 sibermusic kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 28 22:55:55 sibermusic kernel: input: raspberrypi-ts as /devices/platform/soc/soc:firmware/soc:firmware:touchscreen/input/input0 Jan 28 22:55:55 sibermusic kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 28 22:55:55 sibermusic kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jan 28 22:55:55 sibermusic kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jan 28 22:55:55 sibermusic kernel: rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. Jan 28 22:55:55 sibermusic kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jan 28 22:55:55 sibermusic kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jan 28 22:55:55 sibermusic kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jan 28 22:55:55 sibermusic kernel: rpivid feb10000.codec: Device registered as /dev/video19 Jan 28 22:55:55 sibermusic kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jan 28 22:55:55 sibermusic kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jan 28 22:55:55 sibermusic kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jan 28 22:55:55 sibermusic kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jan 28 22:55:55 sibermusic kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jan 28 22:55:55 sibermusic kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jan 28 22:55:55 sibermusic kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 28 22:55:55 sibermusic kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 28 22:55:55 sibermusic kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 28 22:55:55 sibermusic kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 28 22:55:55 sibermusic kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jan 28 22:55:55 sibermusic kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jan 28 22:55:55 sibermusic kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jan 28 22:55:55 sibermusic kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jan 28 22:55:55 sibermusic kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 28 22:55:55 sibermusic kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 28 22:55:55 sibermusic kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 28 22:55:55 sibermusic kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 28 22:55:55 sibermusic kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jan 28 22:55:55 sibermusic systemd-udevd[244]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 28 22:55:55 sibermusic kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jan 28 22:55:55 sibermusic kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jan 28 22:55:55 sibermusic kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jan 28 22:55:55 sibermusic kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jan 28 22:55:55 sibermusic kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Jan 28 22:55:55 sibermusic kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Jan 28 22:55:55 sibermusic kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jan 28 22:55:55 sibermusic kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jan 28 22:55:55 sibermusic kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jan 28 22:55:55 sibermusic kernel: cfg80211: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jan 28 22:55:55 sibermusic kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jan 28 22:55:56 sibermusic kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jan 28 22:55:56 sibermusic kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Jan 28 22:55:56 sibermusic kernel: bcm2835_audio bcm2835_audio: card created with 8 channels Jan 28 22:55:56 sibermusic kernel: Bluetooth: Core ver 2.22 Jan 28 22:55:56 sibermusic kernel: NET: Registered PF_BLUETOOTH protocol family Jan 28 22:55:56 sibermusic kernel: Bluetooth: HCI device and connection manager initialized Jan 28 22:55:56 sibermusic kernel: Bluetooth: HCI socket layer initialized Jan 28 22:55:56 sibermusic kernel: Bluetooth: L2CAP socket layer initialized Jan 28 22:55:56 sibermusic kernel: Bluetooth: SCO socket layer initialized Jan 28 22:55:56 sibermusic kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Jan 28 22:55:56 sibermusic kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jan 28 22:55:56 sibermusic kernel: usbcore: registered new interface driver brcmfmac Jan 28 22:55:56 sibermusic kernel: Bluetooth: HCI UART driver ver 2.3 Jan 28 22:55:56 sibermusic kernel: Bluetooth: HCI UART protocol H4 registered Jan 28 22:55:56 sibermusic kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jan 28 22:55:56 sibermusic kernel: Bluetooth: HCI UART protocol Broadcom registered Jan 28 22:55:56 sibermusic kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jan 28 22:55:56 sibermusic kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jan 28 22:55:56 sibermusic kernel: uart-pl011 fe201000.serial: no DMA platform data Jan 28 22:55:56 sibermusic systemd[1]: Found device /dev/mmcblk0p1. Jan 28 22:55:56 sibermusic kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Jan 28 22:55:56 sibermusic kernel: Bluetooth: hci0: BCM: chip id 107 Jan 28 22:55:56 sibermusic kernel: Bluetooth: hci0: BCM: features 0x2f Jan 28 22:55:56 sibermusic kernel: Bluetooth: hci0: BCM4345C0 Jan 28 22:55:56 sibermusic kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 Jan 28 22:55:56 sibermusic systemd-udevd[238]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 28 22:55:56 sibermusic kernel: Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch Jan 28 22:55:56 sibermusic systemd-udevd[238]: Using default interface naming scheme 'v240'. Jan 28 22:55:56 sibermusic systemd-udevd[238]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 28 22:55:56 sibermusic kernel: usb 1-1.2: 1:3 : unsupported format bits 0x100000000 Jan 28 22:55:56 sibermusic kernel: usbcore: registered new interface driver snd-usb-audio Jan 28 22:55:56 sibermusic systemd-udevd[245]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 28 22:55:57 sibermusic kernel: Bluetooth: hci0: BCM: features 0x2f Jan 28 22:55:57 sibermusic kernel: Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+ Jan 28 22:55:57 sibermusic kernel: Bluetooth: hci0: BCM4345C0 (003.001.025) build 0342 Jan 28 22:55:58 sibermusic systemd-udevd[254]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Jan 28 22:55:58 sibermusic systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Jan 28 22:55:58 sibermusic systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Jan 28 22:55:58 sibermusic systemd[1]: Created slice system-bthelper.slice. Jan 28 22:55:58 sibermusic systemd[1]: Condition check resulted in FUSE Control File System being skipped. Jan 28 22:55:58 sibermusic systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 28 22:55:58 sibermusic systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jan 28 22:55:58 sibermusic systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 28 22:55:58 sibermusic systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jan 28 22:55:58 sibermusic systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jan 28 22:55:58 sibermusic systemd[1]: Created slice system-systemd\x2dbacklight.slice. Jan 28 22:55:58 sibermusic systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:rpi_backlight... Jan 28 22:55:58 sibermusic systemd[1]: Starting File System Check on /dev/mmcblk0p1... Jan 28 22:55:58 sibermusic systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 28 22:55:58 sibermusic systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:rpi_backlight. Jan 28 22:55:58 sibermusic systemd[1]: Started File System Check Daemon to report status. Jan 28 22:55:58 sibermusic systemd[1]: Started Load/Save RF Kill Switch Status. Jan 28 22:55:58 sibermusic systemd-fsck[396]: fsck.fat 4.1 (2017-01-24) Jan 28 22:55:58 sibermusic systemd-fsck[396]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jan 28 22:55:58 sibermusic systemd-fsck[396]: Automatically removing dirty bit. Jan 28 22:55:58 sibermusic systemd-fsck[396]: Performing changes. Jan 28 22:55:58 sibermusic systemd-fsck[396]: /dev/mmcblk0p1: 367 files, 33302/46774 clusters Jan 28 22:55:58 sibermusic systemd[1]: Started File System Check on /dev/mmcblk0p1. Jan 28 22:55:58 sibermusic systemd[1]: Mounting /boot... Jan 28 22:55:58 sibermusic systemd[1]: Mounted /boot. Jan 28 22:55:58 sibermusic systemd[1]: Reached target Local File Systems. Jan 28 22:55:58 sibermusic systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jan 28 22:55:58 sibermusic systemd[1]: Started ifup for eth0. Jan 28 22:55:58 sibermusic systemd[1]: Starting Raise network interfaces... Jan 28 22:55:58 sibermusic systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jan 28 22:55:59 sibermusic systemd[1]: Started ifup for wlan0. Jan 28 22:55:59 sibermusic systemd[1]: Starting Create Volatile Files and Directories... Jan 28 22:55:59 sibermusic systemd[1]: Starting Preprocess NFS configuration... Jan 28 22:55:59 sibermusic systemd[1]: plymouth-read-write.service: Succeeded. Jan 28 22:55:59 sibermusic systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jan 28 22:55:59 sibermusic systemd[1]: Received SIGRTMIN+20 from PID 257 (plymouthd). Jan 28 22:55:59 sibermusic systemd[1]: nfs-config.service: Succeeded. Jan 28 22:55:59 sibermusic systemd[1]: Started Preprocess NFS configuration. Jan 28 22:55:59 sibermusic systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jan 28 22:55:59 sibermusic systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jan 28 22:55:59 sibermusic systemd[1]: Reached target NFS client services. Jan 28 22:55:59 sibermusic systemd[1]: Started Create Volatile Files and Directories. Jan 28 22:55:59 sibermusic systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jan 28 22:55:59 sibermusic systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jan 28 22:55:59 sibermusic systemd[1]: Starting RPC bind portmap service... Jan 28 22:55:59 sibermusic systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jan 28 22:55:59 sibermusic systemd[1]: Started Update UTMP about System Boot/Shutdown. Jan 28 22:55:59 sibermusic systemd[1]: Reached target System Initialization. Jan 28 22:55:59 sibermusic systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 28 22:55:59 sibermusic kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 28 22:55:59 sibermusic systemd[1]: apt-daily.timer: Not using persistent file timestamp Tue 2024-01-30 11:56:17 CET as it is in the future. Jan 28 22:55:59 sibermusic systemd[1]: Started Daily apt download activities. Jan 28 22:55:59 sibermusic systemd[1]: Listening on lircd.socket. Jan 28 22:55:59 sibermusic systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jan 28 22:55:59 sibermusic systemd[1]: Listening on triggerhappy.socket. Jan 28 22:55:59 sibermusic kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 28 22:55:59 sibermusic systemd[1]: Listening on D-Bus System Message Bus Socket. Jan 28 22:55:59 sibermusic systemd[1]: Reached target Sockets. Jan 28 22:55:59 sibermusic systemd[1]: Reached target Basic System. Jan 28 22:55:59 sibermusic systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jan 28 22:55:59 sibermusic systemd[1]: Started D-Bus System Message Bus. Jan 28 22:55:59 sibermusic systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jan 28 22:55:59 sibermusic systemd[1]: Starting Login Service... Jan 28 22:55:59 sibermusic systemd[1]: Starting triggerhappy global hotkey daemon... Jan 28 22:55:59 sibermusic systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jan 28 22:55:59 sibermusic systemd[1]: Starting Wireless Services... Jan 28 22:55:59 sibermusic systemd[1]: Started Manage Sound Card State (restore and store). Jan 28 22:55:59 sibermusic systemd[1]: Starting Save/Restore Sound Card State... Jan 28 22:55:59 sibermusic systemd[1]: Started UPnP Renderer front-end to MPD. Jan 28 22:55:59 sibermusic alsactl[523]: alsactl 1.1.8 daemon started Jan 28 22:55:59 sibermusic systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 28 22:55:59 sibermusic systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jan 28 22:55:59 sibermusic systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Tue 2024-01-30 06:12:01 CET as it is in the future. Jan 28 22:55:59 sibermusic systemd[1]: Started Daily apt upgrade and clean activities. Jan 28 22:55:59 sibermusic systemd[1]: Starting WPA supplicant... Jan 28 22:55:59 sibermusic systemd[1]: man-db.timer: Not using persistent file timestamp Tue 2024-01-30 00:01:07 CET as it is in the future. Jan 28 22:55:59 sibermusic systemd[1]: Started Daily man-db regeneration. Jan 28 22:55:59 sibermusic systemd[1]: Reached target Timers. Jan 28 22:55:59 sibermusic systemd[1]: Started volumio-remote-updater.service. Jan 28 22:55:59 sibermusic systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jan 28 22:55:59 sibermusic systemd[1]: Starting dhcpcd on all interfaces... Jan 28 22:55:59 sibermusic systemd[1]: Started Volumio Iptables Module. Jan 28 22:55:59 sibermusic systemd[1]: Started RPC bind portmap service. Jan 28 22:55:59 sibermusic systemd[1]: hciuart.service: Succeeded. Jan 28 22:55:59 sibermusic systemd[1]: Started Configure Bluetooth Modems connected by UART. Jan 28 22:55:59 sibermusic systemd[1]: Starting Raspberry Pi bluetooth helper... Jan 28 22:55:59 sibermusic systemd[1]: Reached target Remote File Systems (Pre). Jan 28 22:55:59 sibermusic systemd[1]: Reached target Remote File Systems. Jan 28 22:55:59 sibermusic systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jan 28 22:55:59 sibermusic systemd[1]: Reached target RPC Port Mapper. Jan 28 22:56:00 sibermusic sh[408]: wlan0=wlan0 Jan 28 22:56:00 sibermusic dhcpcd[531]: Not running dhcpcd because /etc/network/interfaces Jan 28 22:56:00 sibermusic dhcpcd[531]: defines some interfaces that will use a Jan 28 22:56:00 sibermusic dhcpcd[531]: DHCP client or static address Jan 28 22:56:00 sibermusic thd[513]: Unable to parse trigger line: Jan 28 22:56:00 sibermusic thd[513]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jan 28 22:56:00 sibermusic thd[513]: Unable to parse trigger line: Jan 28 22:56:00 sibermusic thd[513]: Found socket passed from systemd Jan 28 22:56:00 sibermusic sh[405]: eth0: waiting for carrier Jan 28 22:56:00 sibermusic dhcpcd[455]: eth0: waiting for carrier Jan 28 22:56:00 sibermusic kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Jan 28 22:56:00 sibermusic kernel: bcmgenet fd580000.ethernet eth0: Link is Down Jan 28 22:56:00 sibermusic systemd-logind[507]: New seat seat0. Jan 28 22:56:00 sibermusic bthelper[533]: Raspberry Pi BDADDR already set Jan 28 22:56:00 sibermusic avahi-daemon[528]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jan 28 22:56:00 sibermusic avahi-daemon[528]: Successfully dropped root privileges. Jan 28 22:56:00 sibermusic avahi-daemon[528]: avahi-daemon 0.7 starting up. Jan 28 22:56:00 sibermusic volumio-remote-updater[530]: Error: No active session Jan 28 22:56:00 sibermusic volumio-remote-updater[530]: [2024-01-28 22:56:00] [info] asio async_connect error: system:111 (Connection refused) Jan 28 22:56:00 sibermusic volumio-remote-updater[530]: [2024-01-28 22:56:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 28 22:56:00 sibermusic volumio-remote-updater[530]: [2024-01-28 22:56:00] [error] handle_connect error: Underlying Transport Error Jan 28 22:56:00 sibermusic systemd[1]: Started Login Service. Jan 28 22:56:00 sibermusic systemd[1]: Started triggerhappy global hotkey daemon. Jan 28 22:56:00 sibermusic systemd[1]: Started Save/Restore Sound Card State. Jan 28 22:56:00 sibermusic systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jan 28 22:56:00 sibermusic systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jan 28 22:56:00 sibermusic systemd[1]: Failed to start dhcpcd on all interfaces. Jan 28 22:56:00 sibermusic avahi-daemon[528]: Successfully called chroot(). Jan 28 22:56:00 sibermusic avahi-daemon[528]: Successfully dropped remaining capabilities. Jan 28 22:56:00 sibermusic systemd[1]: Started Raspberry Pi bluetooth helper. Jan 28 22:56:00 sibermusic avahi-daemon[528]: Loading service file /services/volumio.service. Jan 28 22:56:00 sibermusic wpa_supplicant[529]: Successfully initialized wpa_supplicant Jan 28 22:56:00 sibermusic avahi-daemon[528]: Network interface enumeration completed. Jan 28 22:56:00 sibermusic avahi-daemon[528]: Server startup complete. Host name is sibermusic.local. Local service cookie is 1427307831. Jan 28 22:56:00 sibermusic avahi-daemon[528]: Service "sibermusic" (/services/volumio.service) successfully established. Jan 28 22:56:00 sibermusic systemd[1]: Started Raise network interfaces. Jan 28 22:56:01 sibermusic volumio[526]: Could not open config: /tmp/upmpdcli.conf Jan 28 22:56:01 sibermusic systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:56:01 sibermusic systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 28 22:56:01 sibermusic systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 28 22:56:01 sibermusic systemd[1]: Started WPA supplicant. Jan 28 22:56:01 sibermusic systemd[1]: Starting Bluetooth service... Jan 28 22:56:01 sibermusic systemd[1]: Reached target Network. Jan 28 22:56:01 sibermusic systemd[1]: Starting Network Time Service... Jan 28 22:56:01 sibermusic systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jan 28 22:56:01 sibermusic systemd[1]: Reached target Network is Online. Jan 28 22:56:01 sibermusic systemd[1]: Starting /etc/rc.local Compatibility... Jan 28 22:56:01 sibermusic systemd[1]: Starting LSB: Brings up/down network automatically... Jan 28 22:56:01 sibermusic systemd[1]: Starting Permit User Sessions... Jan 28 22:56:01 sibermusic systemd[1]: Starting OpenBSD Secure Shell server... Jan 28 22:56:01 sibermusic systemd[1]: Starting Samba NMB Daemon... Jan 28 22:56:01 sibermusic systemd[1]: Reached target Sound Card. Jan 28 22:56:01 sibermusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 28 22:56:01 sibermusic systemd[1]: Started Permit User Sessions. Jan 28 22:56:01 sibermusic bluetoothd[590]: Bluetooth daemon 5.50 Jan 28 22:56:01 sibermusic loadcpufreq[534]: Loading cpufreq kernel modules...done (none). Jan 28 22:56:01 sibermusic systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 28 22:56:01 sibermusic bluetoothd[590]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 28 22:56:01 sibermusic systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 28 22:56:01 sibermusic systemd[1]: Started Bluetooth service. Jan 28 22:56:01 sibermusic systemd[1]: Reached target Bluetooth. Jan 28 22:56:01 sibermusic bluetoothd[590]: Starting SDP server Jan 28 22:56:01 sibermusic bluetoothd[590]: Excluding (cli) sap Jan 28 22:56:01 sibermusic ifplugd(eth0)[649]: ifplugd 0.28 initializing. Jan 28 22:56:01 sibermusic ifplugd(eth0)[649]: Using interface eth0/DC:A6:32:6C:7A:FC with driver (version: 6.1.70-v7l+) Jan 28 22:56:01 sibermusic ifplugd(eth0)[649]: Using detection mode: SIOCETHTOOL Jan 28 22:56:01 sibermusic ifplugd(eth0)[649]: Initialization complete, link beat not detected. Jan 28 22:56:01 sibermusic ifplugd[597]: Network Interface Plugging Daemon...start eth0...done. Jan 28 22:56:01 sibermusic ntpd[595]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 28 22:56:01 sibermusic ntpd[595]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jan 28 22:56:01 sibermusic ntpd[654]: proto: precision = 1.981 usec (-19) Jan 28 22:56:01 sibermusic systemd[1]: Started Network Time Service. Jan 28 22:56:01 sibermusic systemd[1]: Started LSB: Brings up/down network automatically. Jan 28 22:56:01 sibermusic ntpd[654]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 28 22:56:01 sibermusic ntpd[654]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 28 22:56:01 sibermusic ntpd[654]: Listen and drop on 0 v6wildcard [::]:123 Jan 28 22:56:01 sibermusic ntpd[654]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 28 22:56:01 sibermusic ntpd[654]: Listen normally on 2 lo 127.0.0.1:123 Jan 28 22:56:01 sibermusic ntpd[654]: Listening on routing socket on fd #19 for interface updates Jan 28 22:56:01 sibermusic ntpd[654]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 28 22:56:01 sibermusic ntpd[654]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 28 22:56:01 sibermusic systemd[1]: iptables.service: Succeeded. Jan 28 22:56:01 sibermusic kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 28 22:56:01 sibermusic kernel: Bluetooth: BNEP filters: protocol multicast Jan 28 22:56:01 sibermusic kernel: Bluetooth: BNEP socket layer initialized Jan 28 22:56:01 sibermusic bluetoothd[590]: Bluetooth management interface 1.22 initialized Jan 28 22:56:01 sibermusic dbus-daemon[483]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=590 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 28 22:56:01 sibermusic sshd[652]: Server listening on 0.0.0.0 port 22. Jan 28 22:56:01 sibermusic sshd[652]: Server listening on :: port 22. Jan 28 22:56:02 sibermusic kernel: Bluetooth: MGMT ver 1.22 Jan 28 22:56:02 sibermusic cpufrequtils[636]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jan 28 22:56:02 sibermusic bluetoothd[590]: Failed to set privacy: Rejected (0x0b) Jan 28 22:56:02 sibermusic sudo[604]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh -c echo 0 > /sys/class/leds/PWR/brightness Jan 28 22:56:02 sibermusic sudo[604]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:02 sibermusic systemd[1]: Started OpenBSD Secure Shell server. Jan 28 22:56:02 sibermusic systemd[1]: Started LSB: set CPUFreq kernel parameters. Jan 28 22:56:02 sibermusic sudo[604]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:02 sibermusic systemd[1]: Starting Hostname Service... Jan 28 22:56:02 sibermusic systemd[1]: Started /etc/rc.local Compatibility. Jan 28 22:56:02 sibermusic systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 28 22:56:02 sibermusic systemd[1]: Starting Hold until boot process finishes up... Jan 28 22:56:02 sibermusic systemd[1]: Received SIGRTMIN+21 from PID 257 (plymouthd). Jan 28 22:56:02 sibermusic systemd[1]: Received SIGRTMIN+21 from PID 257 (plymouthd). Jan 28 22:56:02 sibermusic systemd[1]: plymouth-quit.service: Succeeded. Jan 28 22:56:02 sibermusic systemd[1]: Started Terminate Plymouth Boot Screen. Jan 28 22:56:02 sibermusic systemd[1]: plymouth-start.service: Succeeded. Jan 28 22:56:02 sibermusic systemd[1]: plymouth-quit-wait.service: Succeeded. Jan 28 22:56:02 sibermusic systemd[1]: Started Hold until boot process finishes up. Jan 28 22:56:02 sibermusic systemd[1]: Started Getty on tty1. Jan 28 22:56:02 sibermusic systemd[1]: Reached target Login Prompts. Jan 28 22:56:02 sibermusic dbus-daemon[483]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 28 22:56:02 sibermusic systemd[1]: Started Hostname Service. Jan 28 22:56:02 sibermusic haveged[443]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jan 28 22:56:02 sibermusic haveged[443]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jan 28 22:56:02 sibermusic haveged[443]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99827 Jan 28 22:56:02 sibermusic haveged[443]: haveged: fills: 0, generated: 0 Jan 28 22:56:02 sibermusic ntpd[654]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 28 22:56:02 sibermusic kernel: new mount options do not match the existing superblock, will be ignored Jan 28 22:56:02 sibermusic nmbd[626]: [2024/01/28 22:56:02.936584, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 28 22:56:02 sibermusic nmbd[626]: started asyncdns process 682 Jan 28 22:56:02 sibermusic nmbd[626]: [2024/01/28 22:56:02.938567, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 28 22:56:02 sibermusic nmbd[626]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 28 22:56:02 sibermusic nmbd[626]: [2024/01/28 22:56:02.938709, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 28 22:56:02 sibermusic nmbd[626]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 28 22:56:03 sibermusic wireless.js[518]: Cleaning previous... Jan 28 22:56:03 sibermusic volumio[515]: cset: --> shielding system active with Jan 28 22:56:03 sibermusic volumio[515]: cset: "system" cpuset of CPUSPEC(2-3) with 75 tasks running Jan 28 22:56:03 sibermusic volumio[515]: cset: "user" cpuset of CPUSPEC(0-1) with 0 tasks running Jan 28 22:56:03 sibermusic systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jan 28 22:56:03 sibermusic sudo[700]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 28 22:56:03 sibermusic sudo[700]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:03 sibermusic sudo[700]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:03 sibermusic sudo[702]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 28 22:56:03 sibermusic sudo[702]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:03 sibermusic systemd[1]: systemd-rfkill.service: Succeeded. Jan 28 22:56:03 sibermusic sudo[702]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:03 sibermusic wireless.js[518]: Stopped aP Jan 28 22:56:03 sibermusic ntpd[654]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 28 22:56:03 sibermusic sudo[709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 28 22:56:03 sibermusic sudo[709]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:03 sibermusic sudo[709]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:03 sibermusic kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 28 22:56:03 sibermusic sudo[711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 28 22:56:03 sibermusic sudo[711]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:03 sibermusic sudo[711]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:03 sibermusic sudo[718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 28 22:56:03 sibermusic sudo[718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:03 sibermusic sudo[718]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:03 sibermusic sudo[720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 28 22:56:03 sibermusic sudo[720]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:04 sibermusic ntpd[654]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 28 22:56:05 sibermusic bthelper[533]: Changing power off succeeded Jan 28 22:56:05 sibermusic bthelper[533]: [CHG] Controller DC:A6:32:6C:7A:FE Class: 0x0000041c Jan 28 22:56:05 sibermusic bthelper[533]: Changing power on succeeded Jan 28 22:56:05 sibermusic volumio-remote-updater[530]: [2024-01-28 22:56:05] [info] asio async_connect error: system:111 (Connection refused) Jan 28 22:56:05 sibermusic volumio-remote-updater[530]: [2024-01-28 22:56:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 28 22:56:05 sibermusic volumio-remote-updater[530]: [2024-01-28 22:56:05] [error] handle_connect error: Underlying Transport Error Jan 28 22:56:05 sibermusic ntpd[654]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 28 22:56:06 sibermusic sudo[720]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:06 sibermusic wireless.js[518]: SETTING APPROPRIATE REG DOMAIN: EU Jan 28 22:56:06 sibermusic sudo[731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 28 22:56:06 sibermusic sudo[731]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:06 sibermusic sudo[731]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:06 sibermusic sudo[733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set EU Jan 28 22:56:06 sibermusic sudo[733]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:06 sibermusic sudo[733]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:06 sibermusic wireless.js[518]: SUCCESSFULLY SET NEW REGDOMAIN: EU Jan 28 22:56:06 sibermusic wireless.js[518]: Start wireless flow Jan 28 22:56:06 sibermusic wireless.js[518]: Stopped hotspot (if there).. Jan 28 22:56:06 sibermusic systemd-udevd[238]: Process '/sbin/crda' failed with exit code 255. Jan 28 22:56:06 sibermusic sudo[739]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 28 22:56:06 sibermusic sudo[739]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:06 sibermusic sudo[739]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:06 sibermusic sudo[741]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 28 22:56:06 sibermusic sudo[741]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:07 sibermusic sudo[741]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:07 sibermusic wireless.js[518]: DHCP IP Jan 28 22:56:07 sibermusic wireless.js[518]: Start ap Jan 28 22:56:07 sibermusic wpa_supplicant[743]: Successfully initialized wpa_supplicant Jan 28 22:56:07 sibermusic sudo[744]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jan 28 22:56:07 sibermusic sudo[744]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:07 sibermusic kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 28 22:56:07 sibermusic dhcpcd[745]: dev: loaded udev Jan 28 22:56:07 sibermusic dhcpcd-run-hooks[763]: wlan0: starting wpa_supplicant Jan 28 22:56:07 sibermusic dhcpcd-run-hooks[768]: wlan0: failed to start wpa_supplicant Jan 28 22:56:07 sibermusic dhcpcd-run-hooks[769]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Jan 28 22:56:07 sibermusic dhcpcd[745]: wlan0: connected to Access Point `' Jan 28 22:56:07 sibermusic dhcpcd[745]: no interfaces have a carrier Jan 28 22:56:07 sibermusic dhcpcd[745]: forked to background, child pid 779 Jan 28 22:56:07 sibermusic dhcpcd[779]: eth0: waiting for carrier Jan 28 22:56:07 sibermusic dhcpcd[779]: wlan0: waiting for carrier Jan 28 22:56:07 sibermusic sudo[744]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:08 sibermusic wireless.js[518]: trying... Jan 28 22:56:08 sibermusic sudo[781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 22:56:08 sibermusic sudo[781]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:08 sibermusic sudo[781]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:09 sibermusic wireless.js[518]: trying... Jan 28 22:56:09 sibermusic sudo[784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 22:56:09 sibermusic sudo[784]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:09 sibermusic sudo[784]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:09 sibermusic wpa_supplicant[767]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jan 28 22:56:09 sibermusic wpa_supplicant[767]: wlan0: Trying to associate with SSID 'Ziggy' Jan 28 22:56:10 sibermusic wireless.js[518]: trying... Jan 28 22:56:10 sibermusic sudo[787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 22:56:10 sibermusic sudo[787]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:10 sibermusic sudo[787]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:11 sibermusic wireless.js[518]: trying... Jan 28 22:56:11 sibermusic sudo[790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 22:56:11 sibermusic sudo[790]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:11 sibermusic sudo[790]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:12 sibermusic wireless.js[518]: trying... Jan 28 22:56:12 sibermusic sudo[793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 22:56:12 sibermusic sudo[793]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:12 sibermusic sudo[793]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:12 sibermusic dhcpcd[779]: wlan0: carrier acquired Jan 28 22:56:12 sibermusic dhcpcd[779]: wlan0: connected to Access Point `Ziggy' Jan 28 22:56:12 sibermusic wpa_supplicant[767]: wlan0: Associated with ac:22:05:46:79:f1 Jan 28 22:56:12 sibermusic wpa_supplicant[767]: wlan0: CTRL-EVENT-CONNECTED - Connection to ac:22:05:46:79:f1 completed [id=0 id_str=] Jan 28 22:56:12 sibermusic wpa_supplicant[767]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 28 22:56:12 sibermusic dhcpcd[779]: DUID 00:01:00:01:2c:c5:41:6b:e4:5f:01:7c:b5:70 Jan 28 22:56:12 sibermusic dhcpcd[779]: wlan0: IAID 32:6c:7a:fd Jan 28 22:56:12 sibermusic dhcpcd[779]: wlan0: adding address fe80::fcef:8611:26df:870e Jan 28 22:56:12 sibermusic dhcpcd[779]: ipv6_addaddr1: Permission denied Jan 28 22:56:12 sibermusic dhcpcd[779]: wlan0: carrier lost Jan 28 22:56:12 sibermusic systemd-udevd[796]: Process '/sbin/crda' failed with exit code 255. Jan 28 22:56:12 sibermusic dhcpcd[779]: wlan0: carrier acquired Jan 28 22:56:12 sibermusic dhcpcd[779]: wlan0: IAID 32:6c:7a:fd Jan 28 22:56:13 sibermusic dhcpcd[779]: wlan0: rebinding lease of 192.168.178.227 Jan 28 22:56:13 sibermusic wireless.js[518]: trying... Jan 28 22:56:13 sibermusic volumio-remote-updater[530]: [2024-01-28 22:56:13] [info] asio async_connect error: system:111 (Connection refused) Jan 28 22:56:13 sibermusic volumio-remote-updater[530]: [2024-01-28 22:56:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 28 22:56:13 sibermusic volumio-remote-updater[530]: [2024-01-28 22:56:13] [error] handle_connect error: Underlying Transport Error Jan 28 22:56:13 sibermusic sudo[810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 22:56:13 sibermusic sudo[810]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:13 sibermusic sudo[810]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:13 sibermusic dhcpcd[779]: wlan0: probing address 192.168.178.227/24 Jan 28 22:56:13 sibermusic dhcpcd[779]: wlan0: soliciting an IPv6 router Jan 28 22:56:14 sibermusic wireless.js[518]: trying... Jan 28 22:56:14 sibermusic sudo[813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 22:56:14 sibermusic sudo[813]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:14 sibermusic sudo[813]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:15 sibermusic wireless.js[518]: trying... Jan 28 22:56:15 sibermusic sudo[816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 22:56:15 sibermusic sudo[816]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:15 sibermusic sudo[816]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:15 sibermusic wpa_supplicant[767]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jan 28 22:56:16 sibermusic wireless.js[518]: trying... Jan 28 22:56:16 sibermusic sudo[819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 22:56:16 sibermusic sudo[819]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:16 sibermusic sudo[819]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:17 sibermusic wireless.js[518]: trying... Jan 28 22:56:17 sibermusic sudo[822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 22:56:17 sibermusic sudo[822]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:17 sibermusic sudo[822]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:17 sibermusic dhcpcd[779]: wlan0: leased 192.168.178.227 for 604800 seconds Jan 28 22:56:17 sibermusic avahi-daemon[528]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.227. Jan 28 22:56:17 sibermusic avahi-daemon[528]: New relevant interface wlan0.IPv4 for mDNS. Jan 28 22:56:17 sibermusic dhcpcd[779]: wlan0: adding route to 192.168.178.0/24 Jan 28 22:56:17 sibermusic avahi-daemon[528]: Registering new address record for 192.168.178.227 on wlan0.IPv4. Jan 28 22:56:17 sibermusic dhcpcd[779]: wlan0: adding default route via 192.168.178.1 Jan 28 22:56:17 sibermusic systemd[1]: Started Samba NMB Daemon. Jan 28 22:56:17 sibermusic nmbd[626]: [2024/01/28 22:56:17.947396, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 28 22:56:17 sibermusic nmbd[626]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 28 22:56:17 sibermusic nmbd[626]: [2024/01/28 22:56:17.969543, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 28 22:56:17 sibermusic nmbd[626]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.227 for name WORKGROUP<1d>. Jan 28 22:56:17 sibermusic nmbd[626]: This response was from IP 192.168.178.80, reporting an IP address of 192.168.178.80. Jan 28 22:56:18 sibermusic systemd[1]: Starting Samba Winbind Daemon... Jan 28 22:56:18 sibermusic wireless.js[518]: trying... Jan 28 22:56:18 sibermusic sudo[848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 28 22:56:18 sibermusic sudo[848]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:18 sibermusic sudo[848]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:18 sibermusic wireless.js[518]: Connected to: ----Ziggy Jan 28 22:56:18 sibermusic wireless.js[518]: ---- Jan 28 22:56:18 sibermusic sudo[851]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 22:56:18 sibermusic sudo[851]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:18 sibermusic sudo[851]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:18 sibermusic wireless.js[518]: ... joined AP, wlan0 IPv4 is 192.168.178.227, ipV6 is undefined Jan 28 22:56:18 sibermusic wireless.js[518]: It's done! AP Jan 28 22:56:18 sibermusic winbindd[845]: [2024/01/28 22:56:18.264668, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 28 22:56:18 sibermusic winbindd[845]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 28 22:56:18 sibermusic systemd[1]: Started Samba Winbind Daemon. Jan 28 22:56:18 sibermusic winbindd[845]: [2024/01/28 22:56:18.309227, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 28 22:56:18 sibermusic winbindd[845]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 28 22:56:18 sibermusic systemd[1]: Starting Samba SMB Daemon... Jan 28 22:56:18 sibermusic systemd[1]: Started Wireless Services. Jan 28 22:56:18 sibermusic kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 28 22:56:18 sibermusic systemd[1]: Started Volumio Backend Module. Jan 28 22:56:18 sibermusic systemd[1]: Started Volumio Cpu Tweaker. Jan 28 22:56:18 sibermusic volumio-cpu-tweak[860]: Setting RT Priority for mpd Jan 28 22:56:18 sibermusic volumio-cpu-tweak[860]: pid 35's current scheduling policy: SCHED_OTHER Jan 28 22:56:18 sibermusic volumio-cpu-tweak[860]: pid 35's current scheduling priority: 0 Jan 28 22:56:18 sibermusic volumio-cpu-tweak[860]: Setting MPD Affinity Jan 28 22:56:18 sibermusic volumio-cpu-tweak[860]: pid 3's current affinity mask: f Jan 28 22:56:18 sibermusic volumio-cpu-tweak[860]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jan 28 22:56:18 sibermusic volumio-cpu-tweak[860]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jan 28 22:56:18 sibermusic systemd[1]: volumio_cpu_tweak.service: Succeeded. Jan 28 22:56:18 sibermusic winbindd[845]: [2024/01/28 22:56:18.701496, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Jan 28 22:56:18 sibermusic winbindd[845]: res_names->count = 3, expected 4 Jan 28 22:56:18 sibermusic ntpd[654]: Listen normally on 3 wlan0 192.168.178.227:123 Jan 28 22:56:18 sibermusic ntpd[654]: new interface(s) found: waking up resolver Jan 28 22:56:19 sibermusic smbd[861]: [2024/01/28 22:56:19.765585, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 28 22:56:19 sibermusic systemd[1]: Started Samba SMB Daemon. Jan 28 22:56:19 sibermusic systemd[1]: Reached target Multi-User System. Jan 28 22:56:19 sibermusic systemd[1]: Reached target Graphical Interface. Jan 28 22:56:19 sibermusic smbd[861]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 28 22:56:19 sibermusic systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 28 22:56:19 sibermusic systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 28 22:56:19 sibermusic systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 28 22:56:19 sibermusic systemd[1]: Startup finished in 11.335s (kernel) + 27.475s (userspace) = 38.811s. Jan 28 22:56:21 sibermusic volumio[859]: info: ------------------------------------------- Jan 28 22:56:21 sibermusic volumio[859]: info: ----- Volumio3 ---- Jan 28 22:56:21 sibermusic volumio[859]: info: ------------------------------------------- Jan 28 22:56:21 sibermusic volumio[859]: info: ----- System startup ---- Jan 28 22:56:21 sibermusic volumio[859]: info: ------------------------------------------- Jan 28 22:56:21 sibermusic volumio[859]: info: MYVOLUMIO Environment detected Jan 28 22:56:22 sibermusic volumio[859]: info: Plugin folders cleanup Jan 28 22:56:22 sibermusic volumio[859]: info: Scanning into folder /volumio/app/plugins/ Jan 28 22:56:22 sibermusic volumio[859]: info: Scanning category audio_interface Jan 28 22:56:22 sibermusic volumio[859]: info: Scanning category miscellanea Jan 28 22:56:22 sibermusic volumio[859]: info: Scanning category music_service Jan 28 22:56:22 sibermusic volumio[859]: info: Scanning category plugins.json Jan 28 22:56:22 sibermusic volumio[859]: info: Scanning category system_controller Jan 28 22:56:22 sibermusic volumio[859]: info: Scanning category user_interface Jan 28 22:56:22 sibermusic volumio[859]: info: Scanning into folder /data/plugins/ Jan 28 22:56:22 sibermusic volumio[859]: info: Scanning category music_service Jan 28 22:56:22 sibermusic volumio[859]: info: Scanning category system_hardware Jan 28 22:56:22 sibermusic volumio[859]: info: Scanning category user_interface Jan 28 22:56:22 sibermusic volumio[859]: info: Plugin folders cleanup completed Jan 28 22:56:22 sibermusic volumio[859]: info: ------------------------------------------- Jan 28 22:56:22 sibermusic volumio[859]: info: ----- Core plugins startup ---- Jan 28 22:56:22 sibermusic volumio[859]: info: ------------------------------------------- Jan 28 22:56:22 sibermusic volumio[859]: info: Loading plugins from folder /volumio/app/plugins/ Jan 28 22:56:22 sibermusic volumio[859]: info: Adding plugin upnp to MyMusic Plugins Jan 28 22:56:22 sibermusic volumio[859]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 28 22:56:22 sibermusic volumio[859]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 28 22:56:22 sibermusic volumio[859]: info: Loading plugins from folder /data/plugins/ Jan 28 22:56:22 sibermusic volumio[859]: info: Loading plugin "system"... Jan 28 22:56:22 sibermusic volumio[859]: info: Loading plugin "appearance"... Jan 28 22:56:23 sibermusic volumio[859]: info: Loading plugin "network"... Jan 28 22:56:23 sibermusic volumio[859]: info: Refreshing Cached IP Addresses Jan 28 22:56:23 sibermusic sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 22:56:23 sibermusic sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:23 sibermusic sudo[896]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:23 sibermusic volumio[859]: info: Loading plugin "services"... Jan 28 22:56:23 sibermusic volumio[859]: info: Loading plugin "alsa_controller"... Jan 28 22:56:23 sibermusic sudo[901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 28 22:56:23 sibermusic sudo[901]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:23 sibermusic sudo[900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 22:56:23 sibermusic sudo[900]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:23 sibermusic volumio-remote-updater[530]: [2024-01-28 22:56:23] [connect] Successful connection Jan 28 22:56:23 sibermusic sudo[900]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:23 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 22:56:23 sibermusic volumio[859]: info: Loading plugin "wizard"... Jan 28 22:56:23 sibermusic volumio[859]: info: Loading plugin "networkfs"... Jan 28 22:56:23 sibermusic volumio[859]: info: Starting Udev Watcher for removable devices Jan 28 22:56:23 sibermusic sudo[910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.10/sibernas /mnt/NAS/sibernas Jan 28 22:56:23 sibermusic sudo[910]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:23 sibermusic kernel: Key type cifs.spnego registered Jan 28 22:56:23 sibermusic kernel: Key type cifs.idmap registered Jan 28 22:56:23 sibermusic kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jan 28 22:56:23 sibermusic kernel: CIFS: Attempting to mount \\192.168.178.10\sibernas Jan 28 22:56:23 sibermusic volumio[859]: info: Ignoring mount for partition: boot Jan 28 22:56:23 sibermusic volumio[859]: info: Ignoring mount for partition: volumio Jan 28 22:56:23 sibermusic volumio[859]: info: Ignoring mount for partition: volumio_data Jan 28 22:56:23 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:56:23 sibermusic volumio[859]: info: Loading plugin "volumio_command_line_client"... Jan 28 22:56:23 sibermusic volumio[859]: info: Loading plugin "upnp"... Jan 28 22:56:23 sibermusic volumio[859]: info: [1706478983703] Starting Upmpd Daemon Jan 28 22:56:23 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:56:23 sibermusic volumio[859]: info: Loading plugin "my_music"... Jan 28 22:56:23 sibermusic volumio[859]: info: Loading plugin "mpd"... Jan 28 22:56:24 sibermusic sudo[910]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:24 sibermusic volumio[859]: info: Loading plugin "upnp_browser"... Jan 28 22:56:24 sibermusic volumio[859]: info: Loading plugin "alarm-clock"... Jan 28 22:56:24 sibermusic volumio[859]: info: Plugin airplay_emulation is not enabled Jan 28 22:56:24 sibermusic volumio[859]: info: Loading plugin "last_100"... Jan 28 22:56:24 sibermusic volumio[859]: info: Loading plugin "webradio"... Jan 28 22:56:24 sibermusic volumio[859]: info: Loading plugin "i2s_dacs"... Jan 28 22:56:24 sibermusic volumio[859]: info: I2S DAC not set, start Auto-detection Jan 28 22:56:24 sibermusic volumio[859]: info: Loading plugin "volumiodiscovery"... Jan 28 22:56:24 sibermusic volumio[859]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 22:56:24 sibermusic volumio[859]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:56:24 sibermusic node[859]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 28 22:56:24 sibermusic volumio[859]: *** WARNING *** For more information see Jan 28 22:56:24 sibermusic volumio[859]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 22:56:24 sibermusic volumio[859]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:56:24 sibermusic volumio[859]: *** WARNING *** For more information see Jan 28 22:56:24 sibermusic node[859]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:56:24 sibermusic node[859]: *** WARNING *** For more information see Jan 28 22:56:24 sibermusic node[859]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 28 22:56:24 sibermusic node[859]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 28 22:56:24 sibermusic node[859]: *** WARNING *** For more information see Jan 28 22:56:24 sibermusic volumio[859]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 28 22:56:24 sibermusic volumio[859]: info: Discovery: Started advertising with name: sibermusic Jan 28 22:56:24 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 28 22:56:24 sibermusic volumio[859]: info: Loading plugin "bandcamp"... Jan 28 22:56:26 sibermusic sudo[901]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:28 sibermusic systemd[1]: systemd-fsckd.service: Succeeded. Jan 28 22:56:28 sibermusic volumio[859]: info: Loading plugin "spop"... Jan 28 22:56:30 sibermusic volumio[859]: info: Loading plugin "ytmusic"... Jan 28 22:56:30 sibermusic sh[405]: timed out Jan 28 22:56:30 sibermusic dhcpcd[455]: timed out Jan 28 22:56:30 sibermusic sh[405]: dhcpcd exited Jan 28 22:56:30 sibermusic dhcpcd[455]: dhcpcd exited Jan 28 22:56:30 sibermusic sh[405]: ifup: failed to bring up eth0 Jan 28 22:56:30 sibermusic systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jan 28 22:56:30 sibermusic systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jan 28 22:56:31 sibermusic volumio[859]: info: Loading plugin "now_playing"... Jan 28 22:56:32 sibermusic volumio[859]: info: Loading plugin "outputs"... Jan 28 22:56:32 sibermusic volumio[859]: info: Loading plugin "albumart"... Jan 28 22:56:32 sibermusic volumio[859]: info: Plugin example_plugin is not enabled Jan 28 22:56:32 sibermusic volumio[859]: info: Loading plugin "inputs"... Jan 28 22:56:32 sibermusic volumio[859]: info: Loading plugin "updater_comm"... Jan 28 22:56:32 sibermusic systemd[1]: systemd-hostnamed.service: Succeeded. Jan 28 22:56:32 sibermusic volumio[859]: info: Plugin mpdemulation is not enabled Jan 28 22:56:32 sibermusic volumio[859]: info: Loading plugin "rest_api"... Jan 28 22:56:32 sibermusic volumio[859]: info: Loading plugin "websocket"... Jan 28 22:56:32 sibermusic volumio[859]: info: Loading plugin "ir_controller"... Jan 28 22:56:32 sibermusic volumio[859]: Forking 3 albumart workers Jan 28 22:56:32 sibermusic volumio[859]: info: Applying required configuration parameters for plugin ir_controller Jan 28 22:56:33 sibermusic volumio[859]: info: Loading plugin "music_services_shield"... Jan 28 22:56:33 sibermusic volumio[859]: info: Applying required configuration parameters for plugin music_services_shield Jan 28 22:56:33 sibermusic volumio[859]: info: Loading plugin "touch_display"... Jan 28 22:56:33 sibermusic volumio[859]: Starting albumart workers Jan 28 22:56:33 sibermusic volumio[859]: Starting albumart workers Jan 28 22:56:33 sibermusic volumio[859]: Starting albumart workers Jan 28 22:56:33 sibermusic volumio[859]: info: Applying required configuration parameters for plugin touch_display Jan 28 22:56:34 sibermusic volumio[859]: info: Loading i18n strings for locale en Jan 28 22:56:34 sibermusic volumio[859]: Updating browse sources language Jan 28 22:56:34 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:56:34 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:56:34 sibermusic volumio[859]: info: CoreCommandRouter::initPlayerControls Jan 28 22:56:34 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:34 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:34 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:34 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:34 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:34 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:34 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:34 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:34 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:56:34 sibermusic volumio[859]: Express server listening on port 3000 Jan 28 22:56:34 sibermusic volumio[859]: [Metrics] WebUI: 13s 903.24ms Jan 28 22:56:34 sibermusic volumio[859]: info: CoreStateMachine::resetVolumioState Jan 28 22:56:34 sibermusic volumio[859]: info: CoreStateMachine::getcurrentVolume Jan 28 22:56:34 sibermusic volumio[859]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:56:34 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:56:34 sibermusic sudo[997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 22:56:34 sibermusic sudo[997]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:34 sibermusic sudo[997]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:34 sibermusic sudo[1000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 22:56:34 sibermusic sudo[1000]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:34 sibermusic sudo[1000]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:35 sibermusic volumio[859]: verbose: New Socket.io Connection to 192.168.178.227:3000 from 192.168.178.73 UA: node-XMLHttpRequest Total Clients: 1 Jan 28 22:56:35 sibermusic volumio[859]: verbose: New Socket.io Connection to 192.168.178.227:3000 from 192.168.178.73 UA: node-XMLHttpRequest Total Clients: 2 Jan 28 22:56:35 sibermusic volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Jan 28 22:56:35 sibermusic volumio[859]: info: VolumeController:: Volume=73 Mute =false Jan 28 22:56:35 sibermusic volumio[859]: info: CoreStateMachine::pushState Jan 28 22:56:35 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::volumioPushState Jan 28 22:56:35 sibermusic volumio[859]: info: CoreStateMachine::updateTrackBlock Jan 28 22:56:35 sibermusic volumio[859]: info: CorePlayQueue::getTrackBlock Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 22:56:35 sibermusic volumio[859]: info: Reloading queue from file Jan 28 22:56:35 sibermusic volumio[859]: info: Setting Device type: Raspberry PI Jan 28 22:56:35 sibermusic volumio[859]: info: CoreStateMachine::setRepeat false single undefined Jan 28 22:56:35 sibermusic volumio[859]: info: CoreStateMachine::pushState Jan 28 22:56:35 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::volumioPushState Jan 28 22:56:35 sibermusic volumio[859]: info: CoreStateMachine::setRandom false Jan 28 22:56:35 sibermusic volumio[859]: info: CoreStateMachine::pushState Jan 28 22:56:35 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::volumioPushState Jan 28 22:56:35 sibermusic sudo[1012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 28 22:56:35 sibermusic sudo[1012]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 28 22:56:35 sibermusic volumio[859]: info: VolumeController:: Volume=73 Mute =false Jan 28 22:56:35 sibermusic volumio[859]: info: CoreStateMachine::pushState Jan 28 22:56:35 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::volumioPushState Jan 28 22:56:35 sibermusic volumio[859]: info: Discovery: adding 411a939b-97a5-4cdf-b005-bdba7cc60c5a Jan 28 22:56:35 sibermusic volumio[859]: info: Discovery: Found device sibermusic Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 28 22:56:35 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 28 22:56:35 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 28 22:56:35 sibermusic systemd[1]: Started UPnP Renderer front-end to MPD. Jan 28 22:56:35 sibermusic volumio[859]: info: Completed loading Core Plugins Jan 28 22:56:35 sibermusic volumio[859]: info: Preparing to generate the ALSA configuration file Jan 28 22:56:35 sibermusic sudo[1012]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:35 sibermusic volumio[859]: info: Upmpdcli Daemon Started Jan 28 22:56:35 sibermusic volumio[859]: info: Discovery: adding bead4742-cfbb-4ddb-b635-1868495b0e00 Jan 28 22:56:35 sibermusic volumio[859]: info: Discovery: Found device siberup Jan 28 22:56:35 sibermusic volumio[859]: info: Discovery: Connecting to remote: 192.168.178.73 Jan 28 22:56:35 sibermusic volumio[859]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Jan 28 22:56:35 sibermusic volumio[859]: info: Asound.conf file written Jan 28 22:56:35 sibermusic sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 28 22:56:35 sibermusic sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:35 sibermusic sudo[1016]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:35 sibermusic volumio[859]: info: Output device has changed, restarting MPD Jan 28 22:56:35 sibermusic sudo[1021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 22:56:35 sibermusic sudo[1021]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:35 sibermusic volumio[859]: info: ___________ START PLUGINS ___________ Jan 28 22:56:35 sibermusic sudo[1021]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:35 sibermusic volumio[859]: info: ControllerMpd::onStart: Initializing MPD Jan 28 22:56:35 sibermusic volumio[859]: info: Creating MPD Configuration file Jan 28 22:56:35 sibermusic sudo[1024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 22:56:35 sibermusic sudo[1024]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:35 sibermusic systemd[1]: musicservicesshield.service: Succeeded. Jan 28 22:56:35 sibermusic systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jan 28 22:56:35 sibermusic systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:56:35 sibermusic volumio[859]: info: [1706478995668] CoreMusicLibrary::Adding element Media Servers Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:56:35 sibermusic sudo[1028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 22:56:35 sibermusic sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:56:35 sibermusic volumio[859]: info: [1706478995684] CoreMusicLibrary::Adding element Last_100 Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:56:35 sibermusic volumio[859]: info: [1706478995686] CoreMusicLibrary::Adding element Webradio Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:56:35 sibermusic volumio[859]: info: [1706478995702] CoreMusicLibrary::Adding element Bandcamp Discover Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:56:35 sibermusic volumio[859]: Cannot find translation for source Bandcamp Discover Jan 28 22:56:35 sibermusic volumio[859]: info: Creating Spotify config file Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:35 sibermusic sudo[1029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 22:56:35 sibermusic sudo[1029]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:35 sibermusic sudo[1028]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:35 sibermusic systemd[1]: Listening on mpd.socket. Jan 28 22:56:35 sibermusic systemd[1]: Starting Music Player Daemon... Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:56:35 sibermusic volumio[859]: info: [1706478995764] CoreMusicLibrary::Adding element YouTube Music Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:56:35 sibermusic volumio[859]: Cannot find translation for source Bandcamp Discover Jan 28 22:56:35 sibermusic volumio[859]: Cannot find translation for source YouTube Music Jan 28 22:56:35 sibermusic systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 28 22:56:35 sibermusic systemd[1]: mpd.service: Succeeded. Jan 28 22:56:35 sibermusic systemd[1]: Stopped Music Player Daemon. Jan 28 22:56:35 sibermusic systemd[1]: Starting Music Player Daemon... Jan 28 22:56:35 sibermusic volumio[859]: info: [now-playing] ConfigUpdater: config is up to date. Jan 28 22:56:35 sibermusic volumio[859]: info: Loading i18n strings for locale en Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 22:56:35 sibermusic sudo[1034]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 22:56:35 sibermusic sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:35 sibermusic sudo[1034]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jan 28 22:56:35 sibermusic sudo[1034]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:35 sibermusic volumio[859]: info: Loading i18n strings for locale en Jan 28 22:56:35 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 22:56:35 sibermusic volumio[859]: info: Volumio Calling Home Jan 28 22:56:36 sibermusic sudo[1063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Jan 28 22:56:36 sibermusic sudo[1063]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:36 sibermusic sudo[1081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 28 22:56:36 sibermusic sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:36 sibermusic systemd[1]: Reloading. Jan 28 22:56:36 sibermusic sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Jan 28 22:56:36 sibermusic sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:36 sibermusic sudo[1080]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:36 sibermusic volumio[859]: info: [now-playing] Fetching API key... Jan 28 22:56:36 sibermusic volumio[859]: info: [now-playing] App is listening on port 4004. Jan 28 22:56:36 sibermusic volumio[859]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jan 28 22:56:36 sibermusic volumio[859]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Jan 28 22:56:36 sibermusic volumio[859]: info: MPD Permissions set Jan 28 22:56:36 sibermusic volumio[859]: info: MPD Permissions set Jan 28 22:56:36 sibermusic volumio[859]: info: Volumio called home Jan 28 22:56:36 sibermusic volumio[859]: info: Spotify config file written Jan 28 22:56:36 sibermusic volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Jan 28 22:56:36 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:36 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:36 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:36 sibermusic sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 28 22:56:36 sibermusic sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:36 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:56:36 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:36 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:36 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:36 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:36 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:36 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:36 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:36 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:36 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:36 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:56:36 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:36 sibermusic systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 28 22:56:36 sibermusic volumio[859]: info: Discovery: Connected to remote: 192.168.178.73 Jan 28 22:56:36 sibermusic volumio[859]: info: touch_display: Raspberry Pi Foundation touch screen detected. Jan 28 22:56:36 sibermusic volumio[859]: info: touch_display: Backlight module of a Raspberry Pi Foundation touch screen detected. Jan 28 22:56:36 sibermusic sudo[1063]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:36 sibermusic systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jan 28 22:56:36 sibermusic sudo[1122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Jan 28 22:56:36 sibermusic sudo[1122]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:37 sibermusic sudo[1081]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:37 sibermusic volumio[859]: info: ir_controller: LIRC correctly updated. Jan 28 22:56:37 sibermusic volumio[859]: info: ir_controller: HAT did not load /proc/device-tree/ir_receiver! Jan 28 22:56:37 sibermusic volumio[859]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 28 22:56:37 sibermusic volumio[859]: SPOTIFY: BQDvN8N1i7ok9mm5FQ92SYvTUX4XritWeXMIwMxEtAJ2v1HtaVrgNqjc8z9NuMQd809Wr4B6fxm6XsKqoqQ4R2Pwp_tLCT3FCrv6yZq-7V0_4B232BHcrrep3n8WlYYG3FF7wYMaoLdaONtUZM9hRgsX1RH6dvGSctrXZ3gRMIoUOtqx5tiIJpF6r6udfIIkUb02Xq4G1-7dMB8xSjBocUwnXQqC1qzMVeT7-ze-K5VcnR9ieKDAFGGlABLlxkTVpHtGAmXjgOU Jan 28 22:56:37 sibermusic volumio[859]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 28 22:56:37 sibermusic volumio[859]: info: New Spotify access token = BQDvN8N1i7ok9mm5FQ92SYvTUX4XritWeXMIwMxEtAJ2v1HtaVrgNqjc8z9NuMQd809Wr4B6fxm6XsKqoqQ4R2Pwp_tLCT3FCrv6yZq-7V0_4B232BHcrrep3n8WlYYG3FF7wYMaoLdaONtUZM9hRgsX1RH6dvGSctrXZ3gRMIoUOtqx5tiIJpF6r6udfIIkUb02Xq4G1-7dMB8xSjBocUwnXQqC1qzMVeT7-ze-K5VcnR9ieKDAFGGlABLlxkTVpHtGAmXjgOU Jan 28 22:56:37 sibermusic volumio[859]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 28 22:56:37 sibermusic go-librespot[1127]: Librespot-go daemon starting... Jan 28 22:56:37 sibermusic systemd[1]: Started go-librespot Daemon. Jan 28 22:56:37 sibermusic volumio[859]: info: touch_display: systemctl daemon-reload succeeded. Jan 28 22:56:37 sibermusic sudo[1101]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:37 sibermusic sudo[1133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/devices/platform/rpi_backlight/backlight/rpi_backlight/brightness Jan 28 22:56:37 sibermusic volumio[859]: info: ir_controller: Raspberry Pi revision code: c03112 Jan 28 22:56:37 sibermusic sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:37 sibermusic sudo[1133]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:37 sibermusic sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jan 28 22:56:37 sibermusic sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:37 sibermusic systemd[1]: Started Volumio Kiosk. Jan 28 22:56:37 sibermusic sudo[1132]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:37 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 28 22:56:37 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 28 22:56:37 sibermusic volumio[859]: info: touch_display: File permissions for backlight brightness control set. Jan 28 22:56:37 sibermusic volumio[859]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jan 28 22:56:37 sibermusic volumio[859]: info: touch_display: Volumio Kiosk started Jan 28 22:56:37 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 28 22:56:37 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 28 22:56:37 sibermusic mpd[1044]: Jan 28 22:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 22:56:37 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 28 22:56:37 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 28 22:56:37 sibermusic volumio[859]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 28 22:56:37 sibermusic volumio[859]: SPOTIFY: User informations: {"display_name":"sibermusic","external_urls":{"spotify":"https://open.spotify.com/user/31oqpqhlnukeslyt3pinp75emawe"},"href":"https://api.spotify.com/v1/users/31oqpqhlnukeslyt3pinp75emawe","id":"31oqpqhlnukeslyt3pinp75emawe","images":[{"url":"https://i.scdn.co/image/ab67757000003b8268686fe646192cee39d908f9","height":64,"width":64},{"url":"https://i.scdn.co/image/ab6775700000ee8568686fe646192cee39d908f9","height":300,"width":300}],"type":"user","uri":"spotify:user:31oqpqhlnukeslyt3pinp75emawe","followers":{"href":null,"total":0},"country":"NL","product":"premium","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"gee.siber@gmail.com"} Jan 28 22:56:37 sibermusic volumio[859]: info: Spotify Successfully logged in Jan 28 22:56:37 sibermusic sudo[1168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r -1 Jan 28 22:56:37 sibermusic volumio[859]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 22:56:37 sibermusic volumio[859]: info: [1706478997993] CoreMusicLibrary::Adding element Spotify Jan 28 22:56:37 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:56:37 sibermusic volumio[859]: Cannot find translation for source Bandcamp Discover Jan 28 22:56:37 sibermusic volumio[859]: Cannot find translation for source YouTube Music Jan 28 22:56:37 sibermusic volumio[859]: Cannot find translation for source Spotify Jan 28 22:56:37 sibermusic sudo[1168]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:38 sibermusic sudo[1168]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:38 sibermusic systemd[1]: Started Music Player Daemon. Jan 28 22:56:38 sibermusic systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jan 28 22:56:38 sibermusic sudo[1024]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:38 sibermusic sudo[1029]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:38 sibermusic volumio[859]: info: Completed starting Core Plugins Jan 28 22:56:38 sibermusic volumio[859]: info: ------------------------------------------- Jan 28 22:56:38 sibermusic volumio[859]: info: ----- MyVolumio plugins startup ---- Jan 28 22:56:38 sibermusic volumio[859]: info: ------------------------------------------- Jan 28 22:56:38 sibermusic volumio[859]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 28 22:56:38 sibermusic volumio-remote-updater[530]: [2024-01-28 22:56:38] [connect] Successful connection Jan 28 22:56:38 sibermusic startx[1138]: X.Org X Server 1.20.4 Jan 28 22:56:38 sibermusic startx[1138]: X Protocol Version 11, Revision 0 Jan 28 22:56:38 sibermusic startx[1138]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jan 28 22:56:38 sibermusic startx[1138]: Current Operating System: Linux sibermusic 6.1.70-v7l+ #1712 SMP Tue Jan 2 18:29:06 GMT 2024 armv7l Jan 28 22:56:38 sibermusic startx[1138]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:6C:7A:FC 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=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop 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 Jan 28 22:56:38 sibermusic startx[1138]: Build Date: 04 April 2023 07:50:56AM Jan 28 22:56:38 sibermusic startx[1138]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jan 28 22:56:38 sibermusic startx[1138]: Current version of pixman: 0.36.0 Jan 28 22:56:38 sibermusic startx[1138]: Before reporting problems, check http://wiki.x.org Jan 28 22:56:38 sibermusic startx[1138]: to make sure that you have the latest version. Jan 28 22:56:38 sibermusic startx[1138]: Markers: (--) probed, (**) from config file, (==) default setting, Jan 28 22:56:38 sibermusic startx[1138]: (++) from command line, (!!) notice, (II) informational, Jan 28 22:56:38 sibermusic startx[1138]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jan 28 22:56:38 sibermusic startx[1138]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Jan 28 22:56:38 2024 Jan 28 22:56:38 sibermusic sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-ir gpio_pin=25 gpio_pull=up Jan 28 22:56:38 sibermusic startx[1138]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jan 28 22:56:38 sibermusic startx[1138]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jan 28 22:56:38 sibermusic sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:38 sibermusic volumio[859]: info: MPD running with PID1044 Jan 28 22:56:38 sibermusic volumio[859]: ,establishing connection Jan 28 22:56:38 sibermusic volumio[859]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 28 22:56:38 sibermusic go-librespot[1127]: time="2024-01-28T22:56:38+01:00" level=info msg="generated new device id: 02f67be98da341fc9fdcf52d7e6302f9ecd5e18e" Jan 28 22:56:38 sibermusic go-librespot[1127]: time="2024-01-28T22:56:38+01:00" level=debug msg="stored credentials found for 31oqpqhlnukeslyt3pinp75emawe" Jan 28 22:56:38 sibermusic kernel: Registered IR keymap rc-rc6-mce Jan 28 22:56:38 sibermusic kernel: IR RC6 protocol handler initialized Jan 28 22:56:38 sibermusic volumio[859]: error: MPD error: The expression evaluated to a falsy value: Jan 28 22:56:38 sibermusic volumio[859]: assert.ok(self.idling) Jan 28 22:56:38 sibermusic volumio[859]: error: The expression evaluated to a falsy value: Jan 28 22:56:38 sibermusic volumio[859]: assert.ok(self.idling) Jan 28 22:56:38 sibermusic volumio[859]: error: MPD error: The expression evaluated to a falsy value: Jan 28 22:56:38 sibermusic volumio[859]: assert.ok(self.idling) Jan 28 22:56:38 sibermusic volumio[859]: error: The expression evaluated to a falsy value: Jan 28 22:56:38 sibermusic volumio[859]: assert.ok(self.idling) Jan 28 22:56:38 sibermusic volumio-remote-updater[530]: [2024-01-28 22:56:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1706478998 101 Jan 28 22:56:38 sibermusic sudo[1187]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:38 sibermusic kernel: rc rc0: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0 Jan 28 22:56:38 sibermusic kernel: rc rc0: lirc_dev: driver gpio_ir_recv registered at minor = 0, raw IR receiver, no transmitter Jan 28 22:56:38 sibermusic kernel: input: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0/input1 Jan 28 22:56:38 sibermusic volumio[859]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 5 Jan 28 22:56:38 sibermusic kernel: rc rc0: two consecutive events of type space Jan 28 22:56:38 sibermusic volumio[859]: info: ir_controller: Overlay gpio-ir gpio_pin=25 gpio_pull=up loaded. Jan 28 22:56:38 sibermusic sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jan 28 22:56:38 sibermusic sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:38 sibermusic systemd[1]: Starting lircd(8) initialization helper tool... Jan 28 22:56:38 sibermusic volumio[859]: error: updateQueue error: null Jan 28 22:56:38 sibermusic volumio[859]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 28 22:56:38 sibermusic sudo[1227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jan 28 22:56:38 sibermusic sudo[1227]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:38 sibermusic systemd-udevd[1202]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 28 22:56:38 sibermusic systemd-logind[507]: Watching system buttons on /dev/input/event1 (gpio_ir_recv) Jan 28 22:56:38 sibermusic volumio[1178]: cset: --> shielding system active with Jan 28 22:56:38 sibermusic volumio[1178]: cset: "system" cpuset of CPUSPEC(2-3) with 119 tasks running Jan 28 22:56:38 sibermusic volumio[1178]: cset: "user" cpuset of CPUSPEC(0-1) with 3 tasks running Jan 28 22:56:38 sibermusic go-librespot[1127]: time="2024-01-28T22:56:38+01:00" level=debug msg="obtained new client token: AADlQoDowmZXm+ld9P5NDu+Y+lZK4g6ver6/Agh0d8+nGHj4GrogNUER3DOEwLFkyu1lnxi0JxrIQmcmPz3PTmK2Xa/RIcdorctm+BrskxCP3K8NLuU06b0qZdRG5bT0dBERlMAqzugU5JM9JmwoCTMOJx4YbdIuXx4UpTnatozgSnhHYboEoKK3CkeONq/YDsHOhZ18VMUGubBEVrTrM8BXmpjiydptVZa3+BbSROoOoAReH+ywmZu2SURHPEk=" Jan 28 22:56:38 sibermusic systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jan 28 22:56:38 sibermusic systemd[1]: Reloading. Jan 28 22:56:39 sibermusic go-librespot[1127]: time="2024-01-28T22:56:39+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 28 22:56:39 sibermusic go-librespot[1127]: time="2024-01-28T22:56:39+01:00" level=debug msg="completed keyexchange" Jan 28 22:56:39 sibermusic volumio[859]: info: [now-playing] OpenWeatherMap API key obtained. Jan 28 22:56:39 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 22:56:39 sibermusic volumio[859]: info: Discovery: Getting this device information Jan 28 22:56:39 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 28 22:56:39 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 28 22:56:39 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 22:56:39 sibermusic go-librespot[1127]: time="2024-01-28T22:56:39+01:00" level=debug msg="completed challenge" Jan 28 22:56:39 sibermusic lircd-setup[1226]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Jan 28 22:56:39 sibermusic lircd-setup[1226]: parser = configparser.SafeConfigParser() Jan 28 22:56:39 sibermusic go-librespot[1127]: time="2024-01-28T22:56:39+01:00" level=debug msg="authenticated as 31oqpqhlnukeslyt3pinp75emawe" Jan 28 22:56:39 sibermusic systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 28 22:56:39 sibermusic volumio[1014]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 28 22:56:39 sibermusic go-librespot[1127]: time="2024-01-28T22:56:39+01:00" level=debug msg="authenticated as 31oqpqhlnukeslyt3pinp75emawe" Jan 28 22:56:39 sibermusic go-librespot[1127]: time="2024-01-28T22:56:39+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 28 22:56:39 sibermusic go-librespot[1127]: time="2024-01-28T22:56:39+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 28 22:56:40 sibermusic go-librespot[1127]: time="2024-01-28T22:56:40+01:00" level=debug msg="dealer connection opened" Jan 28 22:56:40 sibermusic go-librespot[1127]: time="2024-01-28T22:56:40+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 28 22:56:40 sibermusic go-librespot[1127]: time="2024-01-28T22:56:40+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 28 22:56:40 sibermusic go-librespot[1127]: time="2024-01-28T22:56:40+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 28 22:56:40 sibermusic go-librespot[1127]: time="2024-01-28T22:56:40+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 28 22:56:40 sibermusic go-librespot[1127]: time="2024-01-28T22:56:40+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Jan 28 22:56:40 sibermusic go-librespot[1127]: time="2024-01-28T22:56:40+01:00" level=debug msg="received connection id: ZTU4MzE0MzctZTU2Yy00OTliLWJkNWItY2Q1NzYwOWU1MjkwK2RlYWxlcit0Y3A6Ly9nZXc0LWRlYWxlci1hLTBzN3QuZ2V3NC5zcG90aWZ5Lm5ldDo1NzAwK0I3NTM1RkQ4MjRCNDRFMEY2NUJFMTc2Q0U2NkZDNUQ0MTY2MUNCOTQwQjk1NUJCQTZDRENEMTFFNDRFRjBBQjg=" Jan 28 22:56:40 sibermusic go-librespot[1127]: time="2024-01-28T22:56:40+01:00" level=debug msg="put connect state because NEW_DEVICE" Jan 28 22:56:40 sibermusic sudo[1122]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:40 sibermusic systemd[1]: lircd-setup.service: Succeeded. Jan 28 22:56:40 sibermusic volumio[859]: info: succeeded Jan 28 22:56:40 sibermusic systemd[1]: Started lircd(8) initialization helper tool. Jan 28 22:56:40 sibermusic volumio[859]: info: go-librespot daemon successfully initialized Jan 28 22:56:40 sibermusic systemd[1]: Starting Flexible IR remote input/output application support... Jan 28 22:56:40 sibermusic volumio[859]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Info: lircd: Opening log, level: Info Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Version: lircd 0.10.1 Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: System info: Linux sibermusic 6.1.70-v7l+ #1712 SMP Tue Jan 2 18:29:06 GMT 2024 armv7l GNU/Linux Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Info: Initial device: /dev/lirc0 Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Info: Initial device: /dev/lirc0 Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Info: [lirc] protocol is enabled Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Options: driver: default Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Options: output: /var/run/lirc/lircd Jan 28 22:56:40 sibermusic lircd[1341]: lircd-0.10.1[1341]: Info: lircd: Opening log, level: Info Jan 28 22:56:40 sibermusic lircd[1341]: lircd-0.10.1[1341]: Notice: Using systemd fd Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Options: nodaemon: 1 Jan 28 22:56:40 sibermusic lircd[1341]: lircd-0.10.1[1341]: Warning: Running as root Jan 28 22:56:40 sibermusic lircd[1341]: lircd-0.10.1[1341]: Info: Using remote: odroid. Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Jan 28 22:56:40 sibermusic lircd[1341]: lircd-0.10.1[1341]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Options: logfile: syslog Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Options: immediate-init: 0 Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Options: permission: 666 Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Options: driver-options: Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Options: listen: 0 Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Options: connect: (null) Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Options: userelease: 0 Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Options: effective_user: (null) Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Options: release_suffix: _EVUP Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Options: allow_simulate: 0 Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Options: repeat_max: 600 Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Options: configfile: /etc/lirc/lircd.conf Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Options: dynamic_codes: (null) Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Current driver: default Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Driver API version: 3 Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Driver version: 0.10.0 Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Info: lircd: Opening log, level: Info Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: Using systemd fd Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Warning: Running as root Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Info: Using remote: odroid. Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jan 28 22:56:40 sibermusic systemd[1]: Started Flexible IR remote input/output application support. Jan 28 22:56:40 sibermusic sudo[1227]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:40 sibermusic sudo[1220]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:40 sibermusic volumio[859]: info: ir_controller: systemctl restart lircd.service succeeded. Jan 28 22:56:40 sibermusic volumio[859]: info: ir_controller: systemctl restart lircd.service succeeded. Jan 28 22:56:40 sibermusic sudo[1366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jan 28 22:56:40 sibermusic sudo[1366]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:40 sibermusic sudo[1373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jan 28 22:56:40 sibermusic sudo[1373]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 22:56:40 sibermusic systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Jan 28 22:56:40 sibermusic sudo[1366]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:40 sibermusic volumio[859]: info: ir_controller: systemctl restart irexec.service succeeded. Jan 28 22:56:40 sibermusic lircd[1341]: lircd-0.10.1[1341]: Notice: accepted new client on /var/run/lirc/lircd Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Notice: accepted new client on /var/run/lirc/lircd Jan 28 22:56:40 sibermusic lircd[1341]: lircd-0.10.1[1341]: Info: [lirc] protocol is enabled Jan 28 22:56:40 sibermusic lircd-0.10.1[1341]: Info: [lirc] protocol is enabled Jan 28 22:56:41 sibermusic lircd-0.10.1[1341]: Info: removed client Jan 28 22:56:41 sibermusic lircd[1341]: lircd-0.10.1[1341]: Info: removed client Jan 28 22:56:41 sibermusic systemd[1]: Stopping Handle events from IR remotes decoded by lircd(8)... Jan 28 22:56:41 sibermusic systemd[1]: irexec.service: Main process exited, code=killed, status=15/TERM Jan 28 22:56:41 sibermusic systemd[1]: irexec.service: Succeeded. Jan 28 22:56:41 sibermusic systemd[1]: Stopped Handle events from IR remotes decoded by lircd(8). Jan 28 22:56:41 sibermusic systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Jan 28 22:56:41 sibermusic sudo[1373]: pam_unix(sudo:session): session closed for user root Jan 28 22:56:41 sibermusic volumio[859]: info: ir_controller: systemctl restart irexec.service succeeded. Jan 28 22:56:41 sibermusic lircd-0.10.1[1341]: Notice: accepted new client on /var/run/lirc/lircd Jan 28 22:56:41 sibermusic lircd[1341]: lircd-0.10.1[1341]: Notice: accepted new client on /var/run/lirc/lircd Jan 28 22:56:41 sibermusic lircd[1341]: lircd-0.10.1[1341]: Info: [lirc] protocol is enabled Jan 28 22:56:41 sibermusic lircd-0.10.1[1341]: Info: [lirc] protocol is enabled Jan 28 22:56:41 sibermusic volumio[1014]: ..............++++ Jan 28 22:56:43 sibermusic volumio[859]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 28 22:56:43 sibermusic volumio[859]: info: Initializing connection to go-librespot Websocket Jan 28 22:56:43 sibermusic go-librespot[1127]: time="2024-01-28T22:56:43+01:00" level=debug msg="new websocket client" Jan 28 22:56:43 sibermusic volumio[859]: info: Connection to go-librespot Websocket established Jan 28 22:56:46 sibermusic volumio[859]: info: Getting Spotify volume Jan 28 22:56:46 sibermusic volumio[859]: info: Spotify volume: 100 Jan 28 22:56:46 sibermusic volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Jan 28 22:56:46 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 28 22:56:46 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 28 22:56:46 sibermusic volumio[859]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Jan 28 22:56:46 sibermusic volumio[859]: SPOTIFY: SPOTIFY VOLUME 100 Jan 28 22:56:46 sibermusic volumio[859]: SPOTIFY: VOLUMIO VOLUME 73 Jan 28 22:56:46 sibermusic volumio[859]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 28 22:56:46 sibermusic volumio[859]: info: Setting Spotify Volume from Volumio: 73 Jan 28 22:56:48 sibermusic volumio[859]: SPOTIFY: SETTING SPOTIFY VOLUME 73 Jan 28 22:56:48 sibermusic volumio[859]: info: Sending Spotify command with payload to local API: /player/volume Jan 28 22:56:48 sibermusic go-librespot[1127]: time="2024-01-28T22:56:48+01:00" level=debug msg="update volume to 47840/65535" Jan 28 22:56:48 sibermusic go-librespot[1127]: time="2024-01-28T22:56:48+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 28 22:56:48 sibermusic go-librespot[1127]: time="2024-01-28T22:56:48+01:00" level=trace msg="emitting websocket event: volume" Jan 28 22:56:48 sibermusic volumio[859]: SPOTIFY: received: {"type":"volume","data":{"value":73,"max":100}} Jan 28 22:56:48 sibermusic volumio[859]: SPOTIFY: RECEIVED SPOTIFY VOLUME 73 Jan 28 22:56:50 sibermusic volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 7 Jan 28 22:56:51 sibermusic volumio[859]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jan 28 22:56:51 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jan 28 22:56:51 sibermusic volumio[859]: info: Listing playlists Jan 28 22:56:51 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetQueue Jan 28 22:56:51 sibermusic volumio[859]: info: CoreStateMachine::getQueue Jan 28 22:56:51 sibermusic volumio[859]: info: CorePlayQueue::getQueue Jan 28 22:56:51 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetVisibleSources Jan 28 22:56:51 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 22:56:51 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 28 22:56:51 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 28 22:56:52 sibermusic volumio[1014]: ............................................................................................................++++ Jan 28 22:56:52 sibermusic volumio[1014]: e is 65537 (0x010001) Jan 28 22:56:52 sibermusic volumio[1014]: writing RSA key Jan 28 22:56:58 sibermusic volumio[859]: error: MyVolumio Plugin failed to start in a timely fashion Jan 28 22:56:58 sibermusic volumio[859]: info: BOOT COMPLETED Jan 28 22:56:58 sibermusic volumio[859]: [Metrics] CommandRouter: 37s 27.25ms Jan 28 22:56:58 sibermusic volumio[859]: info: CoreCommandRouter::volumiosetStartupVolume Jan 28 22:56:58 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 22:56:58 sibermusic volumio[859]: info: VolumeController:: Setting startup Volume 75 Jan 28 22:56:58 sibermusic volumio[859]: info: VolumeController::SetAlsaVolume75 Jan 28 22:56:58 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:56:58 sibermusic volumio[859]: info: CoreCommandRouter::Close All Modals sent Jan 28 22:56:58 sibermusic volumio[859]: info: CoreCommandRouter::Close All Modals sent Jan 28 22:56:58 sibermusic volumio[859]: info: CoreStateMachine::pushState Jan 28 22:56:58 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 28 22:56:58 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 22:56:58 sibermusic volumio[859]: info: CoreCommandRouter::volumioPushState Jan 28 22:56:58 sibermusic volumio[859]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Jan 28 22:56:58 sibermusic volumio[859]: SPOTIFY: SPOTIFY VOLUME 73 Jan 28 22:56:58 sibermusic volumio[859]: SPOTIFY: VOLUMIO VOLUME 75 Jan 28 22:56:58 sibermusic volumio[859]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 28 22:56:58 sibermusic volumio[859]: info: Setting Spotify Volume from Volumio: 75 Jan 28 22:56:59 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 28 22:56:59 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 22:56:59 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 28 22:56:59 sibermusic volumio[859]: SPOTIFY: SETTING SPOTIFY VOLUME 75 Jan 28 22:56:59 sibermusic volumio[859]: info: Sending Spotify command with payload to local API: /player/volume Jan 28 22:56:59 sibermusic go-librespot[1127]: time="2024-01-28T22:56:59+01:00" level=debug msg="update volume to 49151/65535" Jan 28 22:56:59 sibermusic go-librespot[1127]: time="2024-01-28T22:56:59+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 28 22:56:59 sibermusic go-librespot[1127]: time="2024-01-28T22:56:59+01:00" level=trace msg="emitting websocket event: volume" Jan 28 22:56:59 sibermusic volumio[859]: SPOTIFY: received: {"type":"volume","data":{"value":75,"max":100}} Jan 28 22:56:59 sibermusic volumio[859]: SPOTIFY: RECEIVED SPOTIFY VOLUME 75 Jan 28 22:57:05 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 22:57:05 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 22:57:05 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 22:57:07 sibermusic ntpd[654]: Soliciting pool server 162.159.200.1 Jan 28 22:57:08 sibermusic ntpd[654]: Soliciting pool server 174.138.107.7 Jan 28 22:57:08 sibermusic ntpd[654]: Soliciting pool server 213.136.0.252 Jan 28 22:57:09 sibermusic ntpd[654]: Soliciting pool server 95.85.21.89 Jan 28 22:57:09 sibermusic ntpd[654]: Soliciting pool server 185.51.192.63 Jan 28 22:57:09 sibermusic ntpd[654]: Soliciting pool server 86.80.166.233 Jan 28 22:57:10 sibermusic ntpd[654]: Soliciting pool server 45.159.204.28 Jan 28 22:57:10 sibermusic ntpd[654]: Soliciting pool server 45.138.55.62 Jan 28 22:57:10 sibermusic ntpd[654]: Soliciting pool server 45.32.4.67 Jan 28 22:57:10 sibermusic ntpd[654]: Soliciting pool server 185.224.145.68 Jan 28 22:57:11 sibermusic ntpd[654]: Soliciting pool server 77.173.137.253 Jan 28 22:57:11 sibermusic ntpd[654]: Soliciting pool server 45.138.55.60 Jan 28 22:57:11 sibermusic ntpd[654]: Soliciting pool server 83.98.201.134 Jan 28 22:57:12 sibermusic ntpd[654]: Soliciting pool server 45.159.204.28 Jan 28 22:57:12 sibermusic ntpd[654]: Soliciting pool server 87.251.32.228 Jan 28 22:57:13 sibermusic ntpd[654]: Soliciting pool server 108.61.189.74 Jan 28 22:57:13 sibermusic ntpd[654]: Soliciting pool server 81.172.225.142 Jan 28 22:57:14 sibermusic ntpd[654]: Soliciting pool server 185.51.192.61 Jan 28 22:57:15 sibermusic ntpd[654]: Soliciting pool server 83.84.240.223 Jan 30 18:28:10 sibermusic ntpd[654]: receive: Unexpected origin timestamp 0xe9614c3c.d87173f0 does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xe963b02a.b6bff3d0 Jan 30 18:28:10 sibermusic ntpd[654]: receive: Unexpected origin timestamp 0xe9614c3c.d86f1523 does not match aorg 0000000000.00000000 from server@86.80.166.233 xmt 0xe963b02a.b78a3f00 Jan 30 18:28:10 sibermusic ntpd[654]: receive: Unexpected origin timestamp 0xe9614c3c.d86c8f89 does not match aorg 0000000000.00000000 from server@77.173.137.253 xmt 0xe963b02a.b6e41f6d Jan 30 18:28:10 sibermusic ntpd[654]: receive: Unexpected origin timestamp 0xe9614c3c.d870b4ff does not match aorg 0000000000.00000000 from server@174.138.107.7 xmt 0xe963b02a.b98a565e Jan 30 18:28:10 sibermusic systemd[1]: Starting Daily apt download activities... Jan 30 18:28:10 sibermusic systemd[1]: Starting Daily man-db regeneration... Jan 30 18:28:10 sibermusic ntpd[654]: receive: Unexpected origin timestamp 0xe9614c3c.d86d8ad7 does not match aorg 0000000000.00000000 from server@45.32.4.67 xmt 0xe963b02a.bea06d5d Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 30 18:28:12 sibermusic volumio[859]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 30 18:28:13 sibermusic volumio[859]: info: Adding plugin bluetooth to MyMusic Plugins Jan 30 18:28:13 sibermusic volumio[859]: info: Adding plugin multiroom to MyMusic Plugins Jan 30 18:28:13 sibermusic volumio[859]: info: Adding plugin metavolumio to MyMusic Plugins Jan 30 18:28:13 sibermusic volumio[859]: info: Adding plugin cd_controller to MyMusic Plugins Jan 30 18:28:13 sibermusic volumio[859]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 30 18:28:13 sibermusic volumio[859]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 30 18:28:13 sibermusic volumio[859]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 30 18:28:14 sibermusic volumio[859]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 30 18:28:14 sibermusic volumio[859]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 30 18:28:14 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 18:28:14 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 18:28:14 sibermusic volumio[859]: info: Starting MyVolumio Remote Streaming Endpoints Jan 30 18:28:14 sibermusic volumio[859]: info: MyVolumio login type: Token Jan 30 18:28:14 sibermusic volumio[859]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 30 18:28:14 sibermusic volumio[859]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 30 18:28:16 sibermusic nmbd[626]: [2024/01/30 18:28:16.064291, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 30 18:28:16 sibermusic nmbd[626]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.227 for name WORKGROUP<1d>. Jan 30 18:28:16 sibermusic nmbd[626]: This response was from IP 192.168.178.80, reporting an IP address of 192.168.178.80. Jan 30 18:28:16 sibermusic systemd[1]: man-db.service: Succeeded. Jan 30 18:28:16 sibermusic systemd[1]: Started Daily man-db regeneration. Jan 30 18:28:16 sibermusic systemd[1]: apt-daily.service: Succeeded. Jan 30 18:28:16 sibermusic systemd[1]: Started Daily apt download activities. Jan 30 18:28:16 sibermusic systemd[1]: Starting Daily apt upgrade and clean activities... Jan 30 18:28:17 sibermusic volumio[859]: info: Starting Streaming Service Transparent Proxy Jan 30 18:28:17 sibermusic volumio[859]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 30 18:28:17 sibermusic volumio[859]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 30 18:28:17 sibermusic volumio[859]: info: Streaming services startup Jan 30 18:28:17 sibermusic volumio[859]: info: Starting Streaming Daemon Jan 30 18:28:17 sibermusic sudo[1884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 30 18:28:17 sibermusic sudo[1884]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:28:17 sibermusic volumio[859]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 30 18:28:17 sibermusic sudo[1884]: pam_unix(sudo:session): session closed for user root Jan 30 18:28:17 sibermusic volumio[859]: error: Cannot start Volumio Streaming Daemon Jan 30 18:28:17 sibermusic volumio[859]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 30 18:28:17 sibermusic volumio[859]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 30 18:28:17 sibermusic volumio[859]: info: Discovery: A device disappeared from network Jan 30 18:28:17 sibermusic volumio[859]: info: Discovery: Device siberup disappeared from network Jan 30 18:28:17 sibermusic volumio[859]: info: Discovery: Disconnected from remote: 192.168.178.73 Jan 30 18:28:17 sibermusic volumio[859]: info: Discovery: A device disappeared from network Jan 30 18:28:17 sibermusic volumio[859]: info: Discovery: Device sibermusic disappeared from network Jan 30 18:28:17 sibermusic volumio[859]: info: Discovery: adding 411a939b-97a5-4cdf-b005-bdba7cc60c5a Jan 30 18:28:17 sibermusic volumio[859]: info: Discovery: Found device sibermusic Jan 30 18:28:17 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 30 18:28:17 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 30 18:28:17 sibermusic volumio[859]: info: Discovery: adding bead4742-cfbb-4ddb-b635-1868495b0e00 Jan 30 18:28:17 sibermusic volumio[859]: info: Discovery: Found device siberup Jan 30 18:28:17 sibermusic volumio[859]: info: Discovery: Connecting to remote: 192.168.178.73 Jan 30 18:28:17 sibermusic volumio[859]: info: Discovery: Connected to remote: 192.168.178.73 Jan 30 18:28:17 sibermusic volumio[859]: error: MyVolumio Custom Token format not valid, refreshing it Jan 30 18:28:17 sibermusic systemd[1]: apt-daily-upgrade.service: Succeeded. Jan 30 18:28:17 sibermusic systemd[1]: Started Daily apt upgrade and clean activities. Jan 30 18:28:18 sibermusic volumio[859]: info: MyVolumio login type: Token Jan 30 18:28:18 sibermusic volumio[859]: STREAMING PROXY: Starting server on port 3245 Jan 30 18:28:18 sibermusic volumio[859]: Node JS runtime: 14 Jan 30 18:28:18 sibermusic volumio[859]: verbose: New Socket.io Connection to 192.168.178.227 from 192.168.178.66 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Mobile Safari/537.36 Total Clients: 8 Jan 30 18:28:18 sibermusic volumio[859]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 30 18:28:20 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 30 18:28:20 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 30 18:28:20 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetVisibleSources Jan 30 18:28:20 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 30 18:28:20 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 30 18:28:20 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 30 18:28:20 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 30 18:28:20 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetQueue Jan 30 18:28:20 sibermusic volumio[859]: info: CoreStateMachine::getQueue Jan 30 18:28:20 sibermusic volumio[859]: info: CorePlayQueue::getQueue Jan 30 18:28:20 sibermusic volumio[859]: info: Listing playlists Jan 30 18:28:20 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 30 18:28:20 sibermusic volumio[859]: info: Received Get System Info Jan 30 18:28:20 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 30 18:28:20 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 30 18:28:20 sibermusic volumio[859]: info: Discovery: Getting this device information Jan 30 18:28:20 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 30 18:28:20 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 30 18:28:20 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 30 18:28:20 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 30 18:28:20 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 30 18:28:20 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 30 18:28:22 sibermusic volumio[859]: info: MyVolumio token set successfully Jan 30 18:28:22 sibermusic volumio[859]: info: MYVOLUMIO: Adding device Jan 30 18:28:22 sibermusic volumio[859]: info: MYVOLUMIO: Evaluating Server Jan 30 18:28:22 sibermusic volumio[859]: info: MyVolumio Plan changed: premium Jan 30 18:28:22 sibermusic volumio[859]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jan 30 18:28:22 sibermusic volumio[859]: info: Removing browser output: myVolumio user plan is not superstar Jan 30 18:28:22 sibermusic volumio[859]: info: Removing audio output: Jan 30 18:28:22 sibermusic volumio[859]: info: MYVOLUMIO: Adding device Jan 30 18:28:22 sibermusic volumio[859]: info: MYVOLUMIO: Evaluating Server Jan 30 18:28:22 sibermusic volumio[859]: info: Remote config written successfully Jan 30 18:28:22 sibermusic volumio[859]: info: Starting Tunnel 1 Jan 30 18:28:22 sibermusic volumio[859]: info: Starting Tunnel Connection Checker Jan 30 18:28:23 sibermusic volumio[859]: info: MYVolumio Device enabled Jan 30 18:28:23 sibermusic volumio[859]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 30 18:28:23 sibermusic volumio[859]: info: MyVolumio status changed Jan 30 18:28:23 sibermusic volumio[859]: info: Streaming services startup Jan 30 18:28:23 sibermusic volumio[859]: info: Starting Streaming Daemon Jan 30 18:28:23 sibermusic volumio[859]: info: Setting Geolocation for MyVolumio to eu3 Jan 30 18:28:23 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 18:28:23 sibermusic sudo[1961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 30 18:28:23 sibermusic sudo[1961]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:28:23 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 18:28:23 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 18:28:23 sibermusic sudo[1961]: pam_unix(sudo:session): session closed for user root Jan 30 18:28:23 sibermusic volumio[859]: error: Cannot start Volumio Streaming Daemon Jan 30 18:28:23 sibermusic volumio[859]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 30 18:28:23 sibermusic volumio[859]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 30 18:28:23 sibermusic volumio[859]: info: Setting Geolocation for MyVolumio to eu2 Jan 30 18:28:23 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 18:28:23 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 18:28:23 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 18:28:23 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 30 18:28:23 sibermusic volumio[859]: error: [MyVolumio PluginManager] Cache data is invalid! Jan 30 18:28:23 sibermusic volumio[859]: info: Successfully Added MyVolumio device Jan 30 18:28:23 sibermusic volumio[859]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jan 30 18:28:24 sibermusic volumio[859]: info: [1706635704092] Starting BluetoothController Jan 30 18:28:24 sibermusic volumio[859]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jan 30 18:28:25 sibermusic volumio[859]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jan 30 18:28:25 sibermusic sudo[1964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 30 18:28:25 sibermusic sudo[1964]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:28:25 sibermusic sudo[1964]: pam_unix(sudo:session): session closed for user root Jan 30 18:28:25 sibermusic volumio[859]: info: MRS: MultiRoom plugin initialized Jan 30 18:28:25 sibermusic volumio[859]: info: MRS: STOPPING SNAPCLIENT Jan 30 18:28:25 sibermusic volumio[859]: info: MRS: Snap server stop Jan 30 18:28:25 sibermusic sudo[1980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 30 18:28:25 sibermusic sudo[1980]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:28:25 sibermusic volumio[859]: info: MRS: STOPPING volumioStreaming Jan 30 18:28:25 sibermusic sudo[1980]: pam_unix(sudo:session): session closed for user root Jan 30 18:28:25 sibermusic sudo[1984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 30 18:28:25 sibermusic volumio[859]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jan 30 18:28:25 sibermusic sudo[1984]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:28:25 sibermusic sudo[1986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 30 18:28:25 sibermusic sudo[1986]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:28:25 sibermusic sudo[1988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 30 18:28:25 sibermusic sudo[1988]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:28:25 sibermusic sudo[1984]: pam_unix(sudo:session): session closed for user root Jan 30 18:28:25 sibermusic sudo[1988]: pam_unix(sudo:session): session closed for user root Jan 30 18:28:25 sibermusic volumio[859]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jan 30 18:28:25 sibermusic sudo[1986]: pam_unix(sudo:session): session closed for user root Jan 30 18:28:25 sibermusic volumio[859]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jan 30 18:28:25 sibermusic volumio[859]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jan 30 18:28:25 sibermusic volumio[859]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jan 30 18:28:25 sibermusic volumio[859]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jan 30 18:28:25 sibermusic volumio[859]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jan 30 18:28:25 sibermusic volumio[859]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jan 30 18:28:25 sibermusic volumio[859]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jan 30 18:28:25 sibermusic volumio[859]: info: Preparing to generate the ALSA configuration file Jan 30 18:28:25 sibermusic volumio[859]: info: Updating MyVolumio device info Jan 30 18:28:25 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 18:28:25 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 18:28:25 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 18:28:25 sibermusic volumio[859]: info: Updating MyVolumio device info Jan 30 18:28:25 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 18:28:25 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 18:28:25 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 18:28:25 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 30 18:28:25 sibermusic volumio[859]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 30 18:28:25 sibermusic volumio[859]: info: Reading ALSA contributions from plugins. Jan 30 18:28:25 sibermusic volumio[859]: info: Successfully Added MyVolumio device Jan 30 18:28:25 sibermusic volumio[859]: info: MRS: Removed streaming files Jan 30 18:28:25 sibermusic volumio[859]: info: MRS: volumioStreaming STOPPED Jan 30 18:28:25 sibermusic volumio[859]: info: MRS: SNAPSERVER STOPPED Jan 30 18:28:25 sibermusic volumio[859]: info: MRS: SNAPCLIENT STOPPED Jan 30 18:28:25 sibermusic volumio[859]: info: Asound.conf file written Jan 30 18:28:25 sibermusic sudo[1992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 30 18:28:25 sibermusic sudo[1992]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:28:25 sibermusic sudo[1992]: pam_unix(sudo:session): session closed for user root Jan 30 18:28:25 sibermusic volumio[859]: info: Output device has changed, restarting MPD Jan 30 18:28:25 sibermusic sudo[1997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 30 18:28:25 sibermusic sudo[1997]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:28:25 sibermusic volumio[859]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 30 18:28:25 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 18:28:25 sibermusic sudo[1997]: pam_unix(sudo:session): session closed for user root Jan 30 18:28:25 sibermusic sudo[2000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 30 18:28:25 sibermusic volumio[859]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jan 30 18:28:25 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 30 18:28:25 sibermusic sudo[2000]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:28:25 sibermusic systemd[1]: musicservicesshield.service: Succeeded. Jan 30 18:28:25 sibermusic systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jan 30 18:28:25 sibermusic systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jan 30 18:28:25 sibermusic systemd[1]: Stopping Music Player Daemon... Jan 30 18:28:25 sibermusic volumio[859]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jan 30 18:28:25 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jan 30 18:28:25 sibermusic volumio[859]: info: MRS: Pushing multiroomSync output for this device Jan 30 18:28:25 sibermusic volumio[859]: info: MRS: Pushing multiroomSync output Jan 30 18:28:25 sibermusic systemd[1]: mpd.service: Succeeded. Jan 30 18:28:25 sibermusic systemd[1]: Stopped Music Player Daemon. Jan 30 18:28:25 sibermusic volumio[859]: info: Adding audio output: Jan 30 18:28:25 sibermusic sudo[2005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name sibermusic Jan 30 18:28:25 sibermusic sudo[2005]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:28:26 sibermusic systemd[1]: Starting Music Player Daemon... Jan 30 18:28:26 sibermusic volumio[859]: info: Adding audio output: Jan 30 18:28:26 sibermusic volumio[859]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 30 18:28:26 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 18:28:26 sibermusic volumio[859]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jan 30 18:28:26 sibermusic volumio[859]: info: Adding METAVOLUMIO REST API Endpoints Jan 30 18:28:26 sibermusic volumio[859]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 30 18:28:26 sibermusic volumio[859]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 30 18:28:26 sibermusic volumio[859]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 30 18:28:26 sibermusic volumio[859]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 30 18:28:26 sibermusic volumio[859]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jan 30 18:28:26 sibermusic volumio[859]: info: Adding Manifest REST API Endpoints Jan 30 18:28:26 sibermusic volumio[859]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Jan 30 18:28:26 sibermusic volumio[859]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jan 30 18:28:26 sibermusic volumio[859]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Jan 30 18:28:26 sibermusic volumio[859]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jan 30 18:28:26 sibermusic volumio[859]: info: Adding inputs REST Endpoints Jan 30 18:28:26 sibermusic volumio[859]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jan 30 18:28:26 sibermusic volumio[859]: info: Scanning Audio Inputs Jan 30 18:28:25 sibermusic sudo[2005]: pam_unix(sudo:session): session closed for user root Jan 30 18:28:26 sibermusic volumio[859]: info: Checking against Known Cards name Jan 30 18:28:26 sibermusic volumio[859]: info: Adding Server instance for streaming Jan 30 18:28:26 sibermusic volumio[859]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jan 30 18:28:26 sibermusic volumio[859]: error: Hi Res Audio Failed Login: Missing Login Data Jan 30 18:28:26 sibermusic volumio[859]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 30 18:28:26 sibermusic volumio[859]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 30 18:28:26 sibermusic volumio[859]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jan 30 18:28:26 sibermusic volumio[859]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jan 30 18:28:26 sibermusic volumio[859]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jan 30 18:28:26 sibermusic volumio[859]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Jan 30 18:28:26 sibermusic volumio[859]: info: Stopping AccessToken refresher cron Jan 30 18:28:26 sibermusic sudo[2006]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 30 18:28:26 sibermusic sudo[2006]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:28:26 sibermusic sudo[2006]: pam_unix(sudo:session): session closed for user root Jan 30 18:28:26 sibermusic volumio[859]: info: AccessToken refresher cron started Jan 30 18:28:26 sibermusic volumio[859]: info: Stopping AccessToken refresher cron for QOBUZ Jan 30 18:28:26 sibermusic volumio[859]: info: AccessToken refresher cron started for QOBUZ Jan 30 18:28:26 sibermusic volumio[859]: info: Adding TIDAL REST API Endpoints Jan 30 18:28:26 sibermusic volumio[859]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jan 30 18:28:26 sibermusic volumio[859]: info: Adding QOBUZ REST API Endpoints Jan 30 18:28:26 sibermusic volumio[859]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jan 30 18:28:26 sibermusic volumio[859]: info: Bluetooth name changed to sibermusic Jan 30 18:28:26 sibermusic volumio[859]: info: MPD Permissions set Jan 30 18:28:26 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 18:28:26 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 18:28:26 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 18:28:26 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 18:28:26 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 18:28:26 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 18:28:26 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 18:28:26 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 18:28:26 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 30 18:28:26 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 18:28:26 sibermusic volumio[859]: info: MRS: Adding multiroomSync output Jan 30 18:28:26 sibermusic volumio[859]: info: Adding audio output: Jan 30 18:28:26 sibermusic volumio[859]: info: MRS: Pushing multiroomSync output Jan 30 18:28:26 sibermusic volumio[859]: info: Successfully Updated MyVolumio device Jan 30 18:28:26 sibermusic mpd[2015]: Jan 30 18:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 30 18:28:26 sibermusic systemd[1]: Started Music Player Daemon. Jan 30 18:28:26 sibermusic sudo[2000]: pam_unix(sudo:session): session closed for user root Jan 30 18:28:26 sibermusic volumio[859]: error: updateQueue error: null Jan 30 18:28:26 sibermusic systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jan 30 18:28:27 sibermusic volumio[859]: info: Successfully Updated MyVolumio device Jan 30 18:28:27 sibermusic volumio[2022]: cset: --> shielding system active with Jan 30 18:28:27 sibermusic volumio[2022]: cset: "system" cpuset of CPUSPEC(2-3) with 208 tasks running Jan 30 18:28:27 sibermusic volumio[2022]: cset: "user" cpuset of CPUSPEC(0-1) with 3 tasks running Jan 30 18:28:27 sibermusic systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jan 30 18:28:28 sibermusic sudo[2049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 30 18:28:28 sibermusic sudo[2049]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:28:29 sibermusic systemd[1]: Started Volumio Bluetooth Module. Jan 30 18:28:29 sibermusic sudo[2049]: pam_unix(sudo:session): session closed for user root Jan 30 18:28:29 sibermusic volumio[859]: info: Volumio BT Module successfully started Jan 30 18:28:29 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 30 18:28:29 sibermusic volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Jan 30 18:28:29 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 30 18:28:29 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 30 18:28:30 sibermusic sudo[2053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jan 30 18:28:30 sibermusic sudo[2053]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:28:30 sibermusic volumiobt[2051]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jan 30 18:28:30 sibermusic sudo[2053]: pam_unix(sudo:session): session closed for user root Jan 30 18:28:30 sibermusic sshd[2047]: Accepted password for volumio from 192.168.178.66 port 54480 ssh2 Jan 30 18:28:30 sibermusic sshd[2047]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jan 30 18:28:30 sibermusic systemd-logind[507]: New session 1 of user volumio. Jan 30 18:28:30 sibermusic volumio[859]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 30 18:28:30 sibermusic systemd[1]: Created slice User Slice of UID 1000. Jan 30 18:28:30 sibermusic volumio[859]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 30 18:28:30 sibermusic volumio[859]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 30 18:28:30 sibermusic systemd[1]: Starting User Runtime Directory /run/user/1000... Jan 30 18:28:30 sibermusic volumio[859]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 30 18:28:30 sibermusic systemd[1]: Started User Runtime Directory /run/user/1000. Jan 30 18:28:30 sibermusic systemd[1]: Starting User Manager for UID 1000... Jan 30 18:28:30 sibermusic volumio[859]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 30 18:28:30 sibermusic systemd[2057]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Jan 30 18:28:30 sibermusic volumio[859]: info: MRS: Getting audio outputs on start Jan 30 18:28:30 sibermusic volumio[859]: info: MRS: Requesting all other devices output Jan 30 18:28:31 sibermusic sudo[2061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jan 30 18:28:31 sibermusic sudo[2061]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:28:31 sibermusic volumiobt[2051]: pulseaudio: no process found Jan 30 18:28:31 sibermusic sudo[2061]: pam_unix(sudo:session): session closed for user root Jan 30 18:28:31 sibermusic sudo[2070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 30 18:28:31 sibermusic sudo[2070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:28:31 sibermusic dbus-daemon[483]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.27' (uid=1000 pid=2075 comm="/usr/bin/pulseaudio --high-priority=true ") Jan 30 18:28:31 sibermusic systemd[1]: Started MyVolumio SSH Tunnel. Jan 30 18:28:31 sibermusic systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jan 30 18:28:31 sibermusic sudo[2070]: pam_unix(sudo:session): session closed for user root Jan 30 18:28:31 sibermusic autossh[2076]: port set to 0, monitoring disabled Jan 30 18:28:31 sibermusic autossh[2076]: starting ssh (count 1) Jan 30 18:28:31 sibermusic autossh[2076]: ssh child pid is 2080 Jan 30 18:28:31 sibermusic volumio[859]: info: Remote SSH Started Jan 30 18:28:31 sibermusic dbus-daemon[483]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jan 30 18:28:31 sibermusic systemd[1]: Started RealtimeKit Scheduling Policy Service. Jan 30 18:28:31 sibermusic rtkit-daemon[2079]: Successfully called chroot. Jan 30 18:28:31 sibermusic rtkit-daemon[2079]: Successfully dropped privileges. Jan 30 18:28:31 sibermusic rtkit-daemon[2079]: Successfully limited resources. Jan 30 18:28:31 sibermusic rtkit-daemon[2079]: Running. Jan 30 18:28:31 sibermusic rtkit-daemon[2079]: Watchdog thread running. Jan 30 18:28:31 sibermusic rtkit-daemon[2079]: Canary thread running. Jan 30 18:28:31 sibermusic dbus-daemon[483]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.28' (uid=0 pid=2079 comm="/usr/lib/rtkit/rtkit-daemon ") Jan 30 18:28:31 sibermusic systemd[1]: Starting Authorization Manager... Jan 30 18:28:31 sibermusic systemd[2057]: Reached target Paths. Jan 30 18:28:31 sibermusic systemd[2057]: Starting D-Bus User Message Bus Socket. Jan 30 18:28:31 sibermusic systemd[2057]: Reached target Timers. Jan 30 18:28:31 sibermusic systemd[2057]: Listening on Sound System. Jan 30 18:28:31 sibermusic systemd[2057]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Jan 30 18:28:31 sibermusic systemd[2057]: Listening on GnuPG network certificate management daemon. Jan 30 18:28:31 sibermusic systemd[2057]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Jan 30 18:28:31 sibermusic systemd[2057]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Jan 30 18:28:31 sibermusic systemd[2057]: Listening on GnuPG cryptographic agent and passphrase cache. Jan 30 18:28:31 sibermusic systemd[2057]: Listening on D-Bus User Message Bus Socket. Jan 30 18:28:31 sibermusic systemd[2057]: Reached target Sockets. Jan 30 18:28:31 sibermusic systemd[2057]: Reached target Basic System. Jan 30 18:28:31 sibermusic systemd[1]: Started User Manager for UID 1000. Jan 30 18:28:31 sibermusic systemd[1]: Started Session 1 of user volumio. Jan 30 18:28:31 sibermusic systemd[2057]: Starting Music Player Daemon... Jan 30 18:28:31 sibermusic polkitd[2083]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 30 18:28:31 sibermusic dbus-daemon[483]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 30 18:28:31 sibermusic systemd[1]: Started Authorization Manager. Jan 30 18:28:31 sibermusic volumiossh-tunnel[2073]: Warning: Permanently added '[eu2.myvolumio.org]:2222,[185.14.187.123]:2222' (RSA) to the list of known hosts. Jan 30 18:28:31 sibermusic volumio[859]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 30 18:28:31 sibermusic volumio[859]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 30 18:28:31 sibermusic volumio[859]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Jan 30 18:28:31 sibermusic pulseaudio[2075]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 30 18:28:31 sibermusic pulseaudio[2075]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 30 18:28:31 sibermusic volumiobt[2051]: Applying permissions Jan 30 18:28:31 sibermusic volumiobt[2051]: Setting BT discoverable and pairable Jan 30 18:28:31 sibermusic bluetoothd[590]: Endpoint registered: sender=:1.49 path=/MediaEndpoint/A2DPSource Jan 30 18:28:31 sibermusic bluetoothd[590]: Endpoint registered: sender=:1.49 path=/MediaEndpoint/A2DPSink Jan 30 18:28:31 sibermusic pulseaudio[2075]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jan 30 18:28:31 sibermusic kernel: Bluetooth: RFCOMM TTY layer initialized Jan 30 18:28:31 sibermusic kernel: Bluetooth: RFCOMM socket layer initialized Jan 30 18:28:31 sibermusic kernel: Bluetooth: RFCOMM ver 1.11 Jan 30 18:28:31 sibermusic sudo[2101]: volumio : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/bash -c touch /testsudo.tmp && rm -rf /testsudo.tmp Jan 30 18:28:31 sibermusic sudo[2101]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:28:31 sibermusic volumiobt[2051]: Agent registered Jan 30 18:28:31 sibermusic volumiobt[2051]: [bluetooth]# -e power on Jan 30 18:28:31 sibermusic volumiobt[2051]: Invalid command in menu main: -e Jan 30 18:28:31 sibermusic volumiobt[2051]: Jan 30 18:28:31 sibermusic volumiobt[2051]: Use "help" for a list of available commands in a menu. Jan 30 18:28:31 sibermusic volumiobt[2051]: Use "menu " if you want to enter any submenu. Jan 30 18:28:31 sibermusic volumiobt[2051]: Use "back" if you want to return to menu main. Jan 30 18:28:31 sibermusic volumiobt[2051]: [bluetooth]# agent on Jan 30 18:28:31 sibermusic volumiobt[2051]: Agent is already registered Jan 30 18:28:31 sibermusic volumiobt[2051]: [bluetooth]# discoverable on Jan 30 18:28:31 sibermusic volumiobt[2051]: [bluetooth]# pairable on Jan 30 18:28:31 sibermusic volumiobt[2051]: [bluetooth]# agent NoInputNoOutput Jan 30 18:28:31 sibermusic volumiobt[2051]: Agent is already registered Jan 30 18:28:31 sibermusic volumiobt[2051]: [bluetooth]# default-agent Jan 30 18:28:31 sibermusic volumiobt[2051]: [bluetooth]# quit Jan 30 18:28:31 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 30 18:28:31 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 30 18:28:31 sibermusic volumiobt[2051]: [59B blob data] Jan 30 18:28:31 sibermusic sudo[2101]: pam_unix(sudo:session): session closed for user root Jan 30 18:28:32 sibermusic mpd[2088]: exception: failed to open log file "/var/log/mpd.log" (config line 7): Permission denied Jan 30 18:28:32 sibermusic systemd[2057]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 30 18:28:32 sibermusic systemd[2057]: mpd.service: Failed with result 'exit-code'. Jan 30 18:28:32 sibermusic systemd[2057]: Failed to start Music Player Daemon. Jan 30 18:28:32 sibermusic systemd[2057]: Reached target Default. Jan 30 18:28:32 sibermusic systemd[2057]: Startup finished in 1.806s. Jan 30 18:28:34 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 18:28:34 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 30 18:28:34 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 30 18:28:34 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 30 18:28:34 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 30 18:28:34 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetBrowseSources Jan 30 18:28:34 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 30 18:29:45 sibermusic sshd[2133]: Did not receive identification string from 192.168.178.66 port 54490 Jan 30 18:29:46 sibermusic sshd[2096]: Received disconnect from 192.168.178.66 port 54480:11: Jan 30 18:29:46 sibermusic sshd[2096]: Disconnected from user volumio 192.168.178.66 port 54480 Jan 30 18:29:46 sibermusic sshd[2047]: pam_unix(sshd:session): session closed for user volumio Jan 30 18:29:46 sibermusic systemd[1]: session-1.scope: Succeeded. Jan 30 18:29:46 sibermusic systemd-logind[507]: Session 1 logged out. Waiting for processes to exit. Jan 30 18:29:46 sibermusic systemd-logind[507]: Removed session 1. Jan 30 18:29:55 sibermusic volumio[859]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 30 18:29:55 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 30 18:29:55 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 30 18:29:56 sibermusic volumio[859]: info: Received Get System Version Jan 30 18:29:56 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 30 18:29:56 sibermusic systemd[1]: Stopping User Manager for UID 1000... Jan 30 18:29:56 sibermusic systemd[2057]: Stopped target Default. Jan 30 18:29:56 sibermusic systemd[2057]: Stopped target Basic System. Jan 30 18:29:56 sibermusic systemd[2057]: Stopped target Timers. Jan 30 18:29:56 sibermusic systemd[2057]: Stopped target Sockets. Jan 30 18:29:56 sibermusic systemd[2057]: dbus.socket: Succeeded. Jan 30 18:29:56 sibermusic systemd[2057]: Closed D-Bus User Message Bus Socket. Jan 30 18:29:56 sibermusic systemd[2057]: gpg-agent-ssh.socket: Succeeded. Jan 30 18:29:56 sibermusic systemd[2057]: Closed GnuPG cryptographic agent (ssh-agent emulation). Jan 30 18:29:56 sibermusic systemd[2057]: gpg-agent-browser.socket: Succeeded. Jan 30 18:29:56 sibermusic volumio[859]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 30 18:29:56 sibermusic systemd[2057]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers). Jan 30 18:29:56 sibermusic systemd[2057]: gpg-agent.socket: Succeeded. Jan 30 18:29:56 sibermusic systemd[2057]: Closed GnuPG cryptographic agent and passphrase cache. Jan 30 18:29:56 sibermusic systemd[2057]: gpg-agent-extra.socket: Succeeded. Jan 30 18:29:56 sibermusic systemd[2057]: Closed GnuPG cryptographic agent and passphrase cache (restricted). Jan 30 18:29:56 sibermusic systemd[2057]: pulseaudio.socket: Succeeded. Jan 30 18:29:56 sibermusic systemd[2057]: Closed Sound System. Jan 30 18:29:56 sibermusic systemd[2057]: dirmngr.socket: Succeeded. Jan 30 18:29:56 sibermusic systemd[2057]: Closed GnuPG network certificate management daemon. Jan 30 18:29:56 sibermusic systemd[2057]: Stopped target Paths. Jan 30 18:29:56 sibermusic systemd[2057]: Reached target Shutdown. Jan 30 18:29:56 sibermusic systemd[2057]: systemd-exit.service: Succeeded. Jan 30 18:29:56 sibermusic systemd[2057]: Started Exit the Session. Jan 30 18:29:56 sibermusic systemd[2057]: Reached target Exit the Session. Jan 30 18:29:56 sibermusic systemd[1]: user@1000.service: Succeeded. Jan 30 18:29:56 sibermusic systemd[1]: Stopped User Manager for UID 1000. Jan 30 18:29:56 sibermusic systemd[1]: Stopping User Runtime Directory /run/user/1000... Jan 30 18:29:56 sibermusic volumio[859]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 30 18:29:56 sibermusic systemd[1]: run-user-1000.mount: Succeeded. Jan 30 18:29:56 sibermusic systemd[1]: user-runtime-dir@1000.service: Succeeded. Jan 30 18:29:56 sibermusic systemd[1]: Stopped User Runtime Directory /run/user/1000. Jan 30 18:29:56 sibermusic systemd[1]: Removed slice User Slice of UID 1000. Jan 30 18:30:14 sibermusic volumio[859]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 30 18:30:14 sibermusic volumio[859]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 30 18:30:14 sibermusic volumio[859]: info: Retrieving Cloud Streaming UI Jan 30 18:30:14 sibermusic volumio[859]: info: Getting Tidal Cloud Configuration Jan 30 18:30:14 sibermusic volumio[859]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 30 18:30:15 sibermusic volumio[859]: info: Getting Qobuz Cloud Configuration Jan 30 18:30:15 sibermusic volumio[859]: info: Asking plugin for UI Config Jan 30 18:30:15 sibermusic volumio[859]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 30 18:30:15 sibermusic volumio[859]: info: Getting Spotify Cloud Configuration Jan 30 18:30:15 sibermusic volumio[859]: info: Asking plugin for UI Config Jan 30 18:30:15 sibermusic volumio[859]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 30 18:30:15 sibermusic volumio[859]: info: Saving Spotify Acccount Jan 30 18:30:15 sibermusic volumio[859]: info: Got it Jan 30 18:30:15 sibermusic volumio[859]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 30 18:30:15 sibermusic volumio[859]: info: Got Tidal Cloud Configuration Jan 30 18:30:15 sibermusic volumio[859]: info: Got it Jan 30 18:30:15 sibermusic volumio[859]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 30 18:30:15 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 30 18:30:15 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 30 18:30:15 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 30 18:30:15 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 30 18:30:15 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 30 18:30:15 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 30 18:30:15 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 30 18:30:15 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 30 18:30:15 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetBrowseSources Jan 30 18:30:15 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetBrowseSources Jan 30 18:30:15 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetBrowseSources Jan 30 18:30:15 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 30 18:30:15 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 30 18:30:19 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 30 18:30:23 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 30 18:30:27 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 30 18:31:20 sibermusic sudo[2144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 30 18:31:20 sibermusic sudo[2144]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:31:20 sibermusic sudo[2144]: pam_unix(sudo:session): session closed for user root Jan 30 18:31:20 sibermusic sudo[2147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 30 18:31:20 sibermusic sudo[2147]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:31:20 sibermusic sudo[2147]: pam_unix(sudo:session): session closed for user root Jan 30 18:31:20 sibermusic volumio[859]: verbose: New Socket.io Connection to 192.168.178.227 from 192.168.178.123 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36 Total Clients: 10 Jan 30 18:31:21 sibermusic sudo[2150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 30 18:31:21 sibermusic sudo[2150]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:31:21 sibermusic sudo[2150]: pam_unix(sudo:session): session closed for user root Jan 30 18:31:21 sibermusic sudo[2153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 30 18:31:21 sibermusic sudo[2153]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:31:21 sibermusic sudo[2153]: pam_unix(sudo:session): session closed for user root Jan 30 18:31:21 sibermusic volumio[859]: verbose: New Socket.io Connection to 192.168.178.227 from 192.168.178.123 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36 Total Clients: 11 Jan 30 18:31:22 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 30 18:31:22 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 30 18:31:22 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetVisibleSources Jan 30 18:31:22 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 30 18:31:22 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 30 18:31:22 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 30 18:31:22 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 30 18:31:22 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 30 18:31:22 sibermusic volumio[859]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 30 18:31:22 sibermusic volumio[859]: info: Received Get System Info Jan 30 18:31:22 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 30 18:31:22 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 30 18:31:22 sibermusic volumio[859]: info: Discovery: Getting this device information Jan 30 18:31:22 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 30 18:31:22 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 30 18:31:22 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 30 18:31:22 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 30 18:31:22 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 30 18:31:22 sibermusic volumio[859]: info: Listing playlists Jan 30 18:31:22 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 30 18:31:22 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 30 18:31:22 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 30 18:31:22 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 30 18:31:22 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 30 18:31:23 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 30 18:31:23 sibermusic volumio[859]: info: Received Get System Info Jan 30 18:31:23 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 30 18:31:23 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 30 18:31:23 sibermusic volumio[859]: info: Discovery: Getting this device information Jan 30 18:31:23 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 30 18:31:23 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 30 18:31:23 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 30 18:31:24 sibermusic volumio[859]: info: Executing endpoint metavolumio Jan 30 18:31:24 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 30 18:31:24 sibermusic volumio[859]: info: Executing endpoint metavolumio Jan 30 18:31:24 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 30 18:31:24 sibermusic volumio[859]: info: Executing endpoint metavolumio Jan 30 18:31:24 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 30 18:31:24 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 30 18:31:24 sibermusic volumio[859]: info: Received Get System Info Jan 30 18:31:24 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 30 18:31:24 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 30 18:31:24 sibermusic volumio[859]: info: Discovery: Getting this device information Jan 30 18:31:24 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 30 18:31:24 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 30 18:31:24 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 30 18:31:30 sibermusic volumio[859]: verbose: New Socket.io Connection to 192.168.178.227 from 192.168.178.123 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36 Total Clients: 11 Jan 30 18:31:31 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetState Jan 30 18:31:31 sibermusic volumio[859]: info: CorePlayQueue::getTrack 0 Jan 30 18:31:31 sibermusic volumio[859]: info: CoreCommandRouter::volumioGetQueue Jan 30 18:31:31 sibermusic volumio[859]: info: CoreStateMachine::getQueue Jan 30 18:31:31 sibermusic volumio[859]: info: CorePlayQueue::getQueue Jan 30 18:31:31 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jan 30 18:32:28 sibermusic volumio[859]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Jan 30 18:32:28 sibermusic volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Jan 30 18:32:29 sibermusic sudo[2177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Jan 30 18:32:29 sibermusic sudo[2177]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 30 18:32:29 sibermusic sudo[2177]: pam_unix(sudo:session): session closed for user root Jan 30 18:32:29 sibermusic volumio[859]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 30 18:32:29 sibermusic volumio[859]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 30 18:32:33 sibermusic sudo[2241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Jan 30 18:32:33 sibermusic sudo[2241]: pam_unix(sudo:session): session opened for user root by (uid=0)