Sat Feb 3 11:25:35 MST 2024 Description="'No info available'" # cat /proc/version --------------- Linux version 6.1.69-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1710 SMP Thu Dec 21 13:14:13 GMT 2023 # 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="f8baf7ad070f376535a93fcc6590774528ddb153" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="0e3cf2fcfe1906c5090fab62b4cc2a5ff0069fd8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 30 Jan 2024 03:58:37 PM CET" VOLUMIO_VERSION="3.611" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1d6379ebb89c40061afa8aee78176887" # ifconfig --------------- eth0: flags=4163 mtu 1500 inet 192.168.4.180 netmask 255.255.255.0 broadcast 192.168.4.255 ether b8:27:eb:41:4b:17 txqueuelen 1000 (Ethernet) RX packets 51491 bytes 26047401 (24.8 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 53347 bytes 32065972 (30.5 MiB) 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 2225 bytes 536707 (524.1 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 2225 bytes 536707 (524.1 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 wlan0: flags=4163 mtu 1500 inet 192.168.211.1 netmask 255.255.255.0 broadcast 192.168.211.255 ether b8:27:eb:14:1e:42 txqueuelen 1000 (Ethernet) RX packets 0 bytes 0 (0.0 B) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 65 bytes 11934 (11.6 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 # sudo iwconfig --------------- lo no wireless extensions. eth0 no wireless extensions. wlan0 IEEE 802.11 Mode:Master Tx-Power=31 dBm Retry short limit:7 RTS thr:off Fragment thr:off Power Management:off # aplay -l --------------- **** List of PLAYBACK Hardware Devices **** card 5: Audio [DigiHug USB Audio], device 0: USB Audio [USB Audio] Subdevices: 0/1 Subdevice #0: subdevice #0 card 5: Audio [DigiHug USB Audio], device 1: USB Audio [USB Audio #1] Subdevices: 1/1 Subdevice #0: subdevice #0 # lsusb --------------- Bus 001 Device 004: ID 1852:7022 GYROCOM C&C Co., LTD Bus 001 Device 003: ID 0424:ec00 Standard Microsystems Corp. SMSC9512/9514 Fast Ethernet Adapter Bus 001 Device 002: ID 0424:9514 Standard Microsystems Corp. SMC9514 Hub Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub # lsusb -t --------------- /: Bus 01.Port 1: Dev 1, Class=root_hub, Driver=dwc_otg/1p, 480M |__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/5p, 480M |__ Port 1: Dev 3, If 0, Class=Vendor Specific Class, Driver=smsc95xx, 480M |__ Port 2: Dev 4, If 0, Class=Human Interface Device, Driver=usbhid, 12M |__ Port 2: Dev 4, If 3, Class=Audio, Driver=snd-usb-audio, 12M |__ Port 2: Dev 4, If 1, Class=Audio, Driver=snd-usb-audio, 12M |__ Port 2: Dev 4, If 2, Class=Audio, Driver=snd-usb-audio, 12M # lsusb -v --------------- Couldn't open device, some information will be missing Bus 001 Device 004: ID 1852:7022 GYROCOM C&C Co., LTD Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 1.10 bDeviceClass 0 bDeviceSubClass 0 bDeviceProtocol 0 bMaxPacketSize0 8 idVendor 0x1852 GYROCOM C&C Co., LTD idProduct 0x7022 bcdDevice 0.01 iManufacturer 1 iProduct 2 iSerial 0 bNumConfigurations 1 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x0182 bNumInterfaces 4 bConfigurationValue 1 iConfiguration 0 bmAttributes 0x80 (Bus Powered) MaxPower 500mA Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 1 bInterfaceClass 3 Human Interface Device bInterfaceSubClass 0 bInterfaceProtocol 0 iInterface 0 HID Device Descriptor: bLength 9 bDescriptorType 33 bcdHID 1.00 bCountryCode 0 Not supported bNumDescriptors 1 bDescriptorType 34 Report wDescriptorLength 58 Report Descriptors: ** UNAVAILABLE ** Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 3 Transfer Type Interrupt Synch Type None Usage Type Data wMaxPacketSize 0x0012 1x 18 bytes bInterval 32 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 1 bAlternateSetting 0 bNumEndpoints 0 bInterfaceClass 1 Audio bInterfaceSubClass 1 Control Device bInterfaceProtocol 0 iInterface 3 AudioControl Interface Descriptor: bLength 10 bDescriptorType 36 bDescriptorSubtype 1 (HEADER) bcdADC 1.00 wTotalLength 0x003e bInCollection 2 baInterfaceNr(0) 2 baInterfaceNr(1) 3 AudioControl Interface Descriptor: bLength 12 bDescriptorType 36 bDescriptorSubtype 2 (INPUT_TERMINAL) bTerminalID 5 wTerminalType 0x0605 SPDIF interface bAssocTerminal 0 bNrChannels 2 wChannelConfig 0x0003 Left Front (L) Right Front (R) iChannelNames 0 iTerminal 0 AudioControl Interface Descriptor: bLength 12 bDescriptorType 36 bDescriptorSubtype 2 (INPUT_TERMINAL) bTerminalID 9 wTerminalType 0x0101 USB Streaming bAssocTerminal 0 bNrChannels 2 wChannelConfig 0x0003 Left Front (L) Right Front (R) iChannelNames 0 iTerminal 0 AudioControl Interface Descriptor: bLength 9 bDescriptorType 36 bDescriptorSubtype 3 (OUTPUT_TERMINAL) bTerminalID 3 wTerminalType 0x0605 SPDIF interface bAssocTerminal 0 bSourceID 16 iTerminal 0 AudioControl Interface Descriptor: bLength 9 bDescriptorType 36 bDescriptorSubtype 3 (OUTPUT_TERMINAL) bTerminalID 7 wTerminalType 0x0101 USB Streaming bAssocTerminal 0 bSourceID 5 iTerminal 0 AudioControl Interface Descriptor: bLength 10 bDescriptorType 36 bDescriptorSubtype 6 (FEATURE_UNIT) bUnitID 16 bSourceID 9 bControlSize 1 bmaControls(0) 0x01 Mute Control bmaControls(1) 0x02 Volume Control bmaControls(2) 0x02 Volume Control iFeature 0 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 2 bAlternateSetting 0 bNumEndpoints 0 bInterfaceClass 1 Audio bInterfaceSubClass 2 Streaming bInterfaceProtocol 0 iInterface 0 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 2 bAlternateSetting 1 bNumEndpoints 1 bInterfaceClass 1 Audio bInterfaceSubClass 2 Streaming bInterfaceProtocol 0 iInterface 0 AudioStreaming Interface Descriptor: bLength 7 bDescriptorType 36 bDescriptorSubtype 1 (AS_GENERAL) bTerminalLink 7 bDelay 0 frames wFormatTag 0x0001 PCM AudioStreaming Interface Descriptor: bLength 26 bDescriptorType 36 bDescriptorSubtype 2 (FORMAT_TYPE) bFormatType 1 (FORMAT_TYPE_I) bNrChannels 2 bSubframeSize 2 bBitResolution 16 bSamFreqType 6 Discrete tSamFreq[ 0] 8000 tSamFreq[ 1] 16000 tSamFreq[ 2] 32000 tSamFreq[ 3] 44100 tSamFreq[ 4] 48000 tSamFreq[ 5] 96000 Endpoint Descriptor: bLength 9 bDescriptorType 5 bEndpointAddress 0x82 EP 2 IN bmAttributes 9 Transfer Type Isochronous Synch Type Adaptive Usage Type Data wMaxPacketSize 0x0184 1x 388 bytes bInterval 1 bRefresh 0 bSynchAddress 0 AudioStreaming Endpoint Descriptor: bLength 7 bDescriptorType 37 bDescriptorSubtype 1 (EP_GENERAL) bmAttributes 0x01 Sampling Frequency bLockDelayUnits 2 Decoded PCM samples wLockDelay 0x0002 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 2 bAlternateSetting 2 bNumEndpoints 1 bInterfaceClass 1 Audio bInterfaceSubClass 2 Streaming bInterfaceProtocol 0 iInterface 0 AudioStreaming Interface Descriptor: bLength 7 bDescriptorType 36 bDescriptorSubtype 1 (AS_GENERAL) bTerminalLink 7 bDelay 0 frames wFormatTag 0x0001 PCM AudioStreaming Interface Descriptor: bLength 26 bDescriptorType 36 bDescriptorSubtype 2 (FORMAT_TYPE) bFormatType 1 (FORMAT_TYPE_I) bNrChannels 2 bSubframeSize 3 bBitResolution 24 bSamFreqType 6 Discrete tSamFreq[ 0] 8000 tSamFreq[ 1] 16000 tSamFreq[ 2] 32000 tSamFreq[ 3] 44100 tSamFreq[ 4] 48000 tSamFreq[ 5] 96000 Endpoint Descriptor: bLength 9 bDescriptorType 5 bEndpointAddress 0x82 EP 2 IN bmAttributes 9 Transfer Type Isochronous Synch Type Adaptive Usage Type Data wMaxPacketSize 0x0246 1x 582 bytes bInterval 1 bRefresh 0 bSynchAddress 0 AudioStreaming Endpoint Descriptor: bLength 7 bDescriptorType 37 bDescriptorSubtype 1 (EP_GENERAL) bmAttributes 0x01 Sampling Frequency bLockDelayUnits 2 Decoded PCM samples wLockDelay 0x0002 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 3 bAlternateSetting 0 bNumEndpoints 0 bInterfaceClass 1 Audio bInterfaceSubClass 2 Streaming bInterfaceProtocol 0 iInterface 0 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 3 bAlternateSetting 1 bNumEndpoints 1 bInterfaceClass 1 Audio bInterfaceSubClass 2 Streaming bInterfaceProtocol 0 iInterface 0 AudioStreaming Interface Descriptor: bLength 7 bDescriptorType 36 bDescriptorSubtype 1 (AS_GENERAL) bTerminalLink 9 bDelay 0 frames wFormatTag 0x0001 PCM AudioStreaming Interface Descriptor: bLength 20 bDescriptorType 36 bDescriptorSubtype 2 (FORMAT_TYPE) bFormatType 1 (FORMAT_TYPE_I) bNrChannels 2 bSubframeSize 2 bBitResolution 16 bSamFreqType 4 Discrete tSamFreq[ 0] 32000 tSamFreq[ 1] 44100 tSamFreq[ 2] 48000 tSamFreq[ 3] 96000 Endpoint Descriptor: bLength 9 bDescriptorType 5 bEndpointAddress 0x03 EP 3 OUT bmAttributes 9 Transfer Type Isochronous Synch Type Adaptive Usage Type Data wMaxPacketSize 0x0184 1x 388 bytes bInterval 1 bRefresh 0 bSynchAddress 0 AudioStreaming Endpoint Descriptor: bLength 7 bDescriptorType 37 bDescriptorSubtype 1 (EP_GENERAL) bmAttributes 0x01 Sampling Frequency bLockDelayUnits 2 Decoded PCM samples wLockDelay 0x0002 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 3 bAlternateSetting 2 bNumEndpoints 1 bInterfaceClass 1 Audio bInterfaceSubClass 2 Streaming bInterfaceProtocol 0 iInterface 0 AudioStreaming Interface Descriptor: bLength 7 bDescriptorType 36 bDescriptorSubtype 1 (AS_GENERAL) bTerminalLink 9 bDelay 0 frames wFormatTag 0x0001 PCM AudioStreaming Interface Descriptor: bLength 20 bDescriptorType 36 bDescriptorSubtype 2 (FORMAT_TYPE) bFormatType 1 (FORMAT_TYPE_I) bNrChannels 2 bSubframeSize 3 bBitResolution 24 bSamFreqType 4 Discrete tSamFreq[ 0] 32000 tSamFreq[ 1] 44100 tSamFreq[ 2] 48000 tSamFreq[ 3] 96000 Endpoint Descriptor: bLength 9 bDescriptorType 5 bEndpointAddress 0x03 EP 3 OUT bmAttributes Couldn't open device, some information will be missing 9 Transfer Type Isochronous Synch Type Adaptive Usage Type Data wMaxPacketSize 0x0246 1x 582 bytes bInterval 1 bRefresh 0 bSynchAddress 0 AudioStreaming Endpoint Descriptor: bLength 7 bDescriptorType 37 bDescriptorSubtype 1 (EP_GENERAL) bmAttributes 0x01 Sampling Frequency bLockDelayUnits 2 Decoded PCM samples wLockDelay 0x0002 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 3 bAlternateSetting 3 bNumEndpoints 1 bInterfaceClass 1 Audio bInterfaceSubClass 2 Streaming bInterfaceProtocol 0 iInterface 0 AudioStreaming Interface Descriptor: bLength 7 bDescriptorType 36 bDescriptorSubtype 1 (AS_GENERAL) bTerminalLink 9 bDelay 0 frames wFormatTag 0x2001 IEC1937_AC-3 AudioStreaming Interface Descriptor: bLength 11 bDescriptorType 36 bDescriptorSubtype 2 (FORMAT_TYPE) bFormatType 3 (FORMAT_TYPE_III) bNrChannels 2 bSubframeSize 2 bBitResolution 16 bSamFreqType 1 Discrete tSamFreq[ 0] 48000 Endpoint Descriptor: bLength 9 bDescriptorType 5 bEndpointAddress 0x03 EP 3 OUT bmAttributes 9 Transfer Type Isochronous Synch Type Adaptive Usage Type Data wMaxPacketSize 0x00c0 1x 192 bytes bInterval 1 bRefresh 0 bSynchAddress 0 AudioStreaming Endpoint Descriptor: bLength 7 bDescriptorType 37 bDescriptorSubtype 1 (EP_GENERAL) bmAttributes 0x01 Sampling Frequency bLockDelayUnits 2 Decoded PCM samples wLockDelay 0x0002 Bus 001 Device 003: ID 0424:ec00 Standard Microsystems Corp. SMSC9512/9514 Fast Ethernet Adapter Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 2.00 bDeviceClass 255 Vendor Specific Class bDeviceSubClass 0 bDeviceProtocol 1 bMaxPacketSize0 64 idVendor 0x0424 Standard Microsystems Corp. idProduct 0xec00 SMSC9512/9514 Fast Ethernet Adapter bcdDevice 2.00 iManufacturer 0 iProduct 0 iSerial 0 bNumConfigurations 1 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x0027 bNumInterfaces 1 bConfigurationValue 1 iConfiguration 0 bmAttributes 0xe0 Self Powered Remote Wakeup MaxPower 2mA Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 3 bInterfaceClass 255 Vendor Specific Class bInterfaceSubClass 0 bInterfaceProtocol 255 iInterface 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 2 Transfer Type Bulk Synch Type None Usage Type Data wMaxPacketSize 0x0200 1x 512 bytes bInterval 0 Endpoint Descriptor: bLength 7 bDescripCouldn't open device, some information will be missing Couldn't open device, some information will be missing torType 5 bEndpointAddress 0x02 EP 2 OUT bmAttributes 2 Transfer Type Bulk Synch Type None Usage Type Data wMaxPacketSize 0x0200 1x 512 bytes bInterval 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x83 EP 3 IN bmAttributes 3 Transfer Type Interrupt Synch Type None Usage Type Data wMaxPacketSize 0x0010 1x 16 bytes bInterval 4 Bus 001 Device 002: ID 0424:9514 Standard Microsystems Corp. SMC9514 Hub Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 2.00 bDeviceClass 9 Hub bDeviceSubClass 0 bDeviceProtocol 2 TT per port bMaxPacketSize0 64 idVendor 0x0424 Standard Microsystems Corp. idProduct 0x9514 SMC9514 Hub bcdDevice 2.00 iManufacturer 0 iProduct 0 iSerial 0 bNumConfigurations 1 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x0029 bNumInterfaces 1 bConfigurationValue 1 iConfiguration 0 bmAttributes 0xe0 Self Powered Remote Wakeup MaxPower 2mA Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 1 bInterfaceClass 9 Hub bInterfaceSubClass 0 bInterfaceProtocol 1 Single TT iInterface 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 3 Transfer Type Interrupt Synch Type None Usage Type Data wMaxPacketSize 0x0001 1x 1 bytes bInterval 12 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 1 bNumEndpoints 1 bInterfaceClass 9 Hub bInterfaceSubClass 0 bInterfaceProtocol 2 TT per port iInterface 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 3 Transfer Type Interrupt Synch Type None Usage Type Data wMaxPacketSize 0x0001 1x 1 bytes bInterval 12 Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 2.00 bDeviceClass 9 Hub bDeviceSubClass 0 bDeviceProtocol 1 Single TT bMaxPacketSize0 64 idVendor 0x1d6b Linux Foundation idProduct 0x0002 2.0 root hub bcdDevice 6.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 2 11:21 ? 00:00:06 /sbin/init root 2 0 0 11:21 ? 00:00:00 [kthreadd] root 3 2 0 11:21 ? 00:00:00 [rcu_gp] root 4 2 0 11:21 ? 00:00:00 [rcu_par_gp] root 5 2 0 11:21 ? 00:00:00 [slub_flushwq] root 6 2 0 11:21 ? 00:00:00 [netns] root 7 2 0 11:21 ? 00:00:00 [kworker/0:0-events] root 8 2 0 11:21 ? 00:00:00 [kworker/0:0H-mmc_complete] root 9 2 1 11:21 ? 00:00:02 [kworker/u8:0-events_unbound] root 10 2 0 11:21 ? 00:00:00 [mm_percpu_wq] root 11 2 0 11:21 ? 00:00:00 [rcu_tasks_rude_kthread] root 12 2 0 11:21 ? 00:00:00 [rcu_tasks_trace_kthread] root 13 2 0 11:21 ? 00:00:00 [ksoftirqd/0] root 14 2 0 11:21 ? 00:00:00 [rcu_sched] root 15 2 0 11:21 ? 00:00:00 [migration/0] root 16 2 0 11:21 ? 00:00:00 [cpuhp/0] root 17 2 0 11:21 ? 00:00:00 [cpuhp/1] root 18 2 0 11:21 ? 00:00:00 [migration/1] root 19 2 0 11:21 ? 00:00:00 [ksoftirqd/1] root 20 2 0 11:21 ? 00:00:00 [kworker/1:0-events] root 21 2 0 11:21 ? 00:00:00 [kworker/1:0H-kblockd] root 22 2 0 11:21 ? 00:00:00 [cpuhp/2] root 23 2 0 11:21 ? 00:00:00 [migration/2] root 24 2 0 11:21 ? 00:00:00 [ksoftirqd/2] root 25 2 0 11:21 ? 00:00:00 [kworker/2:0-cgroup_destroy] root 26 2 0 11:21 ? 00:00:00 [kworker/2:0H-kblockd] root 27 2 0 11:21 ? 00:00:00 [cpuhp/3] root 28 2 0 11:21 ? 00:00:00 [migration/3] root 29 2 0 11:21 ? 00:00:00 [ksoftirqd/3] root 30 2 0 11:21 ? 00:00:00 [kworker/3:0-cgroup_destroy] root 31 2 0 11:21 ? 00:00:00 [kworker/3:0H-events_highpri] root 32 2 0 11:21 ? 00:00:00 [kdevtmpfs] root 33 2 0 11:21 ? 00:00:00 [inet_frag_wq] root 34 2 0 11:21 ? 00:00:00 [kworker/0:1-events] root 35 2 0 11:21 ? 00:00:00 [kauditd] root 36 2 0 11:21 ? 00:00:00 [khungtaskd] root 37 2 0 11:21 ? 00:00:00 [kworker/u8:1-loop0] root 38 2 0 11:21 ? 00:00:00 [oom_reaper] root 39 2 0 11:21 ? 00:00:00 [writeback] root 40 2 0 11:21 ? 00:00:00 [kcompactd0] root 41 2 0 11:21 ? 00:00:00 [kintegrityd] root 42 2 0 11:21 ? 00:00:00 [kblockd] root 43 2 0 11:21 ? 00:00:00 [blkcg_punt_bio] root 44 2 0 11:21 ? 00:00:00 [watchdogd] root 45 2 0 11:21 ? 00:00:01 [kworker/u8:2-loop0] root 46 2 0 11:21 ? 00:00:00 [kworker/1:1-events] root 47 2 0 11:21 ? 00:00:00 [kworker/3:1-cgroup_destroy] root 48 2 0 11:21 ? 00:00:00 [kworker/3:1H-kblockd] root 49 2 0 11:21 ? 00:00:00 [rpciod] root 50 2 0 11:21 ? 00:00:00 [xprtiod] root 51 2 0 11:21 ? 00:00:00 [kworker/u8:3] root 52 2 0 11:21 ? 00:00:00 [kworker/2:1-events] root 53 2 0 11:21 ? 00:00:00 [kswapd0] root 54 2 0 11:21 ? 00:00:00 [nfsiod] root 56 2 0 11:21 ? 00:00:00 [kworker/3:2-cgroup_destroy] root 59 2 0 11:21 ? 00:00:00 [hwrng] root 60 2 0 11:21 ? 00:00:00 [iscsi_conn_clea] root 61 2 0 11:21 ? 00:00:00 [dwc_otg] root 62 2 0 11:21 ? 00:00:00 [DWC Notificatio] root 63 2 0 11:21 ? 00:00:00 [vchiq-slot/0] root 64 2 0 11:21 ? 00:00:00 [vchiq-recy/0] root 65 2 0 11:21 ? 00:00:00 [vchiq-sync/0] root 66 2 0 11:21 ? 00:00:00 [zswap-shrink] root 67 2 0 11:21 ? 00:00:00 [kworker/u9:0-hci0] root 87 2 0 11:21 ? 00:00:00 [kworker/2:2-events] root 88 2 0 11:21 ? 00:00:00 [kworker/1:2-events] root 89 2 0 11:21 ? 00:00:00 [mmc_complete] root 90 2 0 11:21 ? 00:00:00 [kworker/1:1H-kblockd] root 91 2 0 11:21 ? 00:00:00 [kworker/0:1H-kblockd] root 118 2 0 11:21 ? 00:00:00 [kworker/1:2H-kblockd] root 122 2 0 11:21 ? 00:00:00 [jbd2/mmcblk0p2-8] root 123 2 0 11:21 ? 00:00:00 [ext4-rsv-conver] root 127 2 0 11:21 ? 00:00:00 [irq/199-usb-001:003:01] root 144 2 0 11:21 ? 00:00:00 [kworker/2:1H-kblockd] root 159 2 0 11:21 ? 00:00:00 [kworker/3:2H-kblockd] root 160 2 0 11:21 ? 00:00:00 [jbd2/mmcblk0p3-8] root 161 2 0 11:21 ? 00:00:00 [ext4-rsv-conver] root 169 2 0 11:21 ? 00:00:00 [kworker/0:2H-kblockd] root 170 2 0 11:21 ? 00:00:00 [mld] root 171 2 0 11:21 ? 00:00:00 [ipv6_addrconf] root 194 1 0 11:21 ? 00:00:01 /lib/systemd/systemd-journald root 201 2 0 11:21 ? 00:00:00 [kworker/0:2-events] root 206 2 0 11:21 ? 00:00:00 [kworker/2:2H-kblockd] root 211 2 0 11:21 ? 00:00:00 [kworker/2:3-events] root 212 2 0 11:21 ? 00:00:00 [kworker/2:4-mm_percpu_wq] root 213 2 0 11:21 ? 00:00:00 [kworker/2:5-events] root 214 2 0 11:21 ? 00:00:00 [kworker/2:6-events_freezable_power_] root 224 1 0 11:21 ? 00:00:01 /lib/systemd/systemd-udevd root 225 2 0 11:21 ? 00:00:00 [kworker/0:3-events] root 250 2 0 11:21 ? 00:00:00 [rc1] root 255 2 0 11:21 ? 00:00:00 [vchiq-keep/0] root 258 2 0 11:21 ? 00:00:00 [SMIO] root 300 2 0 11:21 ? 00:00:00 [mmal-vchiq] root 302 2 0 11:21 ? 00:00:00 [mmal-vchiq] root 304 2 0 11:21 ? 00:00:00 [mmal-vchiq] root 305 2 0 11:21 ? 00:00:00 [mmal-vchiq] root 306 2 0 11:21 ? 00:00:00 [mmal-vchiq] root 307 2 0 11:21 ? 00:00:00 [mmal-vchiq] root 308 2 0 11:21 ? 00:00:00 [mmal-vchiq] root 331 2 0 11:21 ? 00:00:00 [kworker/u8:4-loop0] root 346 2 0 11:21 ? 00:00:00 [kworker/u9:1-brcmf_wq/mmc1:0001:1] root 349 2 0 11:21 ? 00:00:00 [kworker/u9:2-brcmf_wq/mmc1:0001:1] root 356 2 0 11:21 ? 00:00:00 [cfg80211] root 367 2 0 11:21 ? 00:00:00 [brcmf_wq/mmc1:0] root 368 2 0 11:21 ? 00:00:00 [brcmf_wdog/mmc1:0001:1] root 383 2 0 11:21 ? 00:00:00 [kworker/1:3-events] root 406 2 0 11:21 ? 00:00:00 [kworker/0:4-mm_percpu_wq] _rpc 467 1 0 11:21 ? 00:00:00 /sbin/rpcbind -f -w root 474 1 0 11:21 ? 00:00:01 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 root 482 1 0 11:21 ? 00:00:00 /lib/systemd/systemd-logind message+ 485 1 0 11:21 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 489 1 0 11:21 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant nobody 505 1 0 11:21 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* volumio 512 1 0 11:21 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 515 1 0 11:21 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon avahi 519 1 0 11:21 ? 00:00:00 avahi-daemon: running [volumio3dev.local] root 554 1 0 11:21 ? 00:00:00 /usr/sbin/cron -f avahi 573 519 0 11:21 ? 00:00:00 avahi-daemon: chroot helper daemon 578 1 0 11:21 ? 00:00:00 /usr/sbin/atd -f root 585 2 0 11:21 ? 00:00:00 [kworker/3:3-cgroup_destroy] root 586 2 0 11:21 ? 00:00:00 [kworker/3:4-cgroup_destroy] root 588 2 0 11:21 ? 00:00:00 [kworker/3:5-mm_percpu_wq] volumio 602 1 0 11:21 ? 00:00:02 /usr/bin/python3 /usr/bin/gunicorn3 --workers 3 --bind 0.0.0.0:8000 views:app --timeout 120 --reload root 622 1 0 11:21 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 652 1 0 11:21 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I root 662 1 0 11:21 ? 00:00:00 /usr/sbin/sshd -D root 664 2 0 11:21 ? 00:00:00 [kworker/1:4-cgroup_destroy] root 668 1 0 11:21 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux root 673 1 0 11:21 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap ntp 678 1 0 11:21 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 root 942 622 0 11:21 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group volumio 976 602 1 11:21 ? 00:00:04 /usr/bin/python3 /usr/bin/gunicorn3 --workers 3 --bind 0.0.0.0:8000 views:app --timeout 120 --reload volumio 978 602 1 11:21 ? 00:00:04 /usr/bin/python3 /usr/bin/gunicorn3 --workers 3 --bind 0.0.0.0:8000 views:app --timeout 120 --reload volumio 980 602 1 11:21 ? 00:00:04 /usr/bin/python3 /usr/bin/gunicorn3 --workers 3 --bind 0.0.0.0:8000 views:app --timeout 120 --reload root 1020 1 0 11:21 ? 00:00:00 /sbin/dhcpcd eth0 dnsmasq 1050 1 0 11:21 ? 00:00:00 /usr/sbin/dnsmasq -x /run/dnsmasq/dnsmasq.pid -u dnsmasq -7 /etc/dnsmasq.d,.dpkg-dist,.dpkg-old,.dpkg-new --local-service root 1058 1 0 11:21 ? 00:00:00 /usr/sbin/hostapd -B -P /run/hostapd.pid -B /etc/hostapd/hostapd.conf volumio 1061 1 35 11:21 ? 00:01:16 /usr/bin/node /volumio/index.js root 1085 1 0 11:22 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 1089 1085 0 11:22 ? 00:00:00 winbindd: domain child [VOLUMIO3DEV] root 1090 1 0 11:22 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 1092 1090 0 11:22 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 1093 1090 0 11:22 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 1094 1085 0 11:22 ? 00:00:00 winbindd: idmap child root 1095 1085 0 11:22 ? 00:00:00 winbindd: domain child [BUILTIN] root 1096 1090 0 11:22 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 1130 2 0 11:22 ? 00:00:00 [cifsiod] root 1131 2 0 11:22 ? 00:00:00 [smb3decryptd] root 1132 2 0 11:22 ? 00:00:00 [cifsfileinfoput] root 1133 2 0 11:22 ? 00:00:00 [cifsoplockd] root 1134 2 0 11:22 ? 00:00:00 [deferredclose] root 1136 2 0 11:22 ? 00:00:01 [cifsd] volumio 1150 1061 0 11:22 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1157 1150 0 11:22 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1158 1150 0 11:22 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1159 1150 0 11:22 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1228 1 0 11:22 ? 00:00:01 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf mpd 1261 1 2 11:22 ? 00:00:04 /usr/bin/mpd --systemd volumio 1268 1 0 11:22 ? 00:00:00 /bin/sh /bin/start-go-liberspot.sh volumio 1273 1268 0 11:22 ? 00:00:00 /usr/bin/go-librespot -config_path /tmp/go-librespot-config.yml -credentials_path /data/configuration/music_service/spop/spotifycredentials.json root 1293 2 0 11:22 ? 00:00:00 [kworker/1:5-mm_percpu_wq] root 1294 2 0 11:22 ? 00:00:00 [kworker/1:6-events] shairpo+ 1296 1 0 11:22 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf volumio 1301 978 0 11:22 ? 00:00:00 [sh] volumio 1302 980 0 11:22 ? 00:00:00 [sh] volumio 1305 976 0 11:22 ? 00:00:00 [sh] root 1328 662 0 11:22 ? 00:00:00 sshd: volumio [priv] volumio 1330 1061 0 11:22 ? 00:00:01 /usr/bin/node /myvolumio/plugins/music_service/streaming_services/streamingProxy.js volumio 1383 1 0 11:22 ? 00:00:00 /lib/systemd/systemd --user volumio 1384 1383 0 11:22 ? 00:00:00 (sd-pam) volumio 1402 1328 0 11:22 ? 00:00:00 sshd: volumio@pts/0 volumio 1403 1402 0 11:22 pts/0 00:00:00 -bash volumio 1406 1403 0 11:22 pts/0 00:00:00 journalctl -f volumio 1532 1061 1 11:25 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'No info available' volumio 1533 1532 39 11:25 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js No info available volumio 1563 1533 0 11:25 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 1564 1563 0 11:25 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.5G 1.1G 1.3G 47% /imgpart /dev/loop0 490M 490M 0 100% /static overlay 27G 854M 25G 4% / devtmpfs 423M 0 423M 0% /dev tmpfs 462M 0 462M 0% /dev/shm tmpfs 462M 9.0M 453M 2% /run tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 462M 0 462M 0% /sys/fs/cgroup tmpfs 20M 36K 20M 1% /var/log tmpfs 462M 84K 462M 1% /tmp tmpfs 462M 0 462M 0% /var/spool/cups tmpfs 462M 0 462M 0% /var/spool/cups/tmp /dev/mmcblk0p1 92M 66M 27M 72% /boot //192.168.4.50/Multimedia/Music 8.0T 2.6T 5.4T 33% /mnt/NAS/Music tmpfs 93M 0 93M 0% /run/user/1000 # 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=432140k,nr_inodes=108035,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/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) cgroup on /sys/fs/cgroup/net_cls,net_prio type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls,net_prio) cgroup on /sys/fs/cgroup/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event) cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=37,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/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4) tmpfs on /tmp type tmpfs (rw,noatime,mode=755) tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755) tmpfs on /var/spool/cups/tmp type tmpfs (rw,noatime,mode=755) /dev/mmcblk0p1 on /boot type vfat (rw,nosuid,nodev,noexec,relatime,fmask=0111,dmask=0000,allow_utime=0022,codepage=437,iocharset=ascii,shortname=mixed,utf8,errors=remount-ro,user) //192.168.4.50/Multimedia/Music on /mnt/NAS/Music type cifs (rw,relatime,vers=2.1,cache=strict,username=Glenn,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.4.50,file_mode=0666,dir_mode=0777,iocharset=utf8,soft,nounix,serverino,mapposix,rsize=1048576,wsize=1048576,bsize=1048576,echo_interval=60,actimeo=1,closetimeo=1) tmpfs on /run/user/1000 type tmpfs (rw,nosuid,nodev,relatime,size=94444k,mode=700,uid=1000,gid=1000) # netstat -natp --------------- (Not all processes could be identified, non-owned process info will not be shown, you would have to be root to see it all.) Active Internet connections (servers and established) Proto Recv-Q Send-Q Local Address Foreign Address State PID/Program name tcp 0 0 0.0.0.0:111 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:53 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:49152 0.0.0.0:* LISTEN 1228/upmpdcli tcp 0 0 0.0.0.0:22 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:139 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:8000 0.0.0.0:* LISTEN 602/python3 tcp 0 0 0.0.0.0:49149 0.0.0.0:* LISTEN 1228/upmpdcli tcp 0 0 0.0.0.0:445 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:5000 0.0.0.0:* LISTEN - tcp 0 0 127.0.0.1:38674 127.0.0.1:6599 ESTABLISHED 1228/upmpdcli tcp 0 0 127.0.0.1:59700 127.0.0.1:3000 ESTABLISHED 1061/node tcp 0 0 127.0.0.1:44016 127.0.0.1:3000 ESTABLISHED 1061/node tcp 0 0 127.0.0.1:35288 127.0.0.1:6600 ESTABLISHED 1061/node tcp 0 0 192.168.4.180:36664 192.168.4.43:3000 ESTABLISHED 1061/node tcp 0 0 127.0.0.1:49454 127.0.0.1:6599 ESTABLISHED 1228/upmpdcli tcp 1 0 127.0.0.1:49358 127.0.0.1:3000 CLOSE_WAIT 980/python3 tcp 0 0 127.0.0.1:41746 127.0.0.1:9879 ESTABLISHED 1061/node tcp 56367 0 127.0.0.1:59694 127.0.0.1:3000 ESTABLISHED 976/python3 tcp 56367 0 127.0.0.1:59690 127.0.0.1:3000 ESTABLISHED 978/python3 tcp 0 0 192.168.4.180:56816 35.190.39.113:443 ESTABLISHED 1061/node tcp 0 0 127.0.0.1:48830 127.0.0.1:3000 ESTABLISHED 1061/node tcp 0 0 127.0.0.1:45568 127.0.0.1:6600 ESTABLISHED 1061/node tcp 0 0 192.168.4.180:35164 192.168.4.50:445 ESTABLISHED - tcp 56367 0 127.0.0.1:59676 127.0.0.1:3000 ESTABLISHED 980/python3 tcp 0 0 127.0.0.1:48828 127.0.0.1:3000 ESTABLISHED 512/volumio-remote- tcp 1 0 127.0.0.1:49368 127.0.0.1:3000 CLOSE_WAIT 978/python3 tcp 1 0 127.0.0.1:49370 127.0.0.1:3000 CLOSE_WAIT 976/python3 tcp 0 0 192.168.4.180:22 192.168.4.16:57587 ESTABLISHED - tcp 0 0 127.0.0.1:60664 127.0.0.1:3000 ESTABLISHED 1061/node tcp 0 0 127.0.0.1:49448 127.0.0.1:6599 TIME_WAIT - tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::53 :::* LISTEN - tcp6 0 0 :::22 :::* LISTEN - tcp6 0 0 :::3245 :::* LISTEN 1330/node tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::9879 :::* LISTEN 1273/go-librespot tcp6 0 0 :::44523 :::* LISTEN 1273/go-librespot tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::6599 :::* LISTEN 1061/node tcp6 0 0 :::4004 :::* LISTEN 1061/node tcp6 0 0 :::3005 :::* LISTEN 1061/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 1061/node tcp6 0 0 :::3001 :::* LISTEN 1150/node tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 127.0.0.1:3000 127.0.0.1:60664 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.21:58420 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.16:57567 ESTABLISHED 1061/node tcp6 0 0 127.0.0.1:6600 127.0.0.1:35288 ESTABLISHED - tcp6 0 0 127.0.0.1:3000 127.0.0.1:59690 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.16:57687 TIME_WAIT - tcp6 0 0 192.168.4.180:3000 192.168.4.21:58354 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.21:58292 ESTABLISHED 1061/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:44016 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.16:57724 TIME_WAIT - tcp6 0 0 127.0.0.1:6600 127.0.0.1:45568 ESTABLISHED - tcp6 0 0 192.168.4.180:3000 192.168.4.21:58284 ESTABLISHED 1061/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:38674 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.16:57568 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.16:57726 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:59694 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.43:34882 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.16:57699 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.16:57739 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.16:57730 TIME_WAIT - tcp6 0 0 192.168.4.180:3000 192.168.4.16:57704 ESTABLISHED 1061/node tcp6 0 0 127.0.0.1:9879 127.0.0.1:41746 ESTABLISHED 1273/go-librespot tcp6 0 0 192.168.4.180:3000 192.168.4.16:57688 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.21:58410 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.16:57725 TIME_WAIT - tcp6 0 0 192.168.4.180:3000 192.168.4.16:57738 FIN_WAIT2 - tcp6 0 0 127.0.0.1:3000 127.0.0.1:48828 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.21:58550 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.16:57698 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.16:57705 ESTABLISHED 1061/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:49454 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.21:58316 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.16:57694 TIME_WAIT - tcp6 0 0 192.168.4.180:3000 192.168.4.16:57731 FIN_WAIT2 - tcp6 0 0 192.168.4.180:3000 192.168.4.16:57706 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.21:58380 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.16:57652 TIME_WAIT - tcp6 0 0 192.168.4.180:3000 192.168.4.21:58268 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.16:57723 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:59676 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.16:57703 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.16:57686 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:48830 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.16:57736 FIN_WAIT2 - tcp6 0 0 192.168.4.180:3000 192.168.4.21:58332 ESTABLISHED 1061/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:59700 ESTABLISHED 1061/node tcp6 0 0 192.168.4.180:3000 192.168.4.21:57118 TIME_WAIT - # cat /etc/asound.conf --------------- pcm.!default { type empty slave.pcm "volumio" } pcm.volumio { type empty slave.pcm "volumioOutput" } # There is always a plug before the hardware to be safe pcm.volumioOutput { type plug slave.pcm "volumioHw" } pcm.volumioHw { type hw card "Audio" } # cat /var/log/mpd.log --------------- Feb 03 11:22 : zeroconf: No global port, disabling zeroconf Feb 03 11:22 : mixer: Failed to read mixer for 'alsa': failed to attach to default: No such file or directory Feb 03 11:22 : exception: No such directory Feb 03 11:24 : exception: No such directory Feb 03 11:24 : output: Failed to open mixer for 'alsa': failed to attach to default: No such file or directory # 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: 'pandora', name: 'pandora', category: 'music_service', version: '2.12.1', icon: 'fa-lightbulb-o', 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: 'Amplifier Switch', name: 'ampswitch', category: 'system_controller', version: '0.2.0', icon: 'fa-power-off', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'AutoStart', name: 'autostart', category: 'system_controller', version: '3.0.4', icon: 'fa-lightbulb-o', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'System information', name: 'Systeminfo', category: 'user_interface', version: '3.0.0', icon: 'fa-info-circle', 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 } ] ------------------------------------------ # cat /data/configuration/plugins.json --------------- { "music_service": { "airplay_emulation": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "inputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpd": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "webradio": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "example_plugin": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "last_100": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp_browser": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "streaming_services": { "status": { "type": "string", "value": "STARTED" } }, "pandora": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "spop": { "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" } } }, "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" } } }, "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" } }, "Systeminfo": { "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" } }, "ampswitch": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "autostart": { "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 "very high" threads "1" } audio_output { type "alsa" name "alsa" device "volumio" dop "no" format "*:*:2" } audio_output { type "fifo" enabled "no" name "multiroom" path "/tmp/snapfifo" format "44100:16:2" } #replaygain "album" #replaygain_preamp "0" volume_normalization "no" ############################################################################### # MPD Internal Buffering ###################################################### audio_buffer_size "2048" ############################################################################### # Resource Limitations ######################################################## #connection_timeout "60" max_connections "20" max_playlist_length "81920" max_command_list_size "81920" max_output_buffer_size "81920" ############################################################################### # Character Encoding ########################################################## filesystem_charset "UTF-8" ############################################################################### # volumio endpointstest --------------- TESTING REMOTE ENDPOINTS https://google.com, 517 ms: OK https://www.googleapis.com, 330 ms: OK https://securetoken.googleapis.com, 315 ms: OK https://myvolumio.firebaseio.com, 412 ms: OK https://functions.volumio.cloud, 404 ms: OK https://oauth-performer.dfs.volumio.org, 713 ms: OK https://browsing-performer.dfs.volumio.org, 892 ms: OK http://cddb.volumio.org, 6086 ms: OK https://functions.volumio.cloud, 488 ms: OK http://pushupdates.volumio.org, 644 ms: OK http://plugins.volumio.org, 5145 ms: OK https://database.volumio.cloud, 620 ms: OK https://radio-directory.firebaseapp.com, 627 ms: OK ---------- REMOTE ENDPOINTS TEST OK, all Endpoints are reachable ---------- # cat /data/updatesHistoryFile --------------- --- Thu Mar 30 2023 15:51:01 GMT+0000 (Coordinated Universal Time) {"systemversion":"3.449","builddate":"Thu 30 Mar 2023 05:45:03 PM CEST","variant":"volumio","hardware":"pi"} ------ Fri Jul 07 2023 04:01:20 GMT-0700 (Mountain Standard Time) {"systemversion":"3.512","builddate":"Sun 25 Jun 2023 07:20:58 PM CEST","variant":"volumio","hardware":"pi"} ------ Fri Oct 13 2023 05:08:27 GMT-0700 (Mountain Standard Time) {"systemversion":"3.546","builddate":"Wed 27 Sep 2023 11:35:49 AM CEST","variant":"volumio","hardware":"pi"} ------ Wed Oct 18 2023 03:59:35 GMT-0700 (Mountain Standard Time) {"systemversion":"3.559","builddate":"Mon 16 Oct 2023 02:37:48 PM CEST","variant":"volumio","hardware":"pi"} ------ Thu Oct 19 2023 03:33:54 GMT-0700 (Mountain Standard Time) {"systemversion":"3.562","builddate":"Wed 18 Oct 2023 11:21:03 PM CEST","variant":"volumio","hardware":"pi"} ------ Fri Oct 20 2023 03:24:27 GMT-0700 (Mountain Standard Time) {"systemversion":"3.566","builddate":"Thu 19 Oct 2023 06:31:05 PM CEST","variant":"volumio","hardware":"pi"} ------ Sat Oct 21 2023 03:52:09 GMT-0700 (Mountain Standard Time) {"systemversion":"3.569","builddate":"Fri 20 Oct 2023 03:38:28 PM CEST","variant":"volumio","hardware":"pi"} ------ Wed Jan 31 2024 10:41:36 GMT-0700 (Mountain Standard Time) {"systemversion":"3.611","builddate":"Tue 30 Jan 2024 03:58:37 PM CET","variant":"volumio","hardware":"pi"} --- # sudo journalctl -p 7 --------------- -- Logs begin at Thu 2019-02-14 03:11:59 MST, end at Sat 2024-02-03 11:25:46 MST. -- Feb 14 03:11:59 volumio3dev kernel: Booting Linux on physical CPU 0x0 Feb 14 03:11:59 volumio3dev kernel: Linux version 6.1.69-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1710 SMP Thu Dec 21 13:14:13 GMT 2023 Feb 14 03:11:59 volumio3dev kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d Feb 14 03:11:59 volumio3dev kernel: CPU: div instructions available: patching division code Feb 14 03:11:59 volumio3dev kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Feb 14 03:11:59 volumio3dev kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2 Feb 14 03:11:59 volumio3dev kernel: random: crng init done Feb 14 03:11:59 volumio3dev kernel: Memory policy: Data cache writealloc Feb 14 03:11:59 volumio3dev kernel: Reserved memory: created CMA memory pool at 0x37400000, size 64 MiB Feb 14 03:11:59 volumio3dev kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Feb 14 03:11:59 volumio3dev kernel: Zone ranges: Feb 14 03:11:59 volumio3dev kernel: DMA [mem 0x0000000000000000-0x000000003b3fffff] Feb 14 03:11:59 volumio3dev kernel: Normal empty Feb 14 03:11:59 volumio3dev kernel: Movable zone start for each node Feb 14 03:11:59 volumio3dev kernel: Early memory node ranges Feb 14 03:11:59 volumio3dev kernel: node 0: [mem 0x0000000000000000-0x000000003b3fffff] Feb 14 03:11:59 volumio3dev kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000003b3fffff] Feb 14 03:11:59 volumio3dev kernel: percpu: Embedded 17 pages/cpu s37012 r8192 d24428 u69632 Feb 14 03:11:59 volumio3dev kernel: pcpu-alloc: s37012 r8192 d24428 u69632 alloc=17*4096 Feb 14 03:11:59 volumio3dev kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 14 03:11:59 volumio3dev kernel: Built 1 zonelists, mobility grouping on. Total pages: 240555 Feb 14 03:11:59 volumio3dev 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=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=/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 Feb 14 03:11:59 volumio3dev kernel: Kernel parameter elevator= does not have any effect anymore. Please use sysfs to set IO scheduler for individual devices. Feb 14 03:11:59 volumio3dev kernel: Unknown kernel command line parameters "splash imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh bootdelay=5", will be passed to user space. Feb 14 03:11:59 volumio3dev kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Feb 14 03:11:59 volumio3dev kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 14 03:11:59 volumio3dev kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 14 03:11:59 volumio3dev kernel: Memory: 864284K/970752K available (10240K kernel code, 1455K rwdata, 3144K rodata, 1024K init, 611K bss, 40932K reserved, 65536K cma-reserved) Feb 14 03:11:59 volumio3dev kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 14 03:11:59 volumio3dev kernel: ftrace: allocating 34929 entries in 103 pages Feb 14 03:11:59 volumio3dev kernel: ftrace: allocated 103 pages with 5 groups Feb 14 03:11:59 volumio3dev kernel: trace event string verifier disabled Feb 14 03:11:59 volumio3dev kernel: rcu: Hierarchical RCU implementation. Feb 14 03:11:59 volumio3dev kernel: Rude variant of Tasks RCU enabled. Feb 14 03:11:59 volumio3dev kernel: Tracing variant of Tasks RCU enabled. Feb 14 03:11:59 volumio3dev kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Feb 14 03:11:59 volumio3dev kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Feb 14 03:11:59 volumio3dev kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Feb 14 03:11:59 volumio3dev kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Feb 14 03:11:59 volumio3dev kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Feb 14 03:11:59 volumio3dev kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Feb 14 03:11:59 volumio3dev kernel: Switching to timer-based delay loop, resolution 52ns Feb 14 03:11:59 volumio3dev kernel: Console: colour dummy device 80x30 Feb 14 03:11:59 volumio3dev kernel: printk: console [tty1] enabled Feb 14 03:11:59 volumio3dev kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Feb 14 03:11:59 volumio3dev kernel: CPU: Testing write buffer coherency: ok Feb 14 03:11:59 volumio3dev kernel: pid_max: default: 32768 minimum: 301 Feb 14 03:11:59 volumio3dev kernel: LSM: Security Framework initializing Feb 14 03:11:59 volumio3dev kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 03:11:59 volumio3dev kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 03:11:59 volumio3dev kernel: cgroup: Disabling memory control group subsystem Feb 14 03:11:59 volumio3dev kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Feb 14 03:11:59 volumio3dev kernel: cblist_init_generic: Setting adjustable number of callback queues. Feb 14 03:11:59 volumio3dev kernel: cblist_init_generic: Setting shift to 2 and lim to 1. Feb 14 03:11:59 volumio3dev kernel: cblist_init_generic: Setting adjustable number of callback queues. Feb 14 03:11:59 volumio3dev kernel: cblist_init_generic: Setting shift to 2 and lim to 1. Feb 14 03:11:59 volumio3dev kernel: Setting up static identity map for 0x100000 - 0x10003c Feb 14 03:11:59 volumio3dev kernel: rcu: Hierarchical SRCU implementation. Feb 14 03:11:59 volumio3dev kernel: rcu: Max phase no-delay instances is 1000. Feb 14 03:11:59 volumio3dev kernel: smp: Bringing up secondary CPUs ... Feb 14 03:11:59 volumio3dev kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Feb 14 03:11:59 volumio3dev kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Feb 14 03:11:59 volumio3dev kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Feb 14 03:11:59 volumio3dev kernel: smp: Brought up 1 node, 4 CPUs Feb 14 03:11:59 volumio3dev kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). Feb 14 03:11:59 volumio3dev kernel: CPU: All CPU(s) started in HYP mode. Feb 14 03:11:59 volumio3dev kernel: CPU: Virtualization extensions available. Feb 14 03:11:59 volumio3dev kernel: devtmpfs: initialized Feb 14 03:11:59 volumio3dev kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 Feb 14 03:11:59 volumio3dev kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Feb 14 03:11:59 volumio3dev kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Feb 14 03:11:59 volumio3dev kernel: pinctrl core: initialized pinctrl subsystem Feb 14 03:11:59 volumio3dev kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Feb 14 03:11:59 volumio3dev kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Feb 14 03:11:59 volumio3dev kernel: audit: initializing netlink subsys (disabled) Feb 14 03:11:59 volumio3dev kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Feb 14 03:11:59 volumio3dev kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 14 03:11:59 volumio3dev kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Feb 14 03:11:59 volumio3dev kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Feb 14 03:11:59 volumio3dev kernel: Serial: AMBA PL011 UART driver Feb 14 03:11:59 volumio3dev kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled Feb 14 03:11:59 volumio3dev kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2023-10-17T15:42:39, variant start Feb 14 03:11:59 volumio3dev kernel: raspberrypi-firmware soc:firmware: Firmware hash is 30f0c5e4d076da3ab4f341d88e7d505760b93ad7 Feb 14 03:11:59 volumio3dev kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Feb 14 03:11:59 volumio3dev kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1 Feb 14 03:11:59 volumio3dev kernel: SCSI subsystem initialized Feb 14 03:11:59 volumio3dev kernel: usbcore: registered new interface driver usbfs Feb 14 03:11:59 volumio3dev kernel: usbcore: registered new interface driver hub Feb 14 03:11:59 volumio3dev kernel: usbcore: registered new device driver usb Feb 14 03:11:59 volumio3dev kernel: usb_phy_generic phy: supply vcc not found, using dummy regulator Feb 14 03:11:59 volumio3dev kernel: pps_core: LinuxPPS API ver. 1 registered Feb 14 03:11:59 volumio3dev kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Feb 14 03:11:59 volumio3dev kernel: PTP clock support registered Feb 14 03:11:59 volumio3dev kernel: clocksource: Switched to clocksource arch_sys_counter Feb 14 03:11:59 volumio3dev kernel: VFS: Disk quotas dquot_6.6.0 Feb 14 03:11:59 volumio3dev kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Feb 14 03:11:59 volumio3dev kernel: FS-Cache: Loaded Feb 14 03:11:59 volumio3dev kernel: CacheFiles: Loaded Feb 14 03:11:59 volumio3dev kernel: NET: Registered PF_INET protocol family Feb 14 03:11:59 volumio3dev kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Feb 14 03:11:59 volumio3dev kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) Feb 14 03:11:59 volumio3dev kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 14 03:11:59 volumio3dev kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Feb 14 03:11:59 volumio3dev kernel: TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear) Feb 14 03:11:59 volumio3dev kernel: TCP: Hash tables configured (established 8192 bind 8192) Feb 14 03:11:59 volumio3dev kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 03:11:59 volumio3dev kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 03:11:59 volumio3dev kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Feb 14 03:11:59 volumio3dev kernel: RPC: Registered named UNIX socket transport module. Feb 14 03:11:59 volumio3dev kernel: RPC: Registered udp transport module. Feb 14 03:11:59 volumio3dev kernel: RPC: Registered tcp transport module. Feb 14 03:11:59 volumio3dev kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 14 03:11:59 volumio3dev kernel: Trying to unpack rootfs image as initramfs... Feb 14 03:11:59 volumio3dev kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Feb 14 03:11:59 volumio3dev kernel: Freeing initrd memory: 13628K Feb 14 03:11:59 volumio3dev kernel: Initialise system trusted keyrings Feb 14 03:11:59 volumio3dev kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 Feb 14 03:11:59 volumio3dev kernel: zbud: loaded Feb 14 03:11:59 volumio3dev kernel: NFS: Registering the id_resolver key type Feb 14 03:11:59 volumio3dev kernel: Key type id_resolver registered Feb 14 03:11:59 volumio3dev kernel: Key type id_legacy registered Feb 14 03:11:59 volumio3dev kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Feb 14 03:11:59 volumio3dev kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Feb 14 03:11:59 volumio3dev kernel: Key type asymmetric registered Feb 14 03:11:59 volumio3dev kernel: Asymmetric key parser 'x509' registered Feb 14 03:11:59 volumio3dev kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Feb 14 03:11:59 volumio3dev kernel: io scheduler mq-deadline registered Feb 14 03:11:59 volumio3dev kernel: io scheduler kyber registered Feb 14 03:11:59 volumio3dev kernel: bcm2708_fb soc:fb: FB found 1 display(s) Feb 14 03:11:59 volumio3dev kernel: Console: switching to colour frame buffer device 80x30 Feb 14 03:11:59 volumio3dev kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Feb 14 03:11:59 volumio3dev kernel: bcm2835-rng 3f104000.rng: hwrng registered Feb 14 03:11:59 volumio3dev kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Feb 14 03:11:59 volumio3dev kernel: brd: module loaded Feb 14 03:11:59 volumio3dev kernel: loop: module loaded Feb 14 03:11:59 volumio3dev kernel: Loading iSCSI transport class v2.0-870. Feb 14 03:11:59 volumio3dev kernel: usbcore: registered new interface driver lan78xx Feb 14 03:11:59 volumio3dev kernel: usbcore: registered new interface driver smsc95xx Feb 14 03:11:59 volumio3dev kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Feb 14 03:11:59 volumio3dev kernel: Core Release: 2.80a Feb 14 03:11:59 volumio3dev kernel: Setting default values for core params Feb 14 03:11:59 volumio3dev kernel: Finished setting default values for core params Feb 14 03:11:59 volumio3dev kernel: Using Buffer DMA mode Feb 14 03:11:59 volumio3dev kernel: Periodic Transfer Interrupt Enhancement - disabled Feb 14 03:11:59 volumio3dev kernel: Multiprocessor Interrupt Enhancement - disabled Feb 14 03:11:59 volumio3dev kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Feb 14 03:11:59 volumio3dev kernel: Dedicated Tx FIFOs mode Feb 14 03:11:59 volumio3dev kernel: Feb 14 03:11:59 volumio3dev kernel: WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = b7504000 dma = 0xf7504000 len=9024 Feb 14 03:11:59 volumio3dev kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Feb 14 03:11:59 volumio3dev kernel: dwc_otg: Microframe scheduler enabled Feb 14 03:11:59 volumio3dev kernel: Feb 14 03:11:59 volumio3dev kernel: WARN::hcd_init_fiq:457: FIQ on core 1 Feb 14 03:11:59 volumio3dev kernel: Feb 14 03:11:59 volumio3dev kernel: WARN::hcd_init_fiq:458: FIQ ASM at 807cb80c length 36 Feb 14 03:11:59 volumio3dev kernel: Feb 14 03:11:59 volumio3dev kernel: WARN::hcd_init_fiq:497: MPHI regs_base at bb810000 Feb 14 03:11:59 volumio3dev kernel: dwc_otg 3f980000.usb: DWC OTG Controller Feb 14 03:11:59 volumio3dev kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Feb 14 03:11:59 volumio3dev kernel: dwc_otg 3f980000.usb: irq 89, io mem 0x00000000 Feb 14 03:11:59 volumio3dev kernel: Init: Port Power? op_state=1 Feb 14 03:11:59 volumio3dev kernel: Init: Power Port (0) Feb 14 03:11:59 volumio3dev kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 Feb 14 03:11:59 volumio3dev kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 14 03:11:59 volumio3dev kernel: usb usb1: Product: DWC OTG Controller Feb 14 03:11:59 volumio3dev kernel: usb usb1: Manufacturer: Linux 6.1.69-v7+ dwc_otg_hcd Feb 14 03:11:59 volumio3dev kernel: usb usb1: SerialNumber: 3f980000.usb Feb 14 03:11:59 volumio3dev kernel: hub 1-0:1.0: USB hub found Feb 14 03:11:59 volumio3dev kernel: hub 1-0:1.0: 1 port detected Feb 14 03:11:59 volumio3dev kernel: dwc_otg: FIQ enabled Feb 14 03:11:59 volumio3dev kernel: dwc_otg: NAK holdoff enabled Feb 14 03:11:59 volumio3dev kernel: dwc_otg: FIQ split-transaction FSM enabled Feb 14 03:11:59 volumio3dev kernel: Module dwc_common_port init Feb 14 03:11:59 volumio3dev kernel: usbcore: registered new interface driver usb-storage Feb 14 03:11:59 volumio3dev kernel: mousedev: PS/2 mouse device common for all mice Feb 14 03:11:59 volumio3dev kernel: sdhci: Secure Digital Host Controller Interface driver Feb 14 03:11:59 volumio3dev kernel: sdhci: Copyright(c) Pierre Ossman Feb 14 03:11:59 volumio3dev kernel: sdhci-pltfm: SDHCI platform and OF driver helper Feb 14 03:11:59 volumio3dev kernel: ledtrig-cpu: registered to indicate activity on CPUs Feb 14 03:11:59 volumio3dev kernel: hid: raw HID events driver (C) Jiri Kosina Feb 14 03:11:59 volumio3dev kernel: usbcore: registered new interface driver usbhid Feb 14 03:11:59 volumio3dev kernel: usbhid: USB HID core driver Feb 14 03:11:59 volumio3dev kernel: Initializing XFRM netlink socket Feb 14 03:11:59 volumio3dev kernel: NET: Registered PF_PACKET protocol family Feb 14 03:11:59 volumio3dev kernel: Key type dns_resolver registered Feb 14 03:11:59 volumio3dev kernel: Registering SWP/SWPB emulation handler Feb 14 03:11:59 volumio3dev kernel: registered taskstats version 1 Feb 14 03:11:59 volumio3dev kernel: Loading compiled-in X.509 certificates Feb 14 03:11:59 volumio3dev kernel: Key type .fscrypt registered Feb 14 03:11:59 volumio3dev kernel: Key type fscrypt-provisioning registered Feb 14 03:11:59 volumio3dev kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Feb 14 03:11:59 volumio3dev kernel: 3f201000.serial: ttyAMA1 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2 Feb 14 03:11:59 volumio3dev kernel: serial serial0: tty port ttyAMA1 registered Feb 14 03:11:59 volumio3dev kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Feb 14 03:11:59 volumio3dev kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Feb 14 03:11:59 volumio3dev kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 Feb 14 03:11:59 volumio3dev kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated Feb 14 03:11:59 volumio3dev kernel: sdhost: log_buf @ aea88f61 (f7507000) Feb 14 03:11:59 volumio3dev kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Feb 14 03:11:59 volumio3dev kernel: of_cfs_init Feb 14 03:11:59 volumio3dev kernel: of_cfs_init: OK Feb 14 03:11:59 volumio3dev kernel: Freeing unused kernel image (initmem) memory: 1024K Feb 14 03:11:59 volumio3dev kernel: Indeed it is in host mode hprt0 = 00021501 Feb 14 03:11:59 volumio3dev kernel: mmc0: host does not support reading read-only switch, assuming write-enable Feb 14 03:11:59 volumio3dev kernel: mmc0: new high speed SDHC card at address 0001 Feb 14 03:11:59 volumio3dev kernel: mmcblk0: mmc0:0001 GB1QT 29.8 GiB Feb 14 03:11:59 volumio3dev kernel: mmcblk0: p1 p2 p3 Feb 14 03:11:59 volumio3dev kernel: mmcblk0: mmc0:0001 GB1QT 29.8 GiB Feb 14 03:11:59 volumio3dev kernel: mmc1: new high speed SDIO card at address 0001 Feb 14 03:11:59 volumio3dev kernel: Run /init as init process Feb 14 03:11:59 volumio3dev kernel: with arguments: Feb 14 03:11:59 volumio3dev kernel: /init Feb 14 03:11:59 volumio3dev kernel: splash Feb 14 03:11:59 volumio3dev kernel: with environment: Feb 14 03:11:59 volumio3dev kernel: HOME=/ Feb 14 03:11:59 volumio3dev kernel: TERM=linux Feb 14 03:11:59 volumio3dev kernel: imgpart=/dev/mmcblk0p2 Feb 14 03:11:59 volumio3dev kernel: imgfile=/volumio_current.sqsh Feb 14 03:11:59 volumio3dev kernel: bootdelay=5 Feb 14 03:11:59 volumio3dev kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Feb 14 03:11:59 volumio3dev kernel: Indeed it is in host mode hprt0 = 00001101 Feb 14 03:11:59 volumio3dev initramfs: Booting Volumio for BCM2835 Feb 14 03:11:59 volumio3dev initramfs: This script mounts rootfs RO with an overlay RW layer. Feb 14 03:11:59 volumio3dev kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Feb 14 03:11:59 volumio3dev initramfs: IMGPART=/dev/mmcblk0p2 Feb 14 03:11:59 volumio3dev initramfs: IMGFILE=/volumio_current.sqsh Feb 14 03:11:59 volumio3dev initramfs: Boot delay (except first time) will be 5 seconds Feb 14 03:11:59 volumio3dev initramfs: /dev/mmcblk0p2: Feb 14 03:11:59 volumio3dev kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Feb 14 03:11:59 volumio3dev kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Feb 14 03:11:59 volumio3dev kernel: hub 1-1:1.0: USB hub found Feb 14 03:11:59 volumio3dev kernel: hub 1-1:1.0: 5 ports detected Feb 14 03:11:59 volumio3dev kernel: EXT4-fs (mmcblk0p2): recovery complete Feb 14 03:11:59 volumio3dev kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Feb 14 03:11:59 volumio3dev initramfs: Doing a 5 second delay here to give kernel load a headstart Feb 14 03:11:59 volumio3dev kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Feb 14 03:11:59 volumio3dev kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Feb 14 03:11:59 volumio3dev kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Feb 14 03:11:59 volumio3dev kernel: smsc95xx v2.0.0 Feb 14 03:11:59 volumio3dev kernel: SMSC LAN8700 usb-001:003:01: attached PHY driver (mii_bus:phy_addr=usb-001:003:01, irq=199) Feb 14 03:11:59 volumio3dev kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:41:4b:17 Feb 14 03:11:59 volumio3dev kernel: usb 1-1.2: new full-speed USB device number 4 using dwc_otg Feb 14 03:11:59 volumio3dev kernel: usb 1-1.2: New USB device found, idVendor=1852, idProduct=7022, bcdDevice= 0.01 Feb 14 03:11:59 volumio3dev kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Feb 14 03:11:59 volumio3dev kernel: usb 1-1.2: Product: DigiHug USB Audio Feb 14 03:11:59 volumio3dev kernel: usb 1-1.2: Manufacturer: FiiO Feb 14 03:11:59 volumio3dev kernel: input: FiiO DigiHug USB Audio as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/0003:1852:7022.0001/input/input0 Feb 14 03:11:59 volumio3dev kernel: hid-generic 0003:1852:7022.0001: input,hidraw0: USB HID v1.00 Device [FiiO DigiHug USB Audio] on usb-3f980000.usb-1.2/input0 Feb 14 03:11:59 volumio3dev initramfs: Checking for USB updates Feb 14 03:11:59 volumio3dev initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Feb 14 03:11:59 volumio3dev kernel: loop0: detected capacity change from 0 to 1002680 Feb 14 03:11:59 volumio3dev kernel: EXT4-fs (mmcblk0p3): recovery complete Feb 14 03:11:59 volumio3dev kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Feb 14 03:11:59 volumio3dev initramfs: With Option: Feb 14 03:11:59 volumio3dev initramfs: VOLUMIO_VERSION="3.611" Feb 14 03:11:59 volumio3dev initramfs: Finish initramfs, continue booting Volumio Feb 14 03:11:59 volumio3dev systemd[1]: System time before build time, advancing clock. Feb 14 03:11:59 volumio3dev kernel: NET: Registered PF_INET6 protocol family Feb 14 03:11:59 volumio3dev kernel: Segment Routing with IPv6 Feb 14 03:11:59 volumio3dev kernel: In-situ OAM (IOAM) with IPv6 Feb 14 03:11:59 volumio3dev 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) Feb 14 03:11:59 volumio3dev systemd[1]: Detected architecture arm. Feb 14 03:11:59 volumio3dev systemd[1]: Set hostname to . Feb 14 03:11:59 volumio3dev 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. Feb 14 03:11:59 volumio3dev systemd[1]: Listening on udev Control Socket. Feb 14 03:11:59 volumio3dev systemd[1]: Listening on fsck to fsckd communication Socket. Feb 14 03:11:59 volumio3dev systemd[1]: Listening on Journal Audit Socket. Feb 14 03:11:59 volumio3dev systemd[1]: Listening on RPCbind Server Activation Socket. Feb 14 03:11:59 volumio3dev systemd[1]: Listening on Journal Socket. Feb 14 03:11:59 volumio3dev systemd[1]: Created slice system-getty.slice. Feb 14 03:11:59 volumio3dev kernel: i2c_dev: i2c /dev entries driver Feb 14 03:11:59 volumio3dev systemd-journald[194]: Journal started Feb 14 03:11:59 volumio3dev systemd-journald[194]: Runtime journal (/run/log/journal/4f62df4b7e374c47e98bbc1f6425ae06) is 3.7M, max 30.0M, 26.2M free. Feb 14 03:11:59 volumio3dev systemd-modules-load[191]: Inserted module 'i2c_dev' Feb 14 03:11:59 volumio3dev systemd[1]: Mounted POSIX Message Queue File System. Feb 14 03:11:59 volumio3dev systemd[1]: Started Remount Root and Kernel File Systems. Feb 14 03:11:59 volumio3dev systemd[1]: Mounted RPC Pipe File System. Feb 14 03:11:59 volumio3dev systemd[1]: Mounted Kernel Debug File System. Feb 14 03:11:59 volumio3dev systemd[1]: Mounted Kernel Configuration File System. Feb 14 03:12:00 volumio3dev systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Feb 03 11:21:28 volumio3dev fake-hwclock[200]: Sat Feb 3 18:21:28 UTC 2024 Feb 03 11:21:28 volumio3dev systemd[1]: Starting Create System Users... Feb 03 11:21:28 volumio3dev systemd[1]: Starting Load/Save Random Seed... Feb 03 11:21:28 volumio3dev systemd[1]: Started Restore / save the current clock. Feb 03 11:21:28 volumio3dev systemd[1]: Started Apply Kernel Variables. Feb 03 11:21:28 volumio3dev systemd[1]: dynamicswap.service: Succeeded. Feb 03 11:21:28 volumio3dev systemd[1]: Started Load/Save Random Seed. Feb 03 11:21:28 volumio3dev systemd[1]: Started Create System Users. Feb 03 11:21:28 volumio3dev systemd[1]: Starting Create Static Device Nodes in /dev... Feb 03 11:21:28 volumio3dev systemd[1]: Started Create Static Device Nodes in /dev. Feb 03 11:21:28 volumio3dev systemd[1]: Starting udev Kernel Device Manager... Feb 03 11:21:28 volumio3dev systemd[1]: Reached target Local File Systems (Pre). Feb 03 11:21:28 volumio3dev systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Feb 03 11:21:28 volumio3dev systemd[1]: Mounting /var/log... Feb 03 11:21:28 volumio3dev systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Feb 03 11:21:28 volumio3dev systemd[1]: Mounting /tmp... Feb 03 11:21:28 volumio3dev systemd[1]: Mounting /var/spool/cups... Feb 03 11:21:28 volumio3dev systemd[1]: Started udev Coldplug all Devices. Feb 03 11:21:28 volumio3dev systemd[1]: Mounted /var/log. Feb 03 11:21:28 volumio3dev systemd[1]: Mounted /tmp. Feb 03 11:21:28 volumio3dev systemd[1]: Mounted /var/spool/cups. Feb 03 11:21:28 volumio3dev systemd[1]: Mounting /var/spool/cups/tmp... Feb 03 11:21:28 volumio3dev systemd[1]: Starting Flush Journal to Persistent Storage... Feb 03 11:21:28 volumio3dev systemd-udevd[224]: Network interface NamePolicy= disabled on kernel command line, ignoring. Feb 03 11:21:28 volumio3dev systemd[1]: Starting Helper to synchronize boot up for ifupdown... Feb 03 11:21:28 volumio3dev systemd[1]: Mounted /var/spool/cups/tmp. Feb 03 11:21:28 volumio3dev systemd[1]: Started Helper to synchronize boot up for ifupdown. Feb 03 11:21:28 volumio3dev systemd-journald[194]: Runtime journal (/run/log/journal/4f62df4b7e374c47e98bbc1f6425ae06) is 7.5M, max 30.0M, 22.5M free. Feb 03 11:21:28 volumio3dev systemd[1]: Started udev Kernel Device Manager. Feb 03 11:21:28 volumio3dev systemd[1]: Starting Show Plymouth Boot Screen... Feb 03 11:21:28 volumio3dev systemd[1]: Started Flush Journal to Persistent Storage. Feb 03 11:21:28 volumio3dev systemd[1]: Received SIGRTMIN+20 from PID 244 (plymouthd). Feb 03 11:21:28 volumio3dev systemd[1]: Started Show Plymouth Boot Screen. Feb 03 11:21:28 volumio3dev systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Feb 03 11:21:28 volumio3dev systemd[1]: Reached target Paths. Feb 03 11:21:28 volumio3dev systemd[1]: Reached target Local Encrypted Volumes. Feb 03 11:21:28 volumio3dev systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Feb 03 11:21:28 volumio3dev kernel: rc rc0: GPIO IR Bit Banging Transmitter as /devices/platform/gpio-ir-transmitter@11/rc/rc0 Feb 03 11:21:28 volumio3dev kernel: Registered IR keymap rc-rc6-mce Feb 03 11:21:28 volumio3dev kernel: rc rc0: lirc_dev: driver gpio-ir-tx registered at minor = 0, no receiver, raw IR transmitter Feb 03 11:21:28 volumio3dev kernel: IR RC6 protocol handler initialized Feb 03 11:21:28 volumio3dev kernel: rc rc1: gpio_ir_recv as /devices/platform/ir-receiver@12/rc/rc1 Feb 03 11:21:28 volumio3dev kernel: rc rc1: lirc_dev: driver gpio_ir_recv registered at minor = 1, raw IR receiver, no transmitter Feb 03 11:21:28 volumio3dev kernel: input: gpio_ir_recv as /devices/platform/ir-receiver@12/rc/rc1/input1 Feb 03 11:21:28 volumio3dev kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Feb 03 11:21:28 volumio3dev kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Feb 03 11:21:28 volumio3dev kernel: [vc_sm_connected_init]: start Feb 03 11:21:28 volumio3dev kernel: mc: Linux media interface: v0.10 Feb 03 11:21:28 volumio3dev kernel: [vc_sm_connected_init]: installed successfully Feb 03 11:21:29 volumio3dev kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Feb 03 11:21:29 volumio3dev kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Feb 03 11:21:29 volumio3dev kernel: videodev: Linux video capture interface: v2.00 Feb 03 11:21:29 volumio3dev kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Feb 03 11:21:29 volumio3dev kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Feb 03 11:21:29 volumio3dev kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Feb 03 11:21:29 volumio3dev kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Feb 03 11:21:29 volumio3dev kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Feb 03 11:21:29 volumio3dev kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Feb 03 11:21:29 volumio3dev kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Feb 03 11:21:29 volumio3dev kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Feb 03 11:21:29 volumio3dev kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Feb 03 11:21:29 volumio3dev kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Feb 03 11:21:29 volumio3dev kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Feb 03 11:21:29 volumio3dev kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Feb 03 11:21:29 volumio3dev kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Feb 03 11:21:29 volumio3dev kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Feb 03 11:21:29 volumio3dev kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Feb 03 11:21:29 volumio3dev kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Feb 03 11:21:29 volumio3dev kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Feb 03 11:21:29 volumio3dev kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Feb 03 11:21:29 volumio3dev kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Feb 03 11:21:29 volumio3dev kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Feb 03 11:21:29 volumio3dev kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Feb 03 11:21:29 volumio3dev kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Feb 03 11:21:29 volumio3dev kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Feb 03 11:21:29 volumio3dev kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Feb 03 11:21:29 volumio3dev kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Feb 03 11:21:29 volumio3dev kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Feb 03 11:21:29 volumio3dev kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Feb 03 11:21:29 volumio3dev kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Feb 03 11:21:29 volumio3dev kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Feb 03 11:21:29 volumio3dev kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Feb 03 11:21:29 volumio3dev kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Feb 03 11:21:29 volumio3dev kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Feb 03 11:21:29 volumio3dev kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Feb 03 11:21:29 volumio3dev kernel: Bluetooth: Core ver 2.22 Feb 03 11:21:29 volumio3dev kernel: NET: Registered PF_BLUETOOTH protocol family Feb 03 11:21:29 volumio3dev kernel: Bluetooth: HCI device and connection manager initialized Feb 03 11:21:29 volumio3dev kernel: Bluetooth: HCI socket layer initialized Feb 03 11:21:29 volumio3dev kernel: Bluetooth: L2CAP socket layer initialized Feb 03 11:21:29 volumio3dev kernel: Bluetooth: SCO socket layer initialized Feb 03 11:21:29 volumio3dev systemd-udevd[238]: Using default interface naming scheme 'v240'. Feb 03 11:21:29 volumio3dev systemd[1]: Found device /dev/mmcblk0p1. Feb 03 11:21:29 volumio3dev systemd-udevd[238]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 03 11:21:29 volumio3dev kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Feb 03 11:21:29 volumio3dev kernel: Bluetooth: HCI UART driver ver 2.3 Feb 03 11:21:29 volumio3dev kernel: Bluetooth: HCI UART protocol H4 registered Feb 03 11:21:29 volumio3dev kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Feb 03 11:21:29 volumio3dev kernel: Bluetooth: HCI UART protocol Broadcom registered Feb 03 11:21:29 volumio3dev kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Feb 03 11:21:29 volumio3dev kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Feb 03 11:21:29 volumio3dev kernel: uart-pl011 3f201000.serial: no DMA platform data Feb 03 11:21:30 volumio3dev kernel: Bluetooth: hci0: BCM: chip id 94 Feb 03 11:21:30 volumio3dev kernel: Bluetooth: hci0: BCM: features 0x2e Feb 03 11:21:30 volumio3dev kernel: Bluetooth: hci0: BCM43430A1 Feb 03 11:21:30 volumio3dev kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0000 Feb 03 11:21:30 volumio3dev kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Feb 03 11:21:30 volumio3dev kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Feb 03 11:21:30 volumio3dev systemd-udevd[240]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 03 11:21:30 volumio3dev kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Feb 03 11:21:30 volumio3dev kernel: Bluetooth: hci0: BCM43430A1 'brcm/BCM43430A1.hcd' Patch Feb 03 11:21:30 volumio3dev systemd-udevd[234]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 03 11:21:30 volumio3dev kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Feb 03 11:21:30 volumio3dev kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Feb 03 11:21:30 volumio3dev kernel: usbcore: registered new interface driver brcmfmac Feb 03 11:21:30 volumio3dev systemd-udevd[241]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 03 11:21:31 volumio3dev kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Jul 19 2021 03:24:18 version 7.45.98 (TOB) (56df937 CY) FWID 01-8e14b897 Feb 03 11:21:31 volumio3dev systemd-udevd[235]: Using default interface naming scheme 'v240'. Feb 03 11:21:31 volumio3dev systemd-udevd[235]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 03 11:21:31 volumio3dev kernel: usbcore: registered new interface driver snd-usb-audio Feb 03 11:21:31 volumio3dev kernel: Bluetooth: hci0: BCM: features 0x2e Feb 03 11:21:31 volumio3dev kernel: Bluetooth: hci0: BCM43438A1 37.4MHz Raspberry Pi 3-0141 Feb 03 11:21:31 volumio3dev kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0508 Feb 03 11:21:31 volumio3dev kernel: hwmon hwmon1: Undervoltage detected! Feb 03 11:21:32 volumio3dev systemd-udevd[234]: Process '/bin/bash -c '/usr/local/bin/volumio scanaudioinputs'' failed with exit code 7. Feb 03 11:21:32 volumio3dev systemd-udevd[241]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Feb 03 11:21:32 volumio3dev systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. Feb 03 11:21:32 volumio3dev systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Feb 03 11:21:32 volumio3dev systemd[1]: Created slice system-bthelper.slice. Feb 03 11:21:32 volumio3dev systemd[1]: Condition check resulted in Huge Pages File System being skipped. Feb 03 11:21:32 volumio3dev systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Feb 03 11:21:32 volumio3dev systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Feb 03 11:21:32 volumio3dev systemd[1]: Condition check resulted in FUSE Control File System being skipped. Feb 03 11:21:32 volumio3dev systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Feb 03 11:21:32 volumio3dev systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Feb 03 11:21:32 volumio3dev systemd[1]: Starting File System Check on /dev/mmcblk0p1... Feb 03 11:21:32 volumio3dev systemd[1]: Starting Load/Save RF Kill Switch Status... Feb 03 11:21:32 volumio3dev systemd[1]: Started File System Check Daemon to report status. Feb 03 11:21:32 volumio3dev systemd[1]: Started Load/Save RF Kill Switch Status. Feb 03 11:21:32 volumio3dev systemd-fsck[400]: fsck.fat 4.1 (2017-01-24) Feb 03 11:21:32 volumio3dev systemd-fsck[400]: /dev/mmcblk0p1: 374 files, 33361/46774 clusters Feb 03 11:21:32 volumio3dev systemd[1]: Started File System Check on /dev/mmcblk0p1. Feb 03 11:21:33 volumio3dev systemd[1]: Mounting /boot... Feb 03 11:21:33 volumio3dev systemd[1]: Mounted /boot. Feb 03 11:21:33 volumio3dev systemd[1]: Reached target Local File Systems. Feb 03 11:21:33 volumio3dev systemd[1]: Started ifup for wlan0. Feb 03 11:21:33 volumio3dev systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Feb 03 11:21:33 volumio3dev systemd[1]: Started ifup for eth0. Feb 03 11:21:33 volumio3dev systemd[1]: Starting Create Volatile Files and Directories... Feb 03 11:21:33 volumio3dev systemd[1]: Starting Preprocess NFS configuration... Feb 03 11:21:33 volumio3dev systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Feb 03 11:21:33 volumio3dev systemd[1]: Starting Raise network interfaces... Feb 03 11:21:33 volumio3dev systemd[1]: plymouth-read-write.service: Succeeded. Feb 03 11:21:33 volumio3dev systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Feb 03 11:21:33 volumio3dev systemd[1]: Received SIGRTMIN+20 from PID 244 (plymouthd). Feb 03 11:21:33 volumio3dev systemd[1]: nfs-config.service: Succeeded. Feb 03 11:21:33 volumio3dev systemd[1]: Started Preprocess NFS configuration. Feb 03 11:21:33 volumio3dev systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Feb 03 11:21:33 volumio3dev systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Feb 03 11:21:33 volumio3dev systemd[1]: Reached target NFS client services. Feb 03 11:21:33 volumio3dev systemd[1]: Started Create Volatile Files and Directories. Feb 03 11:21:33 volumio3dev systemd[1]: Starting Update UTMP about System Boot/Shutdown... Feb 03 11:21:33 volumio3dev systemd[1]: Starting RPC bind portmap service... Feb 03 11:21:33 volumio3dev systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Feb 03 11:21:33 volumio3dev systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Feb 03 11:21:33 volumio3dev kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 03 11:21:33 volumio3dev systemd[1]: Started Update UTMP about System Boot/Shutdown. Feb 03 11:21:33 volumio3dev systemd[1]: Reached target System Initialization. Feb 03 11:21:33 volumio3dev systemd[1]: Listening on triggerhappy.socket. Feb 03 11:21:33 volumio3dev kernel: 8021q: 802.1Q VLAN Support v1.8 Feb 03 11:21:33 volumio3dev systemd[1]: Listening on D-Bus System Message Bus Socket. Feb 03 11:21:33 volumio3dev systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Feb 03 11:21:33 volumio3dev systemd[1]: Reached target Sockets. Feb 03 11:21:33 volumio3dev systemd[1]: Reached target Basic System. Feb 03 11:21:33 volumio3dev systemd[1]: Starting Login Service... Feb 03 11:21:33 volumio3dev systemd[1]: Started D-Bus System Message Bus. Feb 03 11:21:33 volumio3dev systemd[1]: Starting WPA supplicant... Feb 03 11:21:33 volumio3dev systemd[1]: Starting Wireless Services... Feb 03 11:21:33 volumio3dev systemd[1]: Started Volumio Iptables Module. Feb 03 11:21:33 volumio3dev kernel: hwmon hwmon1: Voltage normalised Feb 03 11:21:33 volumio3dev systemd[1]: Starting triggerhappy global hotkey daemon... Feb 03 11:21:33 volumio3dev systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Feb 03 11:21:33 volumio3dev systemd[1]: Started UPnP Renderer front-end to MPD. Feb 03 11:21:33 volumio3dev systemd[1]: Starting Configure Bluetooth Modems connected by UART... Feb 03 11:21:33 volumio3dev systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Feb 03 11:21:33 volumio3dev systemd[1]: Started volumio-remote-updater.service. Feb 03 11:21:33 volumio3dev systemd[1]: Started Daily apt download activities. Feb 03 11:21:33 volumio3dev systemd[1]: Started Daily apt upgrade and clean activities. Feb 03 11:21:33 volumio3dev systemd[1]: Started Daily Cleanup of Temporary Directories. Feb 03 11:21:33 volumio3dev systemd[1]: Reached target Timers. Feb 03 11:21:33 volumio3dev systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Feb 03 11:21:33 volumio3dev systemd[1]: Starting dhcpcd on all interfaces... Feb 03 11:21:33 volumio3dev systemd[1]: Started Manage Sound Card State (restore and store). Feb 03 11:21:33 volumio3dev alsactl[515]: alsactl 1.1.8 daemon started Feb 03 11:21:33 volumio3dev systemd[1]: Starting Save/Restore Sound Card State... Feb 03 11:21:33 volumio3dev systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Feb 03 11:21:33 volumio3dev systemd[1]: Started RPC bind portmap service. Feb 03 11:21:33 volumio3dev systemd-logind[482]: Watching system buttons on /dev/input/event1 (gpio_ir_recv) Feb 03 11:21:33 volumio3dev dhcpcd[514]: Not running dhcpcd because /etc/network/interfaces Feb 03 11:21:33 volumio3dev dhcpcd[514]: defines some interfaces that will use a Feb 03 11:21:33 volumio3dev dhcpcd[514]: DHCP client or static address Feb 03 11:21:33 volumio3dev systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Feb 03 11:21:33 volumio3dev systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Feb 03 11:21:33 volumio3dev systemd[1]: Failed to start dhcpcd on all interfaces. Feb 03 11:21:33 volumio3dev thd[505]: Unable to parse trigger line: Feb 03 11:21:33 volumio3dev thd[505]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Feb 03 11:21:33 volumio3dev thd[505]: Unable to parse trigger line: Feb 03 11:21:33 volumio3dev thd[505]: Found socket passed from systemd Feb 03 11:21:33 volumio3dev systemd[1]: Reached target RPC Port Mapper. Feb 03 11:21:33 volumio3dev systemd[1]: Reached target Remote File Systems (Pre). Feb 03 11:21:33 volumio3dev systemd[1]: Reached target Remote File Systems. Feb 03 11:21:33 volumio3dev systemd-logind[482]: New seat seat0. Feb 03 11:21:34 volumio3dev sh[407]: wlan0=wlan0 Feb 03 11:21:34 volumio3dev systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Feb 03 11:21:34 volumio3dev systemd[1]: Starting Deferred execution scheduler... Feb 03 11:21:34 volumio3dev systemd[1]: Started Regular background program processing daemon. Feb 03 11:21:34 volumio3dev systemd[1]: Started triggerhappy global hotkey daemon. Feb 03 11:21:34 volumio3dev cron[554]: (CRON) INFO (pidfile fd = 3) Feb 03 11:21:34 volumio3dev systemd[1]: Started Save/Restore Sound Card State. Feb 03 11:21:34 volumio3dev systemd[1]: Reached target Sound Card. Feb 03 11:21:34 volumio3dev cron[554]: (CRON) INFO (Running @reboot jobs) Feb 03 11:21:34 volumio3dev sh[408]: eth0: waiting for carrier Feb 03 11:21:34 volumio3dev dhcpcd[456]: eth0: waiting for carrier Feb 03 11:21:34 volumio3dev dhcpcd[456]: eth0: carrier acquired Feb 03 11:21:34 volumio3dev sh[408]: eth0: carrier acquired Feb 03 11:21:34 volumio3dev kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Feb 03 11:21:34 volumio3dev kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Feb 03 11:21:34 volumio3dev avahi-daemon[519]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Feb 03 11:21:34 volumio3dev avahi-daemon[519]: Successfully dropped root privileges. Feb 03 11:21:34 volumio3dev avahi-daemon[519]: avahi-daemon 0.7 starting up. Feb 03 11:21:34 volumio3dev sh[408]: DUID 00:01:00:01:2b:b8:6c:3f:dc:a6:32:1a:15:ba Feb 03 11:21:34 volumio3dev sh[408]: eth0: IAID eb:41:4b:17 Feb 03 11:21:34 volumio3dev sh[408]: eth0: adding address fe80::2508:1781:c445:9a99 Feb 03 11:21:34 volumio3dev sh[408]: ipv6_addaddr1: Permission denied Feb 03 11:21:34 volumio3dev dhcpcd[456]: DUID 00:01:00:01:2b:b8:6c:3f:dc:a6:32:1a:15:ba Feb 03 11:21:34 volumio3dev dhcpcd[456]: eth0: IAID eb:41:4b:17 Feb 03 11:21:34 volumio3dev dhcpcd[456]: eth0: adding address fe80::2508:1781:c445:9a99 Feb 03 11:21:34 volumio3dev dhcpcd[456]: ipv6_addaddr1: Permission denied Feb 03 11:21:34 volumio3dev sh[408]: eth0: soliciting a DHCP lease Feb 03 11:21:34 volumio3dev dhcpcd[456]: eth0: soliciting a DHCP lease Feb 03 11:21:34 volumio3dev sh[408]: eth0: offered 192.168.4.180 from 192.168.4.1 Feb 03 11:21:34 volumio3dev dhcpcd[456]: eth0: offered 192.168.4.180 from 192.168.4.1 Feb 03 11:21:34 volumio3dev sh[408]: eth0: probing address 192.168.4.180/24 Feb 03 11:21:34 volumio3dev dhcpcd[456]: eth0: probing address 192.168.4.180/24 Feb 03 11:21:34 volumio3dev volumio-remote-updater[512]: Error: No active session Feb 03 11:21:34 volumio3dev volumio-remote-updater[512]: [2024-02-03 11:21:34] [info] asio async_connect error: system:111 (Connection refused) Feb 03 11:21:34 volumio3dev volumio-remote-updater[512]: [2024-02-03 11:21:34] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 03 11:21:34 volumio3dev volumio-remote-updater[512]: [2024-02-03 11:21:34] [error] handle_connect error: Underlying Transport Error Feb 03 11:21:34 volumio3dev sh[408]: eth0: soliciting an IPv6 router Feb 03 11:21:34 volumio3dev dhcpcd[456]: eth0: soliciting an IPv6 router Feb 03 11:21:34 volumio3dev systemd[1]: hciuart.service: Succeeded. Feb 03 11:21:34 volumio3dev systemd[1]: Started Configure Bluetooth Modems connected by UART. Feb 03 11:21:34 volumio3dev avahi-daemon[519]: Successfully called chroot(). Feb 03 11:21:34 volumio3dev avahi-daemon[519]: Successfully dropped remaining capabilities. Feb 03 11:21:34 volumio3dev wpa_supplicant[489]: Successfully initialized wpa_supplicant Feb 03 11:21:34 volumio3dev avahi-daemon[519]: Loading service file /services/volumio.service. Feb 03 11:21:34 volumio3dev systemd[1]: Started Deferred execution scheduler. Feb 03 11:21:34 volumio3dev avahi-daemon[519]: Network interface enumeration completed. Feb 03 11:21:34 volumio3dev avahi-daemon[519]: Server startup complete. Host name is volumio3dev.local. Local service cookie is 1893216641. Feb 03 11:21:34 volumio3dev avahi-daemon[519]: Service "Volumio3Dev" (/services/volumio.service) successfully established. Feb 03 11:21:34 volumio3dev volumio[506]: Could not open config: /tmp/upmpdcli.conf Feb 03 11:21:34 volumio3dev systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 03 11:21:34 volumio3dev systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 03 11:21:34 volumio3dev systemd[1]: Started Raise network interfaces. Feb 03 11:21:34 volumio3dev systemd[1]: Started Login Service. Feb 03 11:21:34 volumio3dev systemd[1]: Started Avahi mDNS/DNS-SD Stack. Feb 03 11:21:34 volumio3dev systemd[1]: Started WPA supplicant. Feb 03 11:21:34 volumio3dev systemd[1]: Reached target Network. Feb 03 11:21:35 volumio3dev systemd[1]: Starting Network Time Service... Feb 03 11:21:35 volumio3dev systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Feb 03 11:21:35 volumio3dev systemd[1]: Reached target Network is Online. Feb 03 11:21:35 volumio3dev systemd[1]: Starting LSB: Brings up/down network automatically... Feb 03 11:21:35 volumio3dev systemd[1]: Starting Samba NMB Daemon... Feb 03 11:21:35 volumio3dev systemd[1]: Starting LSB: exim Mail Transport Agent... Feb 03 11:21:35 volumio3dev systemd[1]: Starting OpenBSD Secure Shell server... Feb 03 11:21:35 volumio3dev systemd[1]: Started gunicorn daemon. Feb 03 11:21:35 volumio3dev systemd[1]: Starting /etc/rc.local Compatibility... Feb 03 11:21:35 volumio3dev systemd[1]: Starting Disable Raspberry Pi HDMI output... Feb 03 11:21:35 volumio3dev systemd[1]: Starting Permit User Sessions... Feb 03 11:21:35 volumio3dev systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 03 11:21:35 volumio3dev systemd[1]: Starting Raspberry Pi bluetooth helper... Feb 03 11:21:35 volumio3dev systemd[1]: Started /etc/rc.local Compatibility. Feb 03 11:21:35 volumio3dev tvservice[611]: Powering off HDMI Feb 03 11:21:35 volumio3dev systemd[1]: disable-hdmi.service: Succeeded. Feb 03 11:21:35 volumio3dev systemd[1]: Started Disable Raspberry Pi HDMI output. Feb 03 11:21:35 volumio3dev systemd[1]: Started Permit User Sessions. Feb 03 11:21:35 volumio3dev ifplugd(eth0)[652]: ifplugd 0.28 initializing. Feb 03 11:21:35 volumio3dev ifplugd(eth0)[652]: Using interface eth0/B8:27:EB:41:4B:17 with driver (version: 6.1.69-v7+) Feb 03 11:21:35 volumio3dev ifplugd(eth0)[652]: Using detection mode: SIOCETHTOOL Feb 03 11:21:35 volumio3dev ifplugd(eth0)[652]: Initialization complete, link beat detected. Feb 03 11:21:35 volumio3dev bthelper[617]: Raspberry Pi BDADDR already set Feb 03 11:21:35 volumio3dev systemd[1]: Starting Hold until boot process finishes up... Feb 03 11:21:35 volumio3dev loadcpufreq[544]: Loading cpufreq kernel modules...done (none). Feb 03 11:21:35 volumio3dev systemd[1]: Starting Terminate Plymouth Boot Screen... Feb 03 11:21:35 volumio3dev systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Feb 03 11:21:35 volumio3dev systemd[1]: Started Raspberry Pi bluetooth helper. Feb 03 11:21:35 volumio3dev ifplugd(eth0)[652]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 03 11:21:35 volumio3dev systemd[1]: Received SIGRTMIN+21 from PID 244 (plymouthd). Feb 03 11:21:35 volumio3dev kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Feb 03 11:21:35 volumio3dev kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Feb 03 11:21:35 volumio3dev kernel: detected fb_set_par error, error code: -22 Feb 03 11:21:35 volumio3dev kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Feb 03 11:21:35 volumio3dev kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Feb 03 11:21:35 volumio3dev kernel: bcm2708_fb_pan_display(0,0) returns=-22 Feb 03 11:21:35 volumio3dev systemd[1]: plymouth-start.service: Succeeded. Feb 03 11:21:35 volumio3dev systemd[1]: plymouth-quit-wait.service: Succeeded. Feb 03 11:21:35 volumio3dev systemd[1]: Started Hold until boot process finishes up. Feb 03 11:21:36 volumio3dev systemd[1]: plymouth-quit.service: Succeeded. Feb 03 11:21:36 volumio3dev systemd[1]: Started Terminate Plymouth Boot Screen. Feb 03 11:21:36 volumio3dev systemd[1]: Received SIGRTMIN+21 from PID 244 (n/a). Feb 03 11:21:36 volumio3dev systemd[1]: Started Getty on tty1. Feb 03 11:21:36 volumio3dev systemd[1]: Reached target Login Prompts. Feb 03 11:21:36 volumio3dev systemd[1]: Starting Bluetooth service... Feb 03 11:21:36 volumio3dev systemd[1]: Starting LSB: set CPUFreq kernel parameters... Feb 03 11:21:36 volumio3dev ntpd[584]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 03 11:21:36 volumio3dev ntpd[584]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Feb 03 11:21:36 volumio3dev systemd[1]: Started Network Time Service. Feb 03 11:21:36 volumio3dev systemd[1]: iptables.service: Succeeded. Feb 03 11:21:36 volumio3dev sshd[662]: Server listening on 0.0.0.0 port 22. Feb 03 11:21:36 volumio3dev sshd[662]: Server listening on :: port 22. Feb 03 11:21:36 volumio3dev ntpd[678]: proto: precision = 1.458 usec (-19) Feb 03 11:21:36 volumio3dev systemd[1]: Started OpenBSD Secure Shell server. Feb 03 11:21:36 volumio3dev ifplugd(eth0)[652]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Feb 03 11:21:36 volumio3dev ntpd[678]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 03 11:21:36 volumio3dev ntpd[678]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 03 11:21:36 volumio3dev ntpd[678]: Listen and drop on 0 v6wildcard [::]:123 Feb 03 11:21:36 volumio3dev ntpd[678]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 03 11:21:36 volumio3dev ntpd[678]: Listen normally on 2 lo 127.0.0.1:123 Feb 03 11:21:36 volumio3dev ntpd[678]: Listening on routing socket on fd #19 for interface updates Feb 03 11:21:36 volumio3dev ntpd[678]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 03 11:21:36 volumio3dev ntpd[678]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 03 11:21:36 volumio3dev bluetoothd[673]: Bluetooth daemon 5.50 Feb 03 11:21:36 volumio3dev bluetoothd[673]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Feb 03 11:21:36 volumio3dev systemd[1]: Started Bluetooth service. Feb 03 11:21:36 volumio3dev systemd[1]: Reached target Bluetooth. Feb 03 11:21:36 volumio3dev cpufrequtils[675]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Feb 03 11:21:36 volumio3dev systemd[1]: Started LSB: set CPUFreq kernel parameters. Feb 03 11:21:36 volumio3dev bluetoothd[673]: Starting SDP server Feb 03 11:21:36 volumio3dev bluetoothd[673]: Excluding (cli) sap Feb 03 11:21:36 volumio3dev kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Feb 03 11:21:36 volumio3dev kernel: Bluetooth: BNEP filters: protocol multicast Feb 03 11:21:36 volumio3dev kernel: Bluetooth: BNEP socket layer initialized Feb 03 11:21:36 volumio3dev bluetoothd[673]: Bluetooth management interface 1.22 initialized Feb 03 11:21:36 volumio3dev kernel: Bluetooth: MGMT ver 1.22 Feb 03 11:21:36 volumio3dev dbus-daemon[485]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=673 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Feb 03 11:21:36 volumio3dev haveged[474]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Feb 03 11:21:36 volumio3dev haveged[474]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Feb 03 11:21:36 volumio3dev haveged[474]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.0015 Feb 03 11:21:36 volumio3dev haveged[474]: haveged: fills: 0, generated: 0 Feb 03 11:21:36 volumio3dev bluetoothd[673]: Failed to set privacy: Rejected (0x0b) Feb 03 11:21:36 volumio3dev systemd[1]: Starting Hostname Service... Feb 03 11:21:37 volumio3dev dbus-daemon[485]: [system] Successfully activated service 'org.freedesktop.hostname1' Feb 03 11:21:37 volumio3dev systemd[1]: Started Hostname Service. Feb 03 11:21:37 volumio3dev ntpd[678]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 03 11:21:37 volumio3dev exim4[596]: Starting MTA: exim4. Feb 03 11:21:37 volumio3dev systemd[1]: Started LSB: exim Mail Transport Agent. Feb 03 11:21:37 volumio3dev exim[939]: 2024-02-03 11:21:37 socket bind() to port 25 for address ::1 failed: Cannot assign requested address: waiting 30s before trying again (9 more tries) Feb 03 11:21:37 volumio3dev exim[939]: 2024-02-03 11:21:37 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=0 egid=124 Feb 03 11:21:37 volumio3dev exim[939]: exim: could not open panic log - aborting: see message(s) above Feb 03 11:21:37 volumio3dev nmbd[622]: [2024/02/03 11:21:37.684024, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Feb 03 11:21:37 volumio3dev nmbd[622]: started asyncdns process 942 Feb 03 11:21:37 volumio3dev nmbd[622]: [2024/02/03 11:21:37.686460, 0] ../lib/util/become_daemon.c:149(daemon_status) Feb 03 11:21:37 volumio3dev nmbd[622]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Feb 03 11:21:37 volumio3dev nmbd[622]: [2024/02/03 11:21:37.686637, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Feb 03 11:21:37 volumio3dev nmbd[622]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Feb 03 11:21:37 volumio3dev systemd[1]: systemd-rfkill.service: Succeeded. Feb 03 11:21:38 volumio3dev ntpd[678]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 03 11:21:38 volumio3dev wireless.js[495]: Cleaning previous... Feb 03 11:21:38 volumio3dev sudo[950]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 03 11:21:38 volumio3dev sudo[950]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:21:38 volumio3dev sudo[950]: pam_unix(sudo:session): session closed for user root Feb 03 11:21:38 volumio3dev sudo[952]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 03 11:21:38 volumio3dev sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:21:38 volumio3dev sudo[952]: pam_unix(sudo:session): session closed for user root Feb 03 11:21:38 volumio3dev wireless.js[495]: Stopped aP Feb 03 11:21:38 volumio3dev sudo[959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 03 11:21:38 volumio3dev sudo[959]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:21:38 volumio3dev sudo[959]: pam_unix(sudo:session): session closed for user root Feb 03 11:21:38 volumio3dev kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 03 11:21:39 volumio3dev sudo[961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 03 11:21:39 volumio3dev sudo[961]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:21:39 volumio3dev sudo[961]: pam_unix(sudo:session): session closed for user root Feb 03 11:21:39 volumio3dev sudo[968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 03 11:21:39 volumio3dev sudo[968]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:21:39 volumio3dev sudo[968]: pam_unix(sudo:session): session closed for user root Feb 03 11:21:39 volumio3dev sudo[970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 03 11:21:39 volumio3dev sudo[970]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:21:39 volumio3dev gunicorn3[602]: [2024-02-03 11:21:39 -0700] [602] [INFO] Starting gunicorn 19.9.0 Feb 03 11:21:39 volumio3dev gunicorn3[602]: [2024-02-03 11:21:39 -0700] [602] [INFO] Listening at: http://0.0.0.0:8000 (602) Feb 03 11:21:39 volumio3dev gunicorn3[602]: [2024-02-03 11:21:39 -0700] [602] [INFO] Using worker: sync Feb 03 11:21:39 volumio3dev ntpd[678]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 03 11:21:39 volumio3dev gunicorn3[602]: [2024-02-03 11:21:39 -0700] [976] [INFO] Booting worker with pid: 976 Feb 03 11:21:39 volumio3dev gunicorn3[602]: [2024-02-03 11:21:39 -0700] [978] [INFO] Booting worker with pid: 978 Feb 03 11:21:39 volumio3dev gunicorn3[602]: [2024-02-03 11:21:39 -0700] [980] [INFO] Booting worker with pid: 980 Feb 03 11:21:39 volumio3dev sudo[970]: pam_unix(sudo:session): session closed for user root Feb 03 11:21:39 volumio3dev wireless.js[495]: SETTING APPROPRIATE REG DOMAIN: US Feb 03 11:21:39 volumio3dev volumio-remote-updater[512]: [2024-02-03 11:21:39] [info] asio async_connect error: system:111 (Connection refused) Feb 03 11:21:39 volumio3dev volumio-remote-updater[512]: [2024-02-03 11:21:39] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 03 11:21:39 volumio3dev volumio-remote-updater[512]: [2024-02-03 11:21:39] [error] handle_connect error: Underlying Transport Error Feb 03 11:21:39 volumio3dev sudo[983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 03 11:21:39 volumio3dev sudo[983]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:21:39 volumio3dev sudo[983]: pam_unix(sudo:session): session closed for user root Feb 03 11:21:39 volumio3dev sudo[985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Feb 03 11:21:39 volumio3dev sudo[985]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:21:39 volumio3dev sudo[985]: pam_unix(sudo:session): session closed for user root Feb 03 11:21:39 volumio3dev wireless.js[495]: SUCCESSFULLY SET NEW REGDOMAIN: US Feb 03 11:21:39 volumio3dev sudo[992]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 03 11:21:39 volumio3dev sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:21:39 volumio3dev sudo[992]: pam_unix(sudo:session): session closed for user root Feb 03 11:21:39 volumio3dev sudo[994]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 03 11:21:39 volumio3dev sudo[994]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:21:40 volumio3dev ntpd[678]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 03 11:21:40 volumio3dev sh[408]: eth0: leased 192.168.4.180 for 14400 seconds Feb 03 11:21:40 volumio3dev dhcpcd[456]: eth0: leased 192.168.4.180 for 14400 seconds Feb 03 11:21:40 volumio3dev avahi-daemon[519]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.4.180. Feb 03 11:21:40 volumio3dev avahi-daemon[519]: New relevant interface eth0.IPv4 for mDNS. Feb 03 11:21:40 volumio3dev sh[408]: eth0: adding route to 192.168.4.0/24 Feb 03 11:21:40 volumio3dev sh[408]: eth0: adding default route via 192.168.4.1 Feb 03 11:21:40 volumio3dev avahi-daemon[519]: Registering new address record for 192.168.4.180 on eth0.IPv4. Feb 03 11:21:40 volumio3dev dhcpcd[456]: eth0: adding route to 192.168.4.0/24 Feb 03 11:21:40 volumio3dev dhcpcd[456]: eth0: adding default route via 192.168.4.1 Feb 03 11:21:40 volumio3dev sudo[994]: pam_unix(sudo:session): session closed for user root Feb 03 11:21:40 volumio3dev avahi-daemon[519]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Feb 03 11:21:40 volumio3dev kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 03 11:21:40 volumio3dev avahi-daemon[519]: New relevant interface wlan0.IPv4 for mDNS. Feb 03 11:21:40 volumio3dev avahi-daemon[519]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Feb 03 11:21:40 volumio3dev sh[408]: forked to background, child pid 1020 Feb 03 11:21:40 volumio3dev dhcpcd[456]: forked to background, child pid 1020 Feb 03 11:21:40 volumio3dev systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Feb 03 11:21:40 volumio3dev systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Feb 03 11:21:40 volumio3dev dnsmasq[1017]: dnsmasq: syntax check OK. Feb 03 11:21:40 volumio3dev hostapd[1027]: Configuration file: /etc/hostapd/hostapd.conf Feb 03 11:21:40 volumio3dev hostapd[1027]: wlan0: Could not connect to kernel driver Feb 03 11:21:40 volumio3dev hostapd[1027]: Using interface wlan0 with hwaddr b8:27:eb:14:1e:42 and ssid "Volumio-6FE05" Feb 03 11:21:40 volumio3dev dnsmasq[1050]: started, version 2.80 cachesize 150 Feb 03 11:21:40 volumio3dev dnsmasq[1050]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Feb 03 11:21:40 volumio3dev dnsmasq-dhcp[1050]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Feb 03 11:21:40 volumio3dev dnsmasq[1050]: reading /etc/resolv.conf Feb 03 11:21:40 volumio3dev dnsmasq[1050]: using nameserver 192.168.4.1#53 Feb 03 11:21:40 volumio3dev dnsmasq[1050]: using nameserver 208.67.220.220#53 Feb 03 11:21:40 volumio3dev dnsmasq[1050]: using nameserver 192.168.0.1#53 Feb 03 11:21:40 volumio3dev dnsmasq[1050]: using nameserver 8.8.8.8#53 Feb 03 11:21:40 volumio3dev dnsmasq[1050]: read /etc/hosts - 3 addresses Feb 03 11:21:40 volumio3dev ifplugd(eth0)[652]: client: ifup: interface eth0 already configured Feb 03 11:21:40 volumio3dev sh[408]: eth0=eth0 Feb 03 11:21:40 volumio3dev hostapd[1027]: wlan0: interface state UNINITIALIZED->ENABLED Feb 03 11:21:40 volumio3dev hostapd[1027]: wlan0: AP-ENABLED Feb 03 11:21:40 volumio3dev systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Feb 03 11:21:40 volumio3dev ifplugd(eth0)[652]: Program executed successfully. Feb 03 11:21:40 volumio3dev ifplugd[593]: Network Interface Plugging Daemon...start eth0...done. Feb 03 11:21:40 volumio3dev systemd[1]: Started LSB: Brings up/down network automatically. Feb 03 11:21:40 volumio3dev systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Feb 03 11:21:40 volumio3dev systemd[1]: Reached target Host and Network Name Lookups. Feb 03 11:21:41 volumio3dev iwconfig[1060]: Error for wireless request "Set Power Management" (8B2C) : Feb 03 11:21:41 volumio3dev iwconfig[1060]: SET failed on device wlan0 ; Invalid argument. Feb 03 11:21:41 volumio3dev systemd[1]: wireless.service: Succeeded. Feb 03 11:21:41 volumio3dev systemd[1]: Started Wireless Services. Feb 03 11:21:41 volumio3dev systemd[1]: Started Volumio Backend Module. Feb 03 11:21:41 volumio3dev systemd[1]: Started Volumio Cpu Tweaker. Feb 03 11:21:41 volumio3dev kernel: raspberrypi-firmware soc:firmware: Request 0x00048003 returned status 0x80000001 Feb 03 11:21:41 volumio3dev kernel: bcm2708_fb soc:fb: Failed to allocate GPU framebuffer (-22) Feb 03 11:21:41 volumio3dev kernel: bcm2708_fb_pan_display(0,0) returns=-22 Feb 03 11:21:41 volumio3dev bthelper[617]: Changing power off succeeded Feb 03 11:21:41 volumio3dev volumio-cpu-tweak[1062]: Setting RT Priority for mpd Feb 03 11:21:41 volumio3dev volumio-cpu-tweak[1062]: pid 35's current scheduling policy: SCHED_OTHER Feb 03 11:21:41 volumio3dev volumio-cpu-tweak[1062]: pid 35's current scheduling priority: 0 Feb 03 11:21:41 volumio3dev volumio-cpu-tweak[1062]: Setting MPD Affinity Feb 03 11:21:41 volumio3dev volumio-cpu-tweak[1062]: pid 3's current affinity mask: f Feb 03 11:21:41 volumio3dev volumio-cpu-tweak[1062]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Feb 03 11:21:41 volumio3dev volumio-cpu-tweak[1062]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Feb 03 11:21:41 volumio3dev systemd[1]: volumio_cpu_tweak.service: Succeeded. Feb 03 11:21:41 volumio3dev bthelper[617]: [CHG] Controller B8:27:EB:EB:E1:BD Class: 0x0000041c Feb 03 11:21:41 volumio3dev bthelper[617]: Changing power on succeeded Feb 03 11:21:41 volumio3dev bthelper[617]: [CHG] Controller B8:27:EB:EB:E1:BD Powered: yes Feb 03 11:21:41 volumio3dev kernel: hwmon hwmon1: Undervoltage detected! Feb 03 11:21:42 volumio3dev ntpd[678]: Listen normally on 3 eth0 192.168.4.180:123 Feb 03 11:21:42 volumio3dev ntpd[678]: Listen normally on 4 wlan0 192.168.211.1:123 Feb 03 11:21:42 volumio3dev ntpd[678]: new interface(s) found: waking up resolver Feb 03 11:21:42 volumio3dev nmbd[622]: [2024/02/03 11:21:42.696319, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 03 11:21:42 volumio3dev nmbd[622]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 03 11:21:42 volumio3dev systemd[1]: Started Samba NMB Daemon. Feb 03 11:21:42 volumio3dev systemd[1]: Starting Samba Winbind Daemon... Feb 03 11:21:43 volumio3dev winbindd[1085]: [2024/02/03 11:21:43.430076, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 03 11:21:43 volumio3dev winbindd[1085]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 03 11:21:43 volumio3dev winbindd[1085]: [2024/02/03 11:21:43.482277, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 03 11:21:43 volumio3dev winbindd[1085]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 03 11:21:43 volumio3dev systemd[1]: Started Samba Winbind Daemon. Feb 03 11:21:43 volumio3dev systemd[1]: Starting Samba SMB Daemon... Feb 03 11:21:44 volumio3dev winbindd[1089]: [2024/02/03 11:21:44.066536, 0] ../source3/winbindd/winbindd_samr.c:672(sam_sid_to_name) Feb 03 11:21:44 volumio3dev winbindd[1089]: sam_sid_to_name: possible deadlock - trying to lookup SID S-1-5-21-1226013264-3612488545-32793875-514 Feb 03 11:21:44 volumio3dev smbd[1090]: [2024/02/03 11:21:44.179038, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 03 11:21:44 volumio3dev systemd[1]: Started Samba SMB Daemon. Feb 03 11:21:44 volumio3dev smbd[1090]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 03 11:21:44 volumio3dev systemd[1]: Reached target Multi-User System. Feb 03 11:21:44 volumio3dev systemd[1]: Reached target Graphical Interface. Feb 03 11:21:44 volumio3dev systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 03 11:21:44 volumio3dev systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 03 11:21:44 volumio3dev systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 03 11:21:44 volumio3dev systemd[1]: Startup finished in 11.109s (kernel) + 18.427s (userspace) = 29.536s. Feb 03 11:21:45 volumio3dev volumio[1061]: info: ------------------------------------------- Feb 03 11:21:45 volumio3dev volumio[1061]: info: ----- Volumio3 ---- Feb 03 11:21:45 volumio3dev volumio[1061]: info: ------------------------------------------- Feb 03 11:21:45 volumio3dev volumio[1061]: info: ----- System startup ---- Feb 03 11:21:45 volumio3dev volumio[1061]: info: ------------------------------------------- Feb 03 11:21:46 volumio3dev kernel: hwmon hwmon1: Voltage normalised Feb 03 11:21:46 volumio3dev volumio[1061]: info: MYVOLUMIO Environment detected Feb 03 11:21:46 volumio3dev volumio[1061]: info: Plugin folders cleanup Feb 03 11:21:46 volumio3dev volumio[1061]: info: Scanning into folder /volumio/app/plugins/ Feb 03 11:21:46 volumio3dev volumio[1061]: info: Scanning category audio_interface Feb 03 11:21:46 volumio3dev volumio[1061]: info: Scanning category miscellanea Feb 03 11:21:46 volumio3dev volumio[1061]: info: Scanning category music_service Feb 03 11:21:46 volumio3dev volumio[1061]: info: Scanning category plugins.json Feb 03 11:21:46 volumio3dev volumio[1061]: info: Scanning category system_controller Feb 03 11:21:46 volumio3dev volumio[1061]: info: Scanning category user_interface Feb 03 11:21:46 volumio3dev volumio[1061]: info: Scanning into folder /data/plugins/ Feb 03 11:21:46 volumio3dev volumio[1061]: info: Scanning category music_service Feb 03 11:21:46 volumio3dev volumio[1061]: info: Scanning category system_controller Feb 03 11:21:46 volumio3dev volumio[1061]: info: Scanning category user_interface Feb 03 11:21:46 volumio3dev volumio[1061]: info: Plugin folders cleanup completed Feb 03 11:21:46 volumio3dev volumio[1061]: info: ------------------------------------------- Feb 03 11:21:46 volumio3dev volumio[1061]: info: ----- Core plugins startup ---- Feb 03 11:21:46 volumio3dev volumio[1061]: info: ------------------------------------------- Feb 03 11:21:46 volumio3dev volumio[1061]: info: Loading plugins from folder /volumio/app/plugins/ Feb 03 11:21:46 volumio3dev volumio[1061]: info: Adding plugin upnp to MyMusic Plugins Feb 03 11:21:46 volumio3dev volumio[1061]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 03 11:21:46 volumio3dev volumio[1061]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 03 11:21:46 volumio3dev volumio[1061]: info: Loading plugins from folder /data/plugins/ Feb 03 11:21:46 volumio3dev volumio[1061]: info: Loading plugin "system"... Feb 03 11:21:46 volumio3dev volumio[1061]: info: Loading plugin "ampswitch"... Feb 03 11:21:47 volumio3dev volumio[1061]: info: Applying required configuration parameters for plugin ampswitch Feb 03 11:21:47 volumio3dev volumio-remote-updater[512]: [2024-02-03 11:21:47] [connect] Successful connection Feb 03 11:21:47 volumio3dev volumio[1061]: info: Loading plugin "appearance"... Feb 03 11:21:48 volumio3dev volumio[1061]: info: Loading plugin "network"... Feb 03 11:21:48 volumio3dev volumio[1061]: info: Refreshing Cached IP Addresses Feb 03 11:21:48 volumio3dev sudo[1112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 03 11:21:48 volumio3dev sudo[1112]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:21:48 volumio3dev sudo[1112]: pam_unix(sudo:session): session closed for user root Feb 03 11:21:48 volumio3dev sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 03 11:21:48 volumio3dev volumio[1061]: info: Loading plugin "services"... Feb 03 11:21:48 volumio3dev sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:21:48 volumio3dev sudo[1114]: pam_unix(sudo:session): session closed for user root Feb 03 11:21:48 volumio3dev volumio[1061]: info: Loading plugin "alsa_controller"... Feb 03 11:21:48 volumio3dev sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 03 11:21:48 volumio3dev sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:21:49 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 03 11:21:49 volumio3dev volumio[1061]: info: Loading plugin "wizard"... Feb 03 11:21:49 volumio3dev volumio[1061]: info: Loading plugin "networkfs"... Feb 03 11:21:49 volumio3dev volumio[1061]: info: Starting Udev Watcher for removable devices Feb 03 11:21:49 volumio3dev sudo[1125]: 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,rw //192.168.4.50/Multimedia/Music /mnt/NAS/Music Feb 03 11:21:49 volumio3dev sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:21:49 volumio3dev kernel: Key type cifs.spnego registered Feb 03 11:21:49 volumio3dev kernel: Key type cifs.idmap registered Feb 03 11:21:49 volumio3dev 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. Feb 03 11:21:49 volumio3dev kernel: CIFS: Attempting to mount \\192.168.4.50\Multimedia Feb 03 11:21:49 volumio3dev volumio[1061]: info: Ignoring mount for partition: boot Feb 03 11:21:49 volumio3dev volumio[1061]: info: Ignoring mount for partition: volumio Feb 03 11:21:49 volumio3dev volumio[1061]: info: Ignoring mount for partition: volumio_data Feb 03 11:21:49 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 03 11:21:49 volumio3dev volumio[1061]: info: Loading plugin "volumio_command_line_client"... Feb 03 11:21:49 volumio3dev volumio[1061]: info: Loading plugin "upnp"... Feb 03 11:21:49 volumio3dev volumio[1061]: info: [1706984509414] Starting Upmpd Daemon Feb 03 11:21:49 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 03 11:21:49 volumio3dev volumio[1061]: info: Loading plugin "my_music"... Feb 03 11:21:49 volumio3dev volumio[1061]: info: Loading plugin "mpd"... Feb 03 11:21:49 volumio3dev sudo[1125]: pam_unix(sudo:session): session closed for user root Feb 03 11:21:49 volumio3dev sudo[1117]: pam_unix(sudo:session): session closed for user root Feb 03 11:21:49 volumio3dev volumio[1061]: info: Loading plugin "upnp_browser"... Feb 03 11:21:50 volumio3dev volumio[1061]: info: Loading plugin "alarm-clock"... Feb 03 11:21:50 volumio3dev volumio[1061]: info: Loading plugin "airplay_emulation"... Feb 03 11:21:50 volumio3dev volumio[1061]: info: Starting Shairport Sync Feb 03 11:21:50 volumio3dev volumio[1061]: info: Loading plugin "last_100"... Feb 03 11:21:50 volumio3dev volumio[1061]: info: Loading plugin "webradio"... Feb 03 11:21:50 volumio3dev volumio[1061]: info: Loading plugin "i2s_dacs"... Feb 03 11:21:50 volumio3dev volumio[1061]: info: I2S DAC not set, start Auto-detection Feb 03 11:21:50 volumio3dev volumio[1061]: info: Loading plugin "volumiodiscovery"... Feb 03 11:21:50 volumio3dev volumio[1061]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 03 11:21:50 volumio3dev volumio[1061]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 03 11:21:50 volumio3dev volumio[1061]: *** WARNING *** For more information see Feb 03 11:21:50 volumio3dev volumio[1061]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 03 11:21:50 volumio3dev volumio[1061]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 03 11:21:50 volumio3dev volumio[1061]: *** WARNING *** For more information see Feb 03 11:21:50 volumio3dev node[1061]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 03 11:21:50 volumio3dev node[1061]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 03 11:21:50 volumio3dev node[1061]: *** WARNING *** For more information see Feb 03 11:21:50 volumio3dev node[1061]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 03 11:21:50 volumio3dev node[1061]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 03 11:21:50 volumio3dev node[1061]: *** WARNING *** For more information see Feb 03 11:21:50 volumio3dev volumio[1061]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 03 11:21:50 volumio3dev volumio[1061]: info: Discovery: Started advertising with name: Volumio3Dev Feb 03 11:21:51 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 03 11:21:51 volumio3dev volumio[1061]: info: Loading plugin "spop"... Feb 03 11:21:52 volumio3dev volumio[1061]: info: Loading plugin "autostart"... Feb 03 11:21:53 volumio3dev volumio[1061]: info: Applying required configuration parameters for plugin autostart Feb 03 11:21:53 volumio3dev volumio[1061]: info: AutoStart - onVolumioStart - read config.json Feb 03 11:21:53 volumio3dev volumio[1061]: info: Loading plugin "now_playing"... Feb 03 11:21:55 volumio3dev volumio[1061]: info: Loading plugin "outputs"... Feb 03 11:21:55 volumio3dev volumio[1061]: info: Loading plugin "albumart"... Feb 03 11:21:55 volumio3dev volumio[1061]: info: Plugin example_plugin is not enabled Feb 03 11:21:55 volumio3dev volumio[1061]: info: Loading plugin "inputs"... Feb 03 11:21:55 volumio3dev volumio[1061]: info: Loading plugin "updater_comm"... Feb 03 11:21:55 volumio3dev volumio[1061]: info: Plugin mpdemulation is not enabled Feb 03 11:21:55 volumio3dev volumio[1061]: info: Loading plugin "rest_api"... Feb 03 11:21:55 volumio3dev volumio[1061]: info: Loading plugin "websocket"... Feb 03 11:21:55 volumio3dev volumio[1061]: info: Loading plugin "pandora"... Feb 03 11:21:55 volumio3dev volumio[1061]: Forking 3 albumart workers Feb 03 11:21:56 volumio3dev volumio[1061]: Starting albumart workers Feb 03 11:21:56 volumio3dev volumio[1061]: Starting albumart workers Feb 03 11:21:56 volumio3dev volumio[1061]: Starting albumart workers Feb 03 11:21:57 volumio3dev volumio[1061]: info: Applying required configuration parameters for plugin pandora Feb 03 11:21:57 volumio3dev volumio[1061]: info: Loading plugin "Systeminfo"... Feb 03 11:21:58 volumio3dev volumio[1061]: info: Loading i18n strings for locale en Feb 03 11:21:58 volumio3dev volumio[1061]: Updating browse sources language Feb 03 11:21:58 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 03 11:21:58 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 03 11:21:58 volumio3dev volumio[1061]: info: CoreCommandRouter::initPlayerControls Feb 03 11:21:58 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:21:58 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:21:58 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:21:58 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:21:58 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:21:58 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:21:58 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:21:58 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:21:58 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 03 11:21:58 volumio3dev volumio[1061]: Express server listening on port 3000 Feb 03 11:21:58 volumio3dev volumio[1061]: [Metrics] WebUI: 14s 644.85ms Feb 03 11:21:58 volumio3dev volumio[1061]: info: CoreStateMachine::resetVolumioState Feb 03 11:21:58 volumio3dev volumio[1061]: info: CoreStateMachine::getcurrentVolume Feb 03 11:21:58 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioRetrievevolume Feb 03 11:21:58 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 1 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 2 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 3 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 4 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 5 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 6 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.21 UA: node-XMLHttpRequest Total Clients: 7 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.21 UA: node-XMLHttpRequest Total Clients: 8 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.21 UA: node-XMLHttpRequest Total Clients: 9 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.21 UA: node-XMLHttpRequest Total Clients: 11 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.21 UA: node-XMLHttpRequest Total Clients: 12 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 13 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 14 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.21 UA: node-XMLHttpRequest Total Clients: 15 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.21 UA: node-XMLHttpRequest Total Clients: 16 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.21 UA: node-XMLHttpRequest Total Clients: 17 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.21 UA: node-XMLHttpRequest Total Clients: 18 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.21 UA: node-XMLHttpRequest Total Clients: 19 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.21 UA: node-XMLHttpRequest Total Clients: 20 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.21 UA: node-XMLHttpRequest Total Clients: 21 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180:3000 from 192.168.4.43 UA: node-XMLHttpRequest Total Clients: 22 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.21 UA: node-XMLHttpRequest Total Clients: 23 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.21 UA: node-XMLHttpRequest Total Clients: 24 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.21 UA: node-XMLHttpRequest Total Clients: 25 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.21 UA: node-XMLHttpRequest Total Clients: 26 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180:3000 from 192.168.4.43 UA: node-XMLHttpRequest Total Clients: 27 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 28 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 29 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 30 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 31 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 32 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 33 Feb 03 11:21:59 volumio3dev sudo[1208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 03 11:21:59 volumio3dev sudo[1208]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:21:59 volumio3dev sudo[1208]: pam_unix(sudo:session): session closed for user root Feb 03 11:21:59 volumio3dev sudo[1210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 03 11:21:59 volumio3dev sudo[1210]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:21:59 volumio3dev sudo[1210]: pam_unix(sudo:session): session closed for user root Feb 03 11:21:59 volumio3dev volumio[1061]: info: Reloading queue from file Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 34 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 35 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 36 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 37 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 38 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.21 UA: node-XMLHttpRequest Total Clients: 39 Feb 03 11:21:59 volumio3dev volumio[1061]: info: VolumeController:: Volume=29 Mute =false Feb 03 11:21:59 volumio3dev volumio[1061]: info: CoreStateMachine::pushState Feb 03 11:21:59 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:21:59 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 03 11:21:59 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushState Feb 03 11:21:59 volumio3dev volumio[1061]: info: CoreStateMachine::updateTrackBlock Feb 03 11:21:59 volumio3dev volumio[1061]: info: CorePlayQueue::getTrackBlock Feb 03 11:21:59 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioRetrievevolume Feb 03 11:21:59 volumio3dev volumio[1061]: info: CoreStateMachine::setRepeat undefined single undefined Feb 03 11:21:59 volumio3dev volumio[1061]: info: CoreStateMachine::pushState Feb 03 11:21:59 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:21:59 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 03 11:21:59 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushState Feb 03 11:21:59 volumio3dev volumio[1061]: info: CoreStateMachine::setRandom false Feb 03 11:21:59 volumio3dev volumio[1061]: info: CoreStateMachine::pushState Feb 03 11:21:59 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:21:59 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 03 11:21:59 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushState Feb 03 11:21:59 volumio3dev volumio[1061]: info: Setting Device type: Raspberry PI Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 40 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 41 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 42 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 43 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 44 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 45 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 46 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 47 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 48 Feb 03 11:21:59 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 49 Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 03 11:22:00 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 50 Feb 03 11:22:00 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 51 Feb 03 11:22:00 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 52 Feb 03 11:22:00 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 53 Feb 03 11:22:00 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 54 Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:00 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:00 volumio3dev volumio[1061]: info: Discovery: adding 467441f9-8569-4936-b2fb-978274ffca53 Feb 03 11:22:00 volumio3dev volumio[1061]: info: Discovery: Found device Volumio3Dev Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:00 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:00 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 55 Feb 03 11:22:00 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 56 Feb 03 11:22:00 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 57 Feb 03 11:22:00 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 58 Feb 03 11:22:00 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 59 Feb 03 11:22:00 volumio3dev volumio[1061]: info: VolumeController:: Volume=29 Mute =false Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreStateMachine::pushState Feb 03 11:22:00 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushState Feb 03 11:22:00 volumio3dev volumio[1061]: info: Discovery: this is already registered, 467441f9-8569-4936-b2fb-978274ffca53 Feb 03 11:22:00 volumio3dev volumio[1061]: info: Discovery: Found device Volumio3Dev Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:00 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:00 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 60 Feb 03 11:22:00 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 61 Feb 03 11:22:00 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 62 Feb 03 11:22:00 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 63 Feb 03 11:22:00 volumio3dev sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 03 11:22:00 volumio3dev sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:22:00 volumio3dev volumio[1061]: info: Discovery: adding 22edebbc-2cc3-417c-97ef-cad815606792 Feb 03 11:22:00 volumio3dev volumio[1061]: info: Discovery: Found device VolumioGCB Feb 03 11:22:00 volumio3dev volumio[1061]: info: Discovery: Connecting to remote: 192.168.4.43 Feb 03 11:22:00 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 64 Feb 03 11:22:00 volumio3dev volumio[1061]: info: Completed loading Core Plugins Feb 03 11:22:00 volumio3dev volumio[1061]: info: Preparing to generate the ALSA configuration file Feb 03 11:22:00 volumio3dev systemd[1]: Started UPnP Renderer front-end to MPD. Feb 03 11:22:00 volumio3dev sudo[1226]: pam_unix(sudo:session): session closed for user root Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:00 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:00 volumio3dev volumio[1061]: info: Received Get System Info Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 03 11:22:00 volumio3dev volumio[1061]: info: Discovery: Getting this device information Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:00 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:00 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:00 volumio3dev volumio[1061]: info: Received Get System Info Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 03 11:22:00 volumio3dev volumio[1061]: info: Discovery: Getting this device information Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:00 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:00 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetVisibleSources Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:00 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:00 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 03 11:22:00 volumio3dev volumio[1061]: info: Received Get System Info Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 03 11:22:00 volumio3dev volumio[1061]: info: Discovery: Getting this device information Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:00 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:00 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:00 volumio3dev volumio[1061]: info: Listing playlists Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetQueue Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreStateMachine::getQueue Feb 03 11:22:00 volumio3dev volumio[1061]: info: CorePlayQueue::getQueue Feb 03 11:22:00 volumio3dev volumio[1061]: info: Received Get System Info Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 03 11:22:00 volumio3dev volumio[1061]: info: Discovery: Getting this device information Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:00 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 03 11:22:00 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:00 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:00 volumio3dev volumio[1061]: info: Upmpdcli Daemon Started Feb 03 11:22:00 volumio3dev volumio[1061]: info: Asound.conf file unchanged, so no further update is needed Feb 03 11:22:00 volumio3dev volumio[1061]: info: Output device has changed, restarting MPD Feb 03 11:22:01 volumio3dev volumio[1061]: info: Output device has changed, restarting Shairport Sync Feb 03 11:22:01 volumio3dev sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:01 volumio3dev sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:22:01 volumio3dev sudo[1230]: pam_unix(sudo:session): session closed for user root Feb 03 11:22:01 volumio3dev sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 03 11:22:01 volumio3dev sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:22:01 volumio3dev volumio[1061]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 03 11:22:01 volumio3dev volumio[1061]: info: ___________ START PLUGINS ___________ Feb 03 11:22:01 volumio3dev volumio[1061]: info: ControllerMpd::onStart: Initializing MPD Feb 03 11:22:01 volumio3dev volumio[1061]: info: Creating MPD Configuration file Feb 03 11:22:01 volumio3dev systemd[1]: Listening on mpd.socket. Feb 03 11:22:01 volumio3dev systemd[1]: Starting Music Player Daemon... Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 03 11:22:01 volumio3dev sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521142] CoreMusicLibrary::Adding element Media Servers Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 03 11:22:01 volumio3dev sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:22:01 volumio3dev sudo[1248]: pam_unix(sudo:session): session closed for user root Feb 03 11:22:01 volumio3dev sudo[1250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 03 11:22:01 volumio3dev sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:01 volumio3dev systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 03 11:22:01 volumio3dev systemd[1]: mpd.service: Succeeded. Feb 03 11:22:01 volumio3dev systemd[1]: Stopped Music Player Daemon. Feb 03 11:22:01 volumio3dev systemd[1]: Starting Music Player Daemon... Feb 03 11:22:01 volumio3dev volumio[1061]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:01 volumio3dev volumio[1061]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521299] CoreMusicLibrary::Adding element Last_100 Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521304] CoreMusicLibrary::Adding element Webradio Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 03 11:22:01 volumio3dev volumio[1061]: info: Creating Spotify config file Feb 03 11:22:01 volumio3dev sudo[1256]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:01 volumio3dev sudo[1256]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:22:01 volumio3dev sudo[1256]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 03 11:22:01 volumio3dev sudo[1256]: pam_unix(sudo:session): session closed for user root Feb 03 11:22:01 volumio3dev volumio[1061]: info: AutoStart - onStart Feb 03 11:22:01 volumio3dev volumio[1061]: info: [now-playing] ConfigUpdater: config is up to date. Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521472] CoreMusicLibrary::Adding element Pandora Radio Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 03 11:22:01 volumio3dev volumio[1061]: Cannot find translation for source Pandora Radio Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521476] ControllerPandora::initializeMQTT Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521477] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Feb 03 11:22:01 volumio3dev volumio[1061]: info: Volumio Calling Home Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521554] ControllerPandora::initialSetup Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521569] ControllerPandora::PandoraHandler::init Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521569] ControllerPandora::PandoraHandler::loadErrorCodes Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521581] [Pandora] PandoraHandler::setMaxStationTracks: 16 Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521583] [Pandora] PandoraHandler::setBandFilter: bandfilter is empty Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521584] ControllerPandora::flushPandora Feb 03 11:22:01 volumio3dev volumio[1061]: info: CorePlayQueue::getQueue Feb 03 11:22:01 volumio3dev volumio[1061]: info: CorePlayQueue::clearPlayQueue Feb 03 11:22:01 volumio3dev volumio[1061]: info: CorePlayQueue::saveQueue Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushQueue Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521595] ControllerPandora::validateAndSetAccountOptions Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521596] ControllerPandora::PandoraHandler::setAccountOptions Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521598] ControllerPandora::flushPandora Feb 03 11:22:01 volumio3dev volumio[1061]: info: CorePlayQueue::getQueue Feb 03 11:22:01 volumio3dev volumio[1061]: info: CorePlayQueue::clearPlayQueue Feb 03 11:22:01 volumio3dev volumio[1061]: info: CorePlayQueue::saveQueue Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushQueue Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521608] [Pandora] PreventAuthTimeout::init: Timer loaded Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521608] [Pandora] PreventAuthTimeout::init: Interval set to 10800000 ms Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521609] [Pandora] PreventAuthTimeout::init: Starting delayed timer after next interval Feb 03 11:22:01 volumio3dev volumio[1061]: info: [1706984521610] ControllerPandora::PandoraHandler::pandoraLoginAndGetStations Feb 03 11:22:01 volumio3dev volumio[1061]: info: [now-playing] App is listening on port 4004. Feb 03 11:22:01 volumio3dev volumio[1061]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Feb 03 11:22:01 volumio3dev volumio[1061]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Feb 03 11:22:01 volumio3dev volumio[1061]: info: AutoStart - Plugin is starting Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetQueue Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreStateMachine::getQueue Feb 03 11:22:01 volumio3dev volumio[1061]: info: CorePlayQueue::getQueue Feb 03 11:22:01 volumio3dev volumio[1061]: info: Discovery: Connected to remote: 192.168.4.43 Feb 03 11:22:01 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:01 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:01 volumio3dev volumio[1061]: info: MPD Permissions set Feb 03 11:22:01 volumio3dev volumio[1061]: info: MPD Permissions set Feb 03 11:22:02 volumio3dev volumio[1061]: info: Spotify config file written Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:02 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:02 volumio3dev volumio-remote-updater[512]: [2024-02-03 11:22:02] [connect] Successful connection Feb 03 11:22:02 volumio3dev sudo[1266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 03 11:22:02 volumio3dev sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:22:02 volumio3dev volumio[1061]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 65 Feb 03 11:22:02 volumio3dev volumio[1061]: info: Volumio called home Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:02 volumio3dev systemd[1]: Started go-librespot Daemon. Feb 03 11:22:02 volumio3dev go-librespot[1268]: Librespot-go daemon starting... Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:02 volumio3dev sudo[1266]: pam_unix(sudo:session): session closed for user root Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:02 volumio3dev volumio-remote-updater[512]: [2024-02-03 11:22:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1706984522 101 Feb 03 11:22:02 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 66 Feb 03 11:22:02 volumio3dev volumio[1061]: info: Starting Shairport Sync Feb 03 11:22:02 volumio3dev volumio[1061]: info: Starting Shairport Sync Feb 03 11:22:02 volumio3dev sudo[1284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 03 11:22:02 volumio3dev sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:22:02 volumio3dev volumio[1061]: info: Starting Shairport Sync Feb 03 11:22:02 volumio3dev systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 03 11:22:02 volumio3dev systemd[1]: shairport-sync.service: Succeeded. Feb 03 11:22:02 volumio3dev systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 03 11:22:02 volumio3dev sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 03 11:22:02 volumio3dev sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:22:02 volumio3dev sudo[1287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 03 11:22:02 volumio3dev sudo[1287]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:22:02 volumio3dev systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 03 11:22:02 volumio3dev sudo[1284]: pam_unix(sudo:session): session closed for user root Feb 03 11:22:02 volumio3dev systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 03 11:22:02 volumio3dev systemd[1]: shairport-sync.service: Succeeded. Feb 03 11:22:02 volumio3dev systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 03 11:22:02 volumio3dev systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 03 11:22:02 volumio3dev sudo[1287]: pam_unix(sudo:session): session closed for user root Feb 03 11:22:02 volumio3dev sudo[1289]: pam_unix(sudo:session): session closed for user root Feb 03 11:22:02 volumio3dev go-librespot[1268]: time="2024-02-03T11:22:02-07:00" level=info msg="generated new device id: 4430a70425fb1aa1560cf589c62d20beaf2b8cc6" Feb 03 11:22:02 volumio3dev volumio[1061]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 03 11:22:02 volumio3dev volumio[1061]: SPOTIFY: BQBhuYGsiqq6dkRJ6X7bVgHhlGiPkL0IQJ7oIEknqea1Elfl0brFkXb5JMx6Fqo3-c81MI_BmQiv5QKyr8dDeB7PttxdcfoHz0FQwGFAjGEyn1-A1ZZOeLZ6NghsumkeTZ7IslVfWNW9f3WL3IrfYdA97nf5gVyGHg94a1ZAe-wcNXfEeZSEmsY-BYUfXj00lbZ9zfR-29FM-szrSqynMJsE5LPNtfHaKEUlwWbvxmnA15VGBmD4Sj4 Feb 03 11:22:02 volumio3dev volumio[1061]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Feb 03 11:22:02 volumio3dev volumio[1061]: info: New Spotify access token = BQBhuYGsiqq6dkRJ6X7bVgHhlGiPkL0IQJ7oIEknqea1Elfl0brFkXb5JMx6Fqo3-c81MI_BmQiv5QKyr8dDeB7PttxdcfoHz0FQwGFAjGEyn1-A1ZZOeLZ6NghsumkeTZ7IslVfWNW9f3WL3IrfYdA97nf5gVyGHg94a1ZAe-wcNXfEeZSEmsY-BYUfXj00lbZ9zfR-29FM-szrSqynMJsE5LPNtfHaKEUlwWbvxmnA15VGBmD4Sj4 Feb 03 11:22:02 volumio3dev volumio[1061]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 03 11:22:03 volumio3dev systemd[1]: systemd-fsckd.service: Succeeded. Feb 03 11:22:03 volumio3dev volumio[1061]: info: Shairport-Sync Started Feb 03 11:22:03 volumio3dev volumio[1061]: Error adding Membership: Error: addMembership EINVAL Feb 03 11:22:03 volumio3dev volumio[1061]: info: Shairport-Sync Started Feb 03 11:22:03 volumio3dev volumio[1061]: info: Shairport-Sync Started Feb 03 11:22:03 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:03 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:03 volumio3dev volumio[1061]: info: Received Get System Info Feb 03 11:22:03 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 03 11:22:03 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 03 11:22:03 volumio3dev volumio[1061]: info: Discovery: Getting this device information Feb 03 11:22:03 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:03 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:03 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 03 11:22:03 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:03 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:03 volumio3dev volumio[1061]: info: [ASDebug] CurState: stop PrevState: na Feb 03 11:22:03 volumio3dev volumio[1061]: info: [ASDebug] InitTimeout - Amp off in: 300000 ms Feb 03 11:22:03 volumio3dev volumio[1061]: info: [ASDebug] CurState: stop PrevState: na Feb 03 11:22:03 volumio3dev volumio[1061]: info: [ASDebug] InitTimeout - Amp off in: 300000 ms Feb 03 11:22:03 volumio3dev volumio[1061]: info: [1706984523426] [Pandora] PandoraHandler::pandoraLoginAndGetStations::pandoraLogin: [<=- Logged in to Pandora Servers -=>] Feb 03 11:22:03 volumio3dev volumio[1061]: info: [1706984523521] [Pandora] validateAndSetAccountOptions: ***DEBUG*** self.loggedIn: true Feb 03 11:22:03 volumio3dev volumio[1061]: info: [1706984523523] [Pandora] ExpireOldTracks::init: Timer loaded Feb 03 11:22:03 volumio3dev volumio[1061]: info: [1706984523524] [Pandora] ExpireOldTracks::init: Interval set to 300000 ms Feb 03 11:22:03 volumio3dev volumio[1061]: info: [1706984523524] [Pandora] ExpireOldTracks::init: Starting delayed timer after next interval Feb 03 11:22:03 volumio3dev volumio[1061]: info: [1706984523526] ControllerPandora::PandoraHandler::fillStationData Feb 03 11:22:03 volumio3dev go-librespot[1268]: time="2024-02-03T11:22:03-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 03 11:22:03 volumio3dev go-librespot[1268]: time="2024-02-03T11:22:03-07:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 03 11:22:03 volumio3dev go-librespot[1268]: time="2024-02-03T11:22:03-07:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 03 11:22:03 volumio3dev go-librespot[1268]: time="2024-02-03T11:22:03-07:00" level=debug msg="zeroconf server listening on port 44523" Feb 03 11:22:03 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 03 11:22:03 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 03 11:22:03 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetVisibleSources Feb 03 11:22:03 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 03 11:22:03 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:03 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:03 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:03 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:03 volumio3dev volumio[1061]: SPOTIFY: User informations: {"display_name":"gcannonfodder","external_urls":{"spotify":"https://open.spotify.com/user/gcannonfodder"},"href":"https://api.spotify.com/v1/users/gcannonfodder","id":"gcannonfodder","images":[],"type":"user","uri":"spotify:user:gcannonfodder","followers":{"href":null,"total":0},"country":"US","product":"premium","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"glennburnett1@gmail.com"} Feb 03 11:22:03 volumio3dev volumio[1061]: info: Spotify Successfully logged in Feb 03 11:22:03 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 03 11:22:03 volumio3dev volumio[1061]: info: [1706984523947] CoreMusicLibrary::Adding element Spotify Feb 03 11:22:03 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 03 11:22:03 volumio3dev volumio[1061]: Cannot find translation for source Pandora Radio Feb 03 11:22:03 volumio3dev volumio[1061]: Cannot find translation for source Spotify Feb 03 11:22:04 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 03 11:22:04 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetQueue Feb 03 11:22:04 volumio3dev volumio[1061]: info: CoreStateMachine::getQueue Feb 03 11:22:04 volumio3dev volumio[1061]: info: CorePlayQueue::getQueue Feb 03 11:22:04 volumio3dev volumio[1061]: info: Listing playlists Feb 03 11:22:04 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 03 11:22:04 volumio3dev mpd[1261]: Feb 03 11:22 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 03 11:22:04 volumio3dev volumio[1061]: info: Received Get System Info Feb 03 11:22:04 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 03 11:22:04 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 03 11:22:04 volumio3dev volumio[1061]: info: Discovery: Getting this device information Feb 03 11:22:04 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:04 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:04 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 03 11:22:04 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:04 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:05 volumio3dev systemd[1]: Started Music Player Daemon. Feb 03 11:22:05 volumio3dev sudo[1250]: pam_unix(sudo:session): session closed for user root Feb 03 11:22:05 volumio3dev sudo[1232]: pam_unix(sudo:session): session closed for user root Feb 03 11:22:05 volumio3dev volumio[1061]: info: Completed starting Core Plugins Feb 03 11:22:05 volumio3dev volumio[1061]: info: ------------------------------------------- Feb 03 11:22:05 volumio3dev volumio[1061]: info: ----- MyVolumio plugins startup ---- Feb 03 11:22:05 volumio3dev volumio[1061]: info: ------------------------------------------- Feb 03 11:22:05 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 03 11:22:05 volumio3dev volumio[1061]: error: MPD error: The expression evaluated to a falsy value: Feb 03 11:22:05 volumio3dev volumio[1061]: assert.ok(self.idling) Feb 03 11:22:05 volumio3dev volumio[1061]: error: The expression evaluated to a falsy value: Feb 03 11:22:05 volumio3dev volumio[1061]: assert.ok(self.idling) Feb 03 11:22:05 volumio3dev volumio[1061]: info: MPD running with PID1261 Feb 03 11:22:05 volumio3dev volumio[1061]: ,establishing connection Feb 03 11:22:05 volumio3dev volumio[1061]: error: updateQueue error: null Feb 03 11:22:05 volumio3dev volumio[1061]: error: updateQueue error: null Feb 03 11:22:05 volumio3dev volumio[1061]: info: go-librespot daemon successfully initialized Feb 03 11:22:05 volumio3dev volumio[1228]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 03 11:22:07 volumio3dev systemd[1]: systemd-hostnamed.service: Succeeded. Feb 03 11:22:08 volumio3dev volumio[1061]: info: Initializing connection to go-librespot Websocket Feb 03 11:22:08 volumio3dev go-librespot[1268]: time="2024-02-03T11:22:08-07:00" level=debug msg="new websocket client" Feb 03 11:22:08 volumio3dev volumio[1061]: info: Connection to go-librespot Websocket established Feb 03 11:22:11 volumio3dev volumio[1061]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 03 11:22:11 volumio3dev volumio[1061]: info: Getting Spotify volume Feb 03 11:22:11 volumio3dev volumio[1061]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 67 Feb 03 11:22:12 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:12 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:12 volumio3dev volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Feb 03 11:22:12 volumio3dev volumio[1061]: SPOTIFY: SPOTIFY VOLUME undefined Feb 03 11:22:12 volumio3dev volumio[1061]: SPOTIFY: VOLUMIO VOLUME 29 Feb 03 11:22:12 volumio3dev volumio[1061]: info: Aligning Spotify Volume to Volumio Volume Feb 03 11:22:12 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:12 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:12 volumio3dev volumio[1061]: info: Setting Spotify Volume from Volumio: 29 Feb 03 11:22:13 volumio3dev volumio[1228]: .......................................................++++ Feb 03 11:22:16 volumio3dev volumio[1061]: SPOTIFY: SETTING SPOTIFY VOLUME 29 Feb 03 11:22:16 volumio3dev volumio[1061]: info: Sending Spotify command with payload to local API: /player/volume Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 03 11:22:17 volumio3dev volumio[1061]: info: Adding plugin bluetooth to MyMusic Plugins Feb 03 11:22:17 volumio3dev volumio[1061]: info: Adding plugin multiroom to MyMusic Plugins Feb 03 11:22:17 volumio3dev volumio[1061]: info: Adding plugin metavolumio to MyMusic Plugins Feb 03 11:22:17 volumio3dev volumio[1061]: info: Adding plugin cd_controller to MyMusic Plugins Feb 03 11:22:17 volumio3dev volumio[1061]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 03 11:22:17 volumio3dev volumio[1061]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 03 11:22:17 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 03 11:22:19 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 03 11:22:19 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 03 11:22:19 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:19 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:19 volumio3dev volumio[1061]: info: Starting MyVolumio Remote Streaming Endpoints Feb 03 11:22:19 volumio3dev volumio[1061]: info: MyVolumio login type: Token Feb 03 11:22:19 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 03 11:22:19 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 03 11:22:20 volumio3dev volumio[1061]: info: Starting Streaming Service Transparent Proxy Feb 03 11:22:20 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 03 11:22:20 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 03 11:22:20 volumio3dev volumio[1061]: info: Streaming services startup Feb 03 11:22:20 volumio3dev volumio[1061]: info: Starting Streaming Daemon Feb 03 11:22:20 volumio3dev sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 03 11:22:20 volumio3dev sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:22:20 volumio3dev volumio[1061]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 03 11:22:20 volumio3dev sudo[1337]: pam_unix(sudo:session): session closed for user root Feb 03 11:22:21 volumio3dev volumio[1061]: error: Cannot start Volumio Streaming Daemon Feb 03 11:22:21 volumio3dev volumio[1061]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 03 11:22:21 volumio3dev volumio[1061]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 03 11:22:22 volumio3dev volumio[1061]: STREAMING PROXY: Starting server on port 3245 Feb 03 11:22:22 volumio3dev volumio[1061]: Node JS runtime: 14 Feb 03 11:22:23 volumio3dev volumio[1061]: Cannot compose Albumart path Feb 03 11:22:23 volumio3dev volumio[1061]: Cannot compose Albumart path Feb 03 11:22:25 volumio3dev volumio[1228]: ......................................................................................++++ Feb 03 11:22:25 volumio3dev volumio[1228]: e is 65537 (0x010001) Feb 03 11:22:25 volumio3dev volumio[1228]: writing RSA key Feb 03 11:22:25 volumio3dev volumio[1061]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 03 11:22:26 volumio3dev volumio[1061]: info: MyVolumio token set successfully Feb 03 11:22:26 volumio3dev volumio[1061]: info: MYVOLUMIO: Adding device Feb 03 11:22:26 volumio3dev volumio[1061]: info: MYVOLUMIO: Evaluating Server Feb 03 11:22:26 volumio3dev volumio[1061]: info: MyVolumio status changed Feb 03 11:22:26 volumio3dev volumio[1061]: info: Streaming services startup Feb 03 11:22:26 volumio3dev volumio[1061]: info: Starting Streaming Daemon Feb 03 11:22:26 volumio3dev volumio[1061]: info: Removing browser output: myVolumio user plan is not superstar Feb 03 11:22:26 volumio3dev volumio[1061]: info: Removing audio output: Feb 03 11:22:26 volumio3dev volumio[1061]: info: Stoppping Tunnel 1 Feb 03 11:22:26 volumio3dev sudo[1377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 03 11:22:26 volumio3dev sudo[1377]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:22:26 volumio3dev sudo[1377]: pam_unix(sudo:session): session closed for user root Feb 03 11:22:26 volumio3dev sudo[1380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 03 11:22:26 volumio3dev sudo[1380]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:22:26 volumio3dev volumio[1061]: error: Cannot start Volumio Streaming Daemon Feb 03 11:22:26 volumio3dev volumio[1061]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 03 11:22:26 volumio3dev volumio[1061]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 03 11:22:26 volumio3dev sudo[1380]: pam_unix(sudo:session): session closed for user root Feb 03 11:22:26 volumio3dev volumio[1061]: info: Remote SSH Stopped Feb 03 11:22:27 volumio3dev volumio[1061]: info: Setting Geolocation for MyVolumio to us2 Feb 03 11:22:27 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:27 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:27 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:27 volumio3dev volumio[1061]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 03 11:22:27 volumio3dev sshd[1328]: Accepted password for volumio from 192.168.4.16 port 57587 ssh2 Feb 03 11:22:27 volumio3dev sshd[1328]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Feb 03 11:22:27 volumio3dev systemd[1]: Created slice User Slice of UID 1000. Feb 03 11:22:27 volumio3dev systemd[1]: Starting User Runtime Directory /run/user/1000... Feb 03 11:22:27 volumio3dev systemd-logind[482]: New session 1 of user volumio. Feb 03 11:22:27 volumio3dev systemd[1]: Started User Runtime Directory /run/user/1000. Feb 03 11:22:27 volumio3dev systemd[1]: Starting User Manager for UID 1000... Feb 03 11:22:27 volumio3dev systemd[1383]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Feb 03 11:22:27 volumio3dev systemd[1383]: Reached target Timers. Feb 03 11:22:27 volumio3dev systemd[1383]: Listening on GnuPG network certificate management daemon. Feb 03 11:22:27 volumio3dev systemd[1383]: Reached target Paths. Feb 03 11:22:27 volumio3dev systemd[1383]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Feb 03 11:22:27 volumio3dev systemd[1383]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Feb 03 11:22:27 volumio3dev systemd[1383]: Starting D-Bus User Message Bus Socket. Feb 03 11:22:27 volumio3dev systemd[1383]: Listening on GnuPG cryptographic agent and passphrase cache. Feb 03 11:22:27 volumio3dev systemd[1383]: Listening on Sound System. Feb 03 11:22:27 volumio3dev systemd[1383]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Feb 03 11:22:27 volumio3dev systemd[1383]: Listening on D-Bus User Message Bus Socket. Feb 03 11:22:27 volumio3dev systemd[1383]: Reached target Sockets. Feb 03 11:22:27 volumio3dev systemd[1383]: Reached target Basic System. Feb 03 11:22:27 volumio3dev systemd[1]: Started User Manager for UID 1000. Feb 03 11:22:27 volumio3dev systemd[1]: Started Session 1 of user volumio. Feb 03 11:22:27 volumio3dev systemd[1383]: Starting Music Player Daemon... Feb 03 11:22:28 volumio3dev volumio[1061]: info: Updating MyVolumio device info Feb 03 11:22:28 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:28 volumio3dev volumio[1061]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 03 11:22:28 volumio3dev mpd[1394]: exception: failed to open log file "/var/log/mpd.log" (config line 7): Permission denied Feb 03 11:22:29 volumio3dev systemd[1383]: mpd.service: Main process exited, code=exited, status=1/FAILURE Feb 03 11:22:29 volumio3dev systemd[1383]: mpd.service: Failed with result 'exit-code'. Feb 03 11:22:29 volumio3dev systemd[1383]: Failed to start Music Player Daemon. Feb 03 11:22:29 volumio3dev systemd[1383]: Reached target Default. Feb 03 11:22:29 volumio3dev systemd[1383]: Startup finished in 1.467s. Feb 03 11:22:31 volumio3dev volumio[1061]: info: MYVOLUMIO: Adding device Feb 03 11:22:31 volumio3dev volumio[1061]: info: MYVOLUMIO: Evaluating Server Feb 03 11:22:31 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 03 11:22:31 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:31 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 03 11:22:31 volumio3dev volumio-remote-updater[512]: No test mode Feb 03 11:22:31 volumio3dev volumio-remote-updater[512]: No alpha test mode Feb 03 11:22:31 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 03 11:22:32 volumio3dev volumio[1061]: info: Setting Geolocation for MyVolumio to us2 Feb 03 11:22:32 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:32 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:32 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:32 volumio3dev volumio[1061]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 03 11:22:32 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 03 11:22:32 volumio3dev volumio[1061]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 03 11:22:33 volumio3dev volumio[1061]: info: Updating MyVolumio device info Feb 03 11:22:33 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:33 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:33 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:33 volumio3dev volumio[1061]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 03 11:22:34 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 03 11:22:34 volumio3dev volumio[1061]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 68 Feb 03 11:22:34 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:22:34 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:22:39 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:39 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:39 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 03 11:22:39 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 03 11:22:39 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 03 11:22:39 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetBrowseSources Feb 03 11:22:39 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 03 11:22:40 volumio3dev volumio[1061]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 03 11:22:40 volumio3dev volumio[1061]: info: Completed starting MyVolumio Plugin Feb 03 11:22:40 volumio3dev volumio[1061]: info: BOOT COMPLETED Feb 03 11:22:40 volumio3dev volumio[1061]: [Metrics] CommandRouter: 55s 599.10ms Feb 03 11:22:40 volumio3dev volumio[1061]: info: CoreCommandRouter::volumiosetStartupVolume Feb 03 11:22:40 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 03 11:22:40 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 03 11:22:40 volumio3dev volumio[1061]: info: CoreCommandRouter::Close All Modals sent Feb 03 11:22:40 volumio3dev volumio[1061]: info: CoreCommandRouter::Close All Modals sent Feb 03 11:22:41 volumio3dev ntpd[678]: Soliciting pool server 137.184.81.69 Feb 03 11:22:41 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 03 11:22:41 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 03 11:22:41 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 03 11:22:42 volumio3dev ntpd[678]: Soliciting pool server 71.19.144.140 Feb 03 11:22:43 volumio3dev ntpd[678]: Soliciting pool server 5.161.184.148 Feb 03 11:22:44 volumio3dev ntpd[678]: Soliciting pool server 65.100.46.166 Feb 03 11:22:44 volumio3dev ntpd[678]: Soliciting pool server 198.71.50.75 Feb 03 11:22:45 volumio3dev ntpd[678]: Soliciting pool server 205.233.73.201 Feb 03 11:22:45 volumio3dev ntpd[678]: Soliciting pool server 216.240.36.24 Feb 03 11:22:46 volumio3dev ntpd[678]: Soliciting pool server 209.51.161.238 Feb 03 11:22:46 volumio3dev ntpd[678]: Soliciting pool server 162.159.200.123 Feb 03 11:22:46 volumio3dev ntpd[678]: Soliciting pool server 198.137.202.32 Feb 03 11:22:47 volumio3dev ntpd[678]: Soliciting pool server 71.162.136.44 Feb 03 11:22:47 volumio3dev ntpd[678]: Soliciting pool server 129.146.193.200 Feb 03 11:22:47 volumio3dev ntpd[678]: Soliciting pool server 3.140.11.117 Feb 03 11:22:48 volumio3dev ntpd[678]: Soliciting pool server 74.119.243.5 Feb 03 11:23:08 volumio3dev ntpd[678]: receive: Unexpected origin timestamp 0xe96902f9.1f5c1ebe does not match aorg 0000000000.00000000 from server@205.233.73.201 xmt 0xe969030c.1df122c0 Feb 03 11:23:08 volumio3dev ntpd[678]: receive: Unexpected origin timestamp 0xe96902f9.1f5e074a does not match aorg 0000000000.00000000 from server@198.71.50.75 xmt 0xe969030c.220b6763 Feb 03 11:23:08 volumio3dev ntpd[678]: receive: Unexpected origin timestamp 0xe96902f9.1f553562 does not match aorg 0000000000.00000000 from server@209.51.161.238 xmt 0xe969030c.226aac73 Feb 03 11:23:08 volumio3dev ntpd[678]: receive: Unexpected origin timestamp 0xe96902f9.1f5fb100 does not match aorg 0000000000.00000000 from server@5.161.184.148 xmt 0xe969030c.2270ecec Feb 03 11:23:20 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 03 11:23:20 volumio3dev volumio[1061]: info: In handleBrowseUri, curUri=spotify Feb 03 11:23:20 volumio3dev volumio[1061]: info: Preload queue cleared Feb 03 11:23:20 volumio3dev volumio[1061]: info: Preload queue cleared Feb 03 11:23:20 volumio3dev volumio[1061]: info: Preload queue cleared Feb 03 11:23:20 volumio3dev volumio[1061]: info: Preload queue cleared Feb 03 11:23:24 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 03 11:23:24 volumio3dev volumio[1061]: info: In handleBrowseUri, curUri=spotify/playlists Feb 03 11:23:24 volumio3dev volumio[1061]: info: Preload queue cleared Feb 03 11:23:31 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 03 11:23:31 volumio3dev volumio[1061]: info: CoreStateMachine::ClearQueue Feb 03 11:23:31 volumio3dev volumio[1061]: info: CoreStateMachine::stop Feb 03 11:23:31 volumio3dev volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 03 11:23:31 volumio3dev volumio[1061]: info: CorePlayQueue::clearPlayQueue Feb 03 11:23:31 volumio3dev volumio[1061]: info: CorePlayQueue::saveQueue Feb 03 11:23:31 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushQueue Feb 03 11:23:31 volumio3dev volumio[1061]: info: CoreStateMachine::addQueueItems Feb 03 11:23:31 volumio3dev volumio[1061]: info: CorePlayQueue::addQueueItems Feb 03 11:23:31 volumio3dev volumio[1061]: info: Preload queue cleared Feb 03 11:23:31 volumio3dev volumio[1061]: info: Adding Item to queue: spotify:user:spotify:playlist:37i9dQZF1EId51c7hllKL5 Feb 03 11:23:31 volumio3dev volumio[1061]: info: Exploding uri spotify:user:spotify:playlist:37i9dQZF1EId51c7hllKL5 in service spop Feb 03 11:23:31 volumio3dev volumio[1061]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:37i9dQZF1EId51c7hllKL5 Feb 03 11:23:31 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushQueue Feb 03 11:23:31 volumio3dev volumio[1061]: info: CorePlayQueue::saveQueue Feb 03 11:23:31 volumio3dev volumio[1061]: info: CoreStateMachine::updateTrackBlock Feb 03 11:23:31 volumio3dev volumio[1061]: info: CorePlayQueue::getTrackBlock Feb 03 11:23:31 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPlay Feb 03 11:23:31 volumio3dev volumio[1061]: info: CoreStateMachine::play index 0 Feb 03 11:23:31 volumio3dev volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 03 11:23:31 volumio3dev volumio[1061]: info: CoreStateMachine::stop Feb 03 11:23:31 volumio3dev volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 03 11:23:31 volumio3dev volumio[1061]: info: CoreStateMachine::play index undefined Feb 03 11:23:31 volumio3dev volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 03 11:23:31 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:23:31 volumio3dev volumio[1061]: info: CoreStateMachine::startPlaybackTimer Feb 03 11:23:31 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:23:31 volumio3dev volumio[1061]: info: [1706984611843] ControllerSpotify::clearAddPlayTrack Feb 03 11:23:31 volumio3dev volumio[1061]: info: Sending Spotify command with payload to local API: /player/play Feb 03 11:23:44 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:23:44 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:23:49 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPlay Feb 03 11:23:49 volumio3dev volumio[1061]: info: CoreStateMachine::play index 0 Feb 03 11:23:49 volumio3dev volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 03 11:23:49 volumio3dev volumio[1061]: info: CoreStateMachine::stop Feb 03 11:23:49 volumio3dev volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 03 11:23:49 volumio3dev volumio[1061]: info: CoreStateMachine::play index undefined Feb 03 11:23:49 volumio3dev volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 03 11:23:49 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:23:49 volumio3dev volumio[1061]: info: CoreStateMachine::startPlaybackTimer Feb 03 11:23:49 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:23:49 volumio3dev volumio[1061]: info: [1706984629403] ControllerSpotify::clearAddPlayTrack Feb 03 11:23:49 volumio3dev volumio[1061]: info: Sending Spotify command with payload to local API: /player/play Feb 03 11:24:26 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:24:26 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 0 Feb 03 11:24:35 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 03 11:24:35 volumio3dev volumio[1061]: info: CURURI: music-library Feb 03 11:24:35 volumio3dev volumio[1061]: info: Preload queue cleared Feb 03 11:24:37 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 03 11:24:37 volumio3dev volumio[1061]: info: CURURI: music-library/NAS Feb 03 11:24:37 volumio3dev volumio[1061]: info: Preload queue cleared Feb 03 11:24:38 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 03 11:24:38 volumio3dev volumio[1061]: info: CURURI: music-library/NAS/Music Feb 03 11:24:38 volumio3dev volumio[1061]: error: Cue Parser - Cannot parse NAS/Music/Ich Bin Ein Auslander.cue Feb 03 11:24:39 volumio3dev volumio[1061]: info: Preload queue cleared Feb 03 11:24:39 volumio3dev volumio[1061]: info: Preloading song: music-library/NAS/Music/01 Track01.wav Feb 03 11:24:39 volumio3dev volumio[1061]: info: Preloading song: music-library/NAS/Music/AB test.m3u Feb 03 11:24:39 volumio3dev volumio[1061]: info: Exploding uri music-library/NAS/Music/01 Track01.wav in service mpd Feb 03 11:24:39 volumio3dev volumio[1061]: info: ALBUMART /albumart?cacheid=47&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic%2F01%20Track01.wav&metadata=false Feb 03 11:24:39 volumio3dev volumio[1061]: info: URI /mnt/NAS/Music/01 Track01.wav Feb 03 11:24:39 volumio3dev volumio[1061]: info: Exploding uri music-library/NAS/Music/AB test.m3u in service mpd Feb 03 11:24:41 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 03 11:24:41 volumio3dev volumio[1061]: info: CURURI: music-library/NAS/Music/3 Doors Down Feb 03 11:24:41 volumio3dev volumio[1061]: info: Preload queue cleared Feb 03 11:24:41 volumio3dev volumio[1061]: info: Preloading song: music-library/NAS/Music/3 Doors Down/3 Doors Down - Here Without You.mp3 Feb 03 11:24:42 volumio3dev volumio[1061]: info: Exploding uri music-library/NAS/Music/3 Doors Down/3 Doors Down - Here Without You.mp3 in service mpd Feb 03 11:24:42 volumio3dev volumio[1061]: info: ALBUMART /albumart?cacheid=47&web=3%20Doors%20Down/Away%20From%20The%20Sun/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F3%20Doors%20Down%2F3%20Doors%20Down%20-%20Here%20Without%20You.mp3&metadata=false Feb 03 11:24:42 volumio3dev volumio[1061]: info: URI /mnt/NAS/Music/3 Doors Down/3 Doors Down - Here Without You.mp3 Feb 03 11:24:43 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 03 11:24:43 volumio3dev volumio[1061]: info: CURURI: music-library/NAS/Music/3 Doors Down/The Better Life Feb 03 11:24:43 volumio3dev volumio[1061]: info: Preload queue cleared Feb 03 11:24:43 volumio3dev volumio[1061]: info: Preloading song: music-library/NAS/Music/3 Doors Down/The Better Life/01 3 Doors Down - Kryptonite.wav Feb 03 11:24:43 volumio3dev volumio[1061]: info: Preloading song: music-library/NAS/Music/3 Doors Down/The Better Life/02 3 Doors Down - Loser.wav Feb 03 11:24:43 volumio3dev volumio[1061]: info: Preloading song: music-library/NAS/Music/3 Doors Down/The Better Life/03 3 Doors Down - Duck And Run.wav Feb 03 11:24:43 volumio3dev volumio[1061]: info: Preloading song: music-library/NAS/Music/3 Doors Down/The Better Life/04 3 Doors Down - Not Enough.wav Feb 03 11:24:43 volumio3dev volumio[1061]: info: Preloading song: music-library/NAS/Music/3 Doors Down/The Better Life/05 3 Doors Down - Be Like That.wav Feb 03 11:24:43 volumio3dev volumio[1061]: info: Preloading song: music-library/NAS/Music/3 Doors Down/The Better Life/06 3 Doors Down - Life Of My Own.wav Feb 03 11:24:43 volumio3dev volumio[1061]: info: Preloading song: music-library/NAS/Music/3 Doors Down/The Better Life/07 3 Doors Down - Better Life.wav Feb 03 11:24:43 volumio3dev volumio[1061]: info: Preloading song: music-library/NAS/Music/3 Doors Down/The Better Life/08 3 Doors Down - Down Poison.wav Feb 03 11:24:43 volumio3dev volumio[1061]: info: Preloading song: music-library/NAS/Music/3 Doors Down/The Better Life/09 3 Doors Down - By My Side.wav Feb 03 11:24:43 volumio3dev volumio[1061]: info: Preloading song: music-library/NAS/Music/3 Doors Down/The Better Life/10 3 Doors Down - Smack.wav Feb 03 11:24:43 volumio3dev volumio[1061]: info: Preloading song: music-library/NAS/Music/3 Doors Down/The Better Life/11 3 Doors Down - So I Need You.wav Feb 03 11:24:43 volumio3dev volumio[1061]: info: Exploding uri music-library/NAS/Music/3 Doors Down/The Better Life/01 3 Doors Down - Kryptonite.wav in service mpd Feb 03 11:24:43 volumio3dev volumio[1061]: info: Exploding uri music-library/NAS/Music/3 Doors Down/The Better Life/02 3 Doors Down - Loser.wav in service mpd Feb 03 11:24:43 volumio3dev volumio[1061]: info: ALBUMART /albumart?cacheid=47&web=3%20Doors%20Down/The%20Better%20Life/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F3%20Doors%20Down%2FThe%20Better%20Life%2F01%203%20Doors%20Down%20-%20Kryptonite.wav&metadata=false Feb 03 11:24:43 volumio3dev volumio[1061]: info: URI /mnt/NAS/Music/3 Doors Down/The Better Life/01 3 Doors Down - Kryptonite.wav Feb 03 11:24:43 volumio3dev volumio[1061]: info: ALBUMART /albumart?cacheid=47&web=3%20Doors%20Down/The%20Better%20Life/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F3%20Doors%20Down%2FThe%20Better%20Life%2F02%203%20Doors%20Down%20-%20Loser.wav&metadata=false Feb 03 11:24:43 volumio3dev volumio[1061]: info: URI /mnt/NAS/Music/3 Doors Down/The Better Life/02 3 Doors Down - Loser.wav Feb 03 11:24:43 volumio3dev volumio[1061]: info: Exploding uri music-library/NAS/Music/3 Doors Down/The Better Life/03 3 Doors Down - Duck And Run.wav in service mpd Feb 03 11:24:43 volumio3dev volumio[1061]: info: ALBUMART /albumart?cacheid=47&web=3%20Doors%20Down/The%20Better%20Life/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F3%20Doors%20Down%2FThe%20Better%20Life%2F03%203%20Doors%20Down%20-%20Duck%20And%20Run.wav&metadata=false Feb 03 11:24:43 volumio3dev volumio[1061]: info: URI /mnt/NAS/Music/3 Doors Down/The Better Life/03 3 Doors Down - Duck And Run.wav Feb 03 11:24:43 volumio3dev volumio[1061]: info: Exploding uri music-library/NAS/Music/3 Doors Down/The Better Life/04 3 Doors Down - Not Enough.wav in service mpd Feb 03 11:24:43 volumio3dev volumio[1061]: info: ALBUMART /albumart?cacheid=47&web=3%20Doors%20Down/The%20Better%20Life/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F3%20Doors%20Down%2FThe%20Better%20Life%2F04%203%20Doors%20Down%20-%20Not%20Enough.wav&metadata=false Feb 03 11:24:43 volumio3dev volumio[1061]: info: URI /mnt/NAS/Music/3 Doors Down/The Better Life/04 3 Doors Down - Not Enough.wav Feb 03 11:24:43 volumio3dev volumio[1061]: info: Exploding uri music-library/NAS/Music/3 Doors Down/The Better Life/05 3 Doors Down - Be Like That.wav in service mpd Feb 03 11:24:44 volumio3dev volumio[1061]: info: ALBUMART /albumart?cacheid=47&web=3%20Doors%20Down/The%20Better%20Life/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F3%20Doors%20Down%2FThe%20Better%20Life%2F05%203%20Doors%20Down%20-%20Be%20Like%20That.wav&metadata=false Feb 03 11:24:44 volumio3dev volumio[1061]: info: URI /mnt/NAS/Music/3 Doors Down/The Better Life/05 3 Doors Down - Be Like That.wav Feb 03 11:24:44 volumio3dev volumio[1061]: info: Exploding uri music-library/NAS/Music/3 Doors Down/The Better Life/06 3 Doors Down - Life Of My Own.wav in service mpd Feb 03 11:24:44 volumio3dev volumio[1061]: info: ALBUMART /albumart?cacheid=47&web=3%20Doors%20Down/The%20Better%20Life/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F3%20Doors%20Down%2FThe%20Better%20Life%2F06%203%20Doors%20Down%20-%20Life%20Of%20My%20Own.wav&metadata=false Feb 03 11:24:44 volumio3dev volumio[1061]: info: URI /mnt/NAS/Music/3 Doors Down/The Better Life/06 3 Doors Down - Life Of My Own.wav Feb 03 11:24:44 volumio3dev volumio[1061]: info: Exploding uri music-library/NAS/Music/3 Doors Down/The Better Life/07 3 Doors Down - Better Life.wav in service mpd Feb 03 11:24:44 volumio3dev volumio[1061]: info: ALBUMART /albumart?cacheid=47&web=3%20Doors%20Down/The%20Better%20Life/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F3%20Doors%20Down%2FThe%20Better%20Life%2F07%203%20Doors%20Down%20-%20Better%20Life.wav&metadata=false Feb 03 11:24:44 volumio3dev volumio[1061]: info: URI /mnt/NAS/Music/3 Doors Down/The Better Life/07 3 Doors Down - Better Life.wav Feb 03 11:24:44 volumio3dev volumio[1061]: info: Exploding uri music-library/NAS/Music/3 Doors Down/The Better Life/08 3 Doors Down - Down Poison.wav in service mpd Feb 03 11:24:44 volumio3dev volumio[1061]: info: Exploding uri music-library/NAS/Music/3 Doors Down/The Better Life/09 3 Doors Down - By My Side.wav in service mpd Feb 03 11:24:44 volumio3dev volumio[1061]: info: ALBUMART /albumart?cacheid=47&web=3%20Doors%20Down/The%20Better%20Life/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F3%20Doors%20Down%2FThe%20Better%20Life%2F08%203%20Doors%20Down%20-%20Down%20Poison.wav&metadata=false Feb 03 11:24:44 volumio3dev volumio[1061]: info: URI /mnt/NAS/Music/3 Doors Down/The Better Life/08 3 Doors Down - Down Poison.wav Feb 03 11:24:44 volumio3dev volumio[1061]: info: ALBUMART /albumart?cacheid=47&web=3%20Doors%20Down/The%20Better%20Life/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F3%20Doors%20Down%2FThe%20Better%20Life%2F09%203%20Doors%20Down%20-%20By%20My%20Side.wav&metadata=false Feb 03 11:24:44 volumio3dev volumio[1061]: info: URI /mnt/NAS/Music/3 Doors Down/The Better Life/09 3 Doors Down - By My Side.wav Feb 03 11:24:44 volumio3dev volumio[1061]: info: Exploding uri music-library/NAS/Music/3 Doors Down/The Better Life/10 3 Doors Down - Smack.wav in service mpd Feb 03 11:24:44 volumio3dev volumio[1061]: info: ALBUMART /albumart?cacheid=47&web=3%20Doors%20Down/The%20Better%20Life/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F3%20Doors%20Down%2FThe%20Better%20Life%2F10%203%20Doors%20Down%20-%20Smack.wav&metadata=false Feb 03 11:24:44 volumio3dev volumio[1061]: info: URI /mnt/NAS/Music/3 Doors Down/The Better Life/10 3 Doors Down - Smack.wav Feb 03 11:24:44 volumio3dev volumio[1061]: info: Exploding uri music-library/NAS/Music/3 Doors Down/The Better Life/11 3 Doors Down - So I Need You.wav in service mpd Feb 03 11:24:44 volumio3dev volumio[1061]: info: ALBUMART /albumart?cacheid=47&web=3%20Doors%20Down/The%20Better%20Life/extralarge&path=%2Fmnt%2FNAS%2FMusic%2F3%20Doors%20Down%2FThe%20Better%20Life%2F11%203%20Doors%20Down%20-%20So%20I%20Need%20You.wav&metadata=false Feb 03 11:24:44 volumio3dev volumio[1061]: info: URI /mnt/NAS/Music/3 Doors Down/The Better Life/11 3 Doors Down - So I Need You.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Preload queue cleared Feb 03 11:24:45 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 03 11:24:45 volumio3dev volumio[1061]: info: CoreStateMachine::ClearQueue Feb 03 11:24:45 volumio3dev volumio[1061]: info: CoreStateMachine::stop Feb 03 11:24:45 volumio3dev volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 03 11:24:45 volumio3dev volumio[1061]: info: CorePlayQueue::clearPlayQueue Feb 03 11:24:45 volumio3dev volumio[1061]: info: CorePlayQueue::saveQueue Feb 03 11:24:45 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushQueue Feb 03 11:24:45 volumio3dev volumio[1061]: info: CoreStateMachine::addQueueItems Feb 03 11:24:45 volumio3dev volumio[1061]: info: CorePlayQueue::addQueueItems Feb 03 11:24:45 volumio3dev volumio[1061]: info: Preload queue cleared Feb 03 11:24:45 volumio3dev volumio[1061]: info: Adding Item to queue: music-library/NAS/Music/3 Doors Down/The Better Life/01 3 Doors Down - Kryptonite.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Using cached record of: music-library/NAS/Music/3 Doors Down/The Better Life/01 3 Doors Down - Kryptonite.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Adding Item to queue: music-library/NAS/Music/3 Doors Down/The Better Life/02 3 Doors Down - Loser.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Using cached record of: music-library/NAS/Music/3 Doors Down/The Better Life/02 3 Doors Down - Loser.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Adding Item to queue: music-library/NAS/Music/3 Doors Down/The Better Life/03 3 Doors Down - Duck And Run.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Using cached record of: music-library/NAS/Music/3 Doors Down/The Better Life/03 3 Doors Down - Duck And Run.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Adding Item to queue: music-library/NAS/Music/3 Doors Down/The Better Life/04 3 Doors Down - Not Enough.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Using cached record of: music-library/NAS/Music/3 Doors Down/The Better Life/04 3 Doors Down - Not Enough.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Adding Item to queue: music-library/NAS/Music/3 Doors Down/The Better Life/05 3 Doors Down - Be Like That.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Using cached record of: music-library/NAS/Music/3 Doors Down/The Better Life/05 3 Doors Down - Be Like That.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Adding Item to queue: music-library/NAS/Music/3 Doors Down/The Better Life/06 3 Doors Down - Life Of My Own.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Using cached record of: music-library/NAS/Music/3 Doors Down/The Better Life/06 3 Doors Down - Life Of My Own.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Adding Item to queue: music-library/NAS/Music/3 Doors Down/The Better Life/07 3 Doors Down - Better Life.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Using cached record of: music-library/NAS/Music/3 Doors Down/The Better Life/07 3 Doors Down - Better Life.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Adding Item to queue: music-library/NAS/Music/3 Doors Down/The Better Life/08 3 Doors Down - Down Poison.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Using cached record of: music-library/NAS/Music/3 Doors Down/The Better Life/08 3 Doors Down - Down Poison.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Adding Item to queue: music-library/NAS/Music/3 Doors Down/The Better Life/09 3 Doors Down - By My Side.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Using cached record of: music-library/NAS/Music/3 Doors Down/The Better Life/09 3 Doors Down - By My Side.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Adding Item to queue: music-library/NAS/Music/3 Doors Down/The Better Life/10 3 Doors Down - Smack.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Using cached record of: music-library/NAS/Music/3 Doors Down/The Better Life/10 3 Doors Down - Smack.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Adding Item to queue: music-library/NAS/Music/3 Doors Down/The Better Life/11 3 Doors Down - So I Need You.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: Using cached record of: music-library/NAS/Music/3 Doors Down/The Better Life/11 3 Doors Down - So I Need You.wav Feb 03 11:24:45 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushQueue Feb 03 11:24:45 volumio3dev volumio[1061]: info: CorePlayQueue::saveQueue Feb 03 11:24:45 volumio3dev volumio[1061]: info: CoreStateMachine::updateTrackBlock Feb 03 11:24:45 volumio3dev volumio[1061]: info: CorePlayQueue::getTrackBlock Feb 03 11:24:45 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPlay Feb 03 11:24:45 volumio3dev volumio[1061]: info: CoreStateMachine::play index 1 Feb 03 11:24:45 volumio3dev volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 03 11:24:45 volumio3dev volumio[1061]: info: CoreStateMachine::stop Feb 03 11:24:45 volumio3dev volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 03 11:24:45 volumio3dev volumio[1061]: info: CoreStateMachine::play index undefined Feb 03 11:24:45 volumio3dev volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 03 11:24:45 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:24:45 volumio3dev volumio[1061]: info: CoreStateMachine::startPlaybackTimer Feb 03 11:24:45 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:24:45 volumio3dev volumio[1061]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/3 Doors Down/The Better Life/02 3 Doors Down - Loser.wav Feb 03 11:24:45 volumio3dev volumio[1061]: verbose: ControllerMpd::sendMpdCommand stop Feb 03 11:24:45 volumio3dev volumio[1061]: info: sendMpdCommand stop took 48 milliseconds Feb 03 11:24:45 volumio3dev volumio[1061]: verbose: ControllerMpd::sendMpdCommand clear Feb 03 11:24:45 volumio3dev volumio[1061]: info: Feb 03 11:24:45 volumio3dev volumio[1061]: ---------------------------- MPD announces system playlist update Feb 03 11:24:45 volumio3dev volumio[1061]: info: Ignoring MPD Status Update Feb 03 11:24:45 volumio3dev volumio[1061]: info: sendMpdCommand clear took 15 milliseconds Feb 03 11:24:45 volumio3dev volumio[1061]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/3 Doors Down/The Better Life/02 3 Doors Down - Loser.wav" Feb 03 11:24:45 volumio3dev volumio[1061]: info: Feb 03 11:24:45 volumio3dev volumio[1061]: ---------------------------- MPD announces system playlist update Feb 03 11:24:45 volumio3dev volumio[1061]: info: Ignoring MPD Status Update Feb 03 11:24:45 volumio3dev volumio[1061]: info: Feb 03 11:24:45 volumio3dev volumio[1061]: ---------------------------- MPD announces system playlist update Feb 03 11:24:45 volumio3dev volumio[1061]: info: Ignoring MPD Status Update Feb 03 11:24:45 volumio3dev volumio[1061]: error: updateQueue error: null Feb 03 11:24:45 volumio3dev volumio[1061]: info: Feb 03 11:24:45 volumio3dev volumio[1061]: ---------------------------- MPD announces system playlist update Feb 03 11:24:45 volumio3dev volumio[1061]: info: Ignoring MPD Status Update Feb 03 11:24:45 volumio3dev volumio[1061]: info: ------------------------------ 16ms Feb 03 11:24:45 volumio3dev volumio[1061]: info: sendMpdCommand add "NAS/Music/3 Doors Down/The Better Life/02 3 Doors Down - Loser.wav" took 14 milliseconds Feb 03 11:24:45 volumio3dev volumio[1061]: info: ------------------------------ 11ms Feb 03 11:24:45 volumio3dev volumio[1061]: info: ------------------------------ 10ms Feb 03 11:24:45 volumio3dev volumio[1061]: verbose: ControllerMpd::sendMpdCommand play Feb 03 11:24:45 volumio3dev volumio[1061]: info: Feb 03 11:24:45 volumio3dev volumio[1061]: ---------------------------- MPD announces system playlist update Feb 03 11:24:45 volumio3dev volumio[1061]: info: Ignoring MPD Status Update Feb 03 11:24:45 volumio3dev volumio[1061]: info: Feb 03 11:24:45 volumio3dev volumio[1061]: ---------------------------- MPD announces system playlist update Feb 03 11:24:45 volumio3dev volumio[1061]: info: Ignoring MPD Status Update Feb 03 11:24:45 volumio3dev volumio[1061]: error: Upnp client error: Error: This socket has been ended by the other party Feb 03 11:24:45 volumio3dev volumio[1061]: info: ------------------------------ 19ms Feb 03 11:24:45 volumio3dev volumio[1061]: info: sendMpdCommand play took 15 milliseconds Feb 03 11:24:45 volumio3dev volumio[1061]: info: ------------------------------ 14ms Feb 03 11:24:45 volumio3dev volumio[1061]: info: ------------------------------ 12ms Feb 03 11:24:45 volumio3dev kernel: CIFS: __readahead_batch() returned 192/256 Feb 03 11:24:46 volumio3dev volumio[1061]: info: Feb 03 11:24:46 volumio3dev volumio[1061]: ---------------------------- MPD announces state update: player Feb 03 11:24:46 volumio3dev volumio[1061]: info: ControllerMpd::getState Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::sendMpdCommand status Feb 03 11:24:46 volumio3dev volumio[1061]: info: Feb 03 11:24:46 volumio3dev volumio[1061]: ---------------------------- MPD announces state update: player Feb 03 11:24:46 volumio3dev volumio[1061]: info: ControllerMpd::getState Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::sendMpdCommand status Feb 03 11:24:46 volumio3dev volumio[1061]: info: Feb 03 11:24:46 volumio3dev volumio[1061]: ---------------------------- MPD announces state update: player Feb 03 11:24:46 volumio3dev volumio[1061]: info: ControllerMpd::getState Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::sendMpdCommand status Feb 03 11:24:46 volumio3dev volumio[1061]: info: Feb 03 11:24:46 volumio3dev volumio[1061]: ---------------------------- MPD announces state update: player Feb 03 11:24:46 volumio3dev volumio[1061]: info: ControllerMpd::getState Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::sendMpdCommand status Feb 03 11:24:46 volumio3dev volumio[1061]: info: sendMpdCommand status took 46 milliseconds Feb 03 11:24:46 volumio3dev volumio[1061]: info: sendMpdCommand status took 45 milliseconds Feb 03 11:24:46 volumio3dev volumio[1061]: info: sendMpdCommand status took 43 milliseconds Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::parseState Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::parseState Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::parseState Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 03 11:24:46 volumio3dev volumio[1061]: info: Feb 03 11:24:46 volumio3dev volumio[1061]: ---------------------------- MPD announces state update: player Feb 03 11:24:46 volumio3dev volumio[1061]: info: ControllerMpd::getState Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::sendMpdCommand status Feb 03 11:24:46 volumio3dev volumio[1061]: info: Feb 03 11:24:46 volumio3dev volumio[1061]: ---------------------------- MPD announces state update: player Feb 03 11:24:46 volumio3dev volumio[1061]: info: ControllerMpd::getState Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::sendMpdCommand status Feb 03 11:24:46 volumio3dev volumio[1061]: info: sendMpdCommand status took 22 milliseconds Feb 03 11:24:46 volumio3dev volumio[1061]: info: sendMpdCommand playlistinfo took 17 milliseconds Feb 03 11:24:46 volumio3dev volumio[1061]: info: sendMpdCommand playlistinfo took 17 milliseconds Feb 03 11:24:46 volumio3dev volumio[1061]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 03 11:24:46 volumio3dev volumio[1061]: info: sendMpdCommand status took 14 milliseconds Feb 03 11:24:46 volumio3dev volumio[1061]: info: sendMpdCommand status took 12 milliseconds Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::parseState Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::parseTrackInfo Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::parseTrackInfo Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::parseTrackInfo Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::parseState Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::parseState Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 03 11:24:46 volumio3dev volumio[1061]: info: ControllerMpd::pushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreCommandRouter::servicePushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1467,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Loser","artist":"3 Doors Down","album":"The Better Life","uri":"NAS/Music/3 Doors Down/The Better Life/02 3 Doors Down - Loser.wav","trackType":"wav"} Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: CURRENT POSITION 1 Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::syncState stateService play Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::syncState currentStatus stop Feb 03 11:24:46 volumio3dev volumio[1061]: info: ControllerMpd::pushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreCommandRouter::servicePushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1467,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Loser","artist":"3 Doors Down","album":"The Better Life","uri":"NAS/Music/3 Doors Down/The Better Life/02 3 Doors Down - Loser.wav","trackType":"wav"} Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: CURRENT POSITION 1 Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::syncState stateService play Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::syncState currentStatus play Feb 03 11:24:46 volumio3dev volumio[1061]: info: Received an update from plugin. extracting info from payload Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::pushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::pushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: ControllerMpd::pushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreCommandRouter::servicePushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1467,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Loser","artist":"3 Doors Down","album":"The Better Life","uri":"NAS/Music/3 Doors Down/The Better Life/02 3 Doors Down - Loser.wav","trackType":"wav"} Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: CURRENT POSITION 1 Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::syncState stateService play Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::syncState currentStatus play Feb 03 11:24:46 volumio3dev volumio[1061]: info: Received an update from plugin. extracting info from payload Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::pushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::pushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: ------------------------------ 127ms Feb 03 11:24:46 volumio3dev volumio[1061]: info: ------------------------------ 223ms Feb 03 11:24:46 volumio3dev volumio[1061]: info: ------------------------------ 222ms Feb 03 11:24:46 volumio3dev volumio[1061]: info: sendMpdCommand playlistinfo took 168 milliseconds Feb 03 11:24:46 volumio3dev volumio[1061]: info: sendMpdCommand playlistinfo took 165 milliseconds Feb 03 11:24:46 volumio3dev volumio[1061]: info: sendMpdCommand playlistinfo took 165 milliseconds Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::parseTrackInfo Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::parseTrackInfo Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: ControllerMpd::parseTrackInfo Feb 03 11:24:46 volumio3dev volumio[1061]: info: ControllerMpd::pushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreCommandRouter::servicePushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1467,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Loser","artist":"3 Doors Down","album":"The Better Life","uri":"NAS/Music/3 Doors Down/The Better Life/02 3 Doors Down - Loser.wav","trackType":"wav"} Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: CURRENT POSITION 1 Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::syncState stateService play Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::syncState currentStatus play Feb 03 11:24:46 volumio3dev volumio[1061]: info: Received an update from plugin. extracting info from payload Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::pushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::pushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: ControllerMpd::pushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreCommandRouter::servicePushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1467,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Loser","artist":"3 Doors Down","album":"The Better Life","uri":"NAS/Music/3 Doors Down/The Better Life/02 3 Doors Down - Loser.wav","trackType":"wav"} Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: CURRENT POSITION 1 Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::syncState stateService play Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::syncState currentStatus play Feb 03 11:24:46 volumio3dev volumio[1061]: info: Received an update from plugin. extracting info from payload Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::pushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::pushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: ControllerMpd::pushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreCommandRouter::servicePushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1467,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Loser","artist":"3 Doors Down","album":"The Better Life","uri":"NAS/Music/3 Doors Down/The Better Life/02 3 Doors Down - Loser.wav","trackType":"wav"} Feb 03 11:24:46 volumio3dev volumio[1061]: verbose: CURRENT POSITION 1 Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::syncState stateService play Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::syncState currentStatus play Feb 03 11:24:46 volumio3dev volumio[1061]: info: Received an update from plugin. extracting info from payload Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::pushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreStateMachine::pushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:24:46 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioPushState Feb 03 11:24:46 volumio3dev volumio[1061]: info: ------------------------------ 407ms Feb 03 11:24:46 volumio3dev volumio[1061]: info: ------------------------------ 397ms Feb 03 11:24:46 volumio3dev volumio[1061]: info: ------------------------------ 396ms Feb 03 11:24:46 volumio3dev volumio[1061]: info: [ASDebug] CurState: play PrevState: na Feb 03 11:24:46 volumio3dev volumio[1061]: info: [ASDebug] Togle GPIO: ON Feb 03 11:24:46 volumio3dev volumio[1061]: info: [ASDebug] CurState: play PrevState: play Feb 03 11:24:46 volumio3dev volumio[1061]: info: [ASDebug] CurState: play PrevState: play Feb 03 11:24:46 volumio3dev volumio[1061]: info: [ASDebug] CurState: play PrevState: play Feb 03 11:24:46 volumio3dev volumio[1061]: info: [ASDebug] CurState: play PrevState: play Feb 03 11:24:46 volumio3dev volumio[1061]: info: [ASDebug] CurState: play PrevState: play Feb 03 11:24:46 volumio3dev volumio[1061]: info: [ASDebug] CurState: play PrevState: play Feb 03 11:24:46 volumio3dev volumio[1061]: info: [ASDebug] CurState: play PrevState: play Feb 03 11:24:46 volumio3dev volumio[1061]: info: [ASDebug] CurState: play PrevState: play Feb 03 11:24:46 volumio3dev volumio[1061]: info: [ASDebug] CurState: play PrevState: play Feb 03 11:24:46 volumio3dev volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Feb 03 11:24:46 volumio3dev volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Feb 03 11:24:46 volumio3dev volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Feb 03 11:24:46 volumio3dev volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Feb 03 11:24:46 volumio3dev volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Feb 03 11:24:46 volumio3dev volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Feb 03 11:24:46 volumio3dev volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Feb 03 11:24:46 volumio3dev volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Feb 03 11:24:46 volumio3dev volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Feb 03 11:24:46 volumio3dev volumio[1061]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Feb 03 11:25:02 volumio3dev sudo[1467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 03 11:25:02 volumio3dev sudo[1467]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:25:02 volumio3dev sudo[1467]: pam_unix(sudo:session): session closed for user root Feb 03 11:25:02 volumio3dev sudo[1470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 03 11:25:02 volumio3dev sudo[1470]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:25:02 volumio3dev sudo[1470]: pam_unix(sudo:session): session closed for user root Feb 03 11:25:02 volumio3dev volumio[1061]: verbose: New Socket.io Connection to 192.168.4.180 from 192.168.4.16 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: 22 Feb 03 11:25:02 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 03 11:25:03 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 03 11:25:03 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 03 11:25:03 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetVisibleSources Feb 03 11:25:03 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 03 11:25:03 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:25:03 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:25:03 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 03 11:25:03 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetQueue Feb 03 11:25:03 volumio3dev volumio[1061]: info: CoreStateMachine::getQueue Feb 03 11:25:03 volumio3dev volumio[1061]: info: CorePlayQueue::getQueue Feb 03 11:25:03 volumio3dev volumio[1061]: info: Listing playlists Feb 03 11:25:03 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 03 11:25:04 volumio3dev volumio[1061]: info: Received Get System Info Feb 03 11:25:04 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 03 11:25:04 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 03 11:25:04 volumio3dev volumio[1061]: info: Discovery: Getting this device information Feb 03 11:25:04 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:25:04 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:25:04 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 03 11:25:04 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:25:04 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:25:04 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 03 11:25:04 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 03 11:25:04 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 03 11:25:04 volumio3dev volumio[1061]: info: Received Get System Info Feb 03 11:25:04 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 03 11:25:04 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 03 11:25:04 volumio3dev volumio[1061]: info: Discovery: Getting this device information Feb 03 11:25:04 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:25:04 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:25:04 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 03 11:25:04 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 03 11:25:05 volumio3dev volumio[1061]: info: Received Get System Info Feb 03 11:25:05 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 03 11:25:05 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 03 11:25:05 volumio3dev volumio[1061]: info: Discovery: Getting this device information Feb 03 11:25:05 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:25:05 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:25:05 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 03 11:25:08 volumio3dev volumio[1061]: verbose: New Socket.io Connection to volumio3dev from 192.168.4.16 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: 23 Feb 03 11:25:08 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:25:08 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:25:08 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetQueue Feb 03 11:25:08 volumio3dev volumio[1061]: info: CoreStateMachine::getQueue Feb 03 11:25:08 volumio3dev volumio[1061]: info: CorePlayQueue::getQueue Feb 03 11:25:08 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Feb 03 11:25:08 volumio3dev volumio[1061]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Feb 03 11:25:08 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Feb 03 11:25:08 volumio3dev volumio[1061]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Feb 03 11:25:08 volumio3dev volumio[1061]: verbose: New Socket.io Connection to volumio3dev from 192.168.4.16 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: 22 Feb 03 11:25:08 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetState Feb 03 11:25:08 volumio3dev volumio[1061]: info: CorePlayQueue::getTrack 1 Feb 03 11:25:08 volumio3dev volumio[1061]: info: CoreCommandRouter::volumioGetQueue Feb 03 11:25:08 volumio3dev volumio[1061]: info: CoreStateMachine::getQueue Feb 03 11:25:08 volumio3dev volumio[1061]: info: CorePlayQueue::getQueue Feb 03 11:25:08 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Feb 03 11:25:08 volumio3dev volumio[1061]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Feb 03 11:25:08 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Feb 03 11:25:08 volumio3dev volumio[1061]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Feb 03 11:25:16 volumio3dev volumio[1061]: info: CALLMETHOD: system_controller system setTestPlugins false Feb 03 11:25:16 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , setTestPlugins Feb 03 11:25:16 volumio3dev volumio[1061]: info: Plugins Test File deleted Feb 03 11:25:35 volumio3dev volumio[1061]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Feb 03 11:25:35 volumio3dev volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Feb 03 11:25:35 volumio3dev sudo[1551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Feb 03 11:25:35 volumio3dev sudo[1551]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 03 11:25:35 volumio3dev sudo[1551]: pam_unix(sudo:session): session closed for user root Feb 03 11:25:46 volumio3dev sudo[1629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Feb 03 11:25:46 volumio3dev sudo[1629]: pam_unix(sudo:session): session opened for user root by (uid=0)