Mon Sep 29 16:31:41 CEST 2025 Description="'No info available'" # cat /proc/version --------------- Linux version 4.9.241+ (odroidn2@busteros) (gcc version 6.3.1 20170109 (Linaro GCC 6.3-2017.02) ) #8 SMP PREEMPT Sat Jan 21 21:01:33 CET 2023 # cat /etc/os-release --------------- PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 07 Jul 2025 03:41:45 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="odroidn2" VOLUMIO_DEVICENAME="Odroid-N2" VOLUMIO_HASH="aa8e2111ed7ad6287fe333440aae5701" # ifconfig --------------- eth0: flags=4163 mtu 1500 inet 192.168.1.104 netmask 255.255.255.0 broadcast 192.168.1.255 ether 00:1e:06:42:a3:da txqueuelen 1000 (Ethernet) RX packets 854 bytes 225204 (219.9 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 656 bytes 239138 (233.5 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 device interrupt 22 lo: flags=73 mtu 65536 inet 127.0.0.1 netmask 255.0.0.0 loop txqueuelen 1 (Local Loopback) RX packets 1046 bytes 197633 (193.0 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 1046 bytes 197633 (193.0 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 # sudo iwconfig --------------- eth0 no wireless extensions. lo no wireless extensions. # aplay -l --------------- **** List of PLAYBACK Hardware Devices **** card 0: AMLAUGESOUND [AML-AUGESOUND], device 0: SPDIF-B-dit-hifi dit-hifi-0 [] Subdevices: 0/1 Subdevice #0: subdevice #0 card 0: AMLAUGESOUND [AML-AUGESOUND], device 1: TDM-C-T9015-audio-hifi T9015-audio-hifi-1 [] Subdevices: 1/1 Subdevice #0: subdevice #0 card 0: AMLAUGESOUND [AML-AUGESOUND], device 2: SPDIF-dit-hifi dit-hifi-2 [] Subdevices: 1/1 Subdevice #0: subdevice #0 # lsusb --------------- Bus 002 Device 002: ID 05e3:0620 Genesys Logic, Inc. Bus 002 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub Bus 001 Device 003: ID 14cd:125d Super Top Bus 001 Device 002: ID 05e3:0610 Genesys Logic, Inc. 4-port hub Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub # lsusb -t --------------- /: Bus 02.Port 1: Dev 1, Class=root_hub, Driver=xhci-hcd/1p, 5000M |__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/4p, 5000M /: Bus 01.Port 1: Dev 1, Class=root_hub, Driver=xhci-hcd/2p, 480M |__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/4p, 480M |__ Port 3: Dev 3, If 0, Class=Mass Storage, Driver=usb-storage, 480M # lsusb -v --------------- Couldn't open device, some information will be missing Couldn't open device, some information will be missing Couldn't open device, some information will be missing Bus 002 Device 002: ID 05e3:0620 Genesys Logic, Inc. Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 3.10 bDeviceClass 9 Hub bDeviceSubClass 0 bDeviceProtocol 3 bMaxPacketSize0 9 idVendor 0x05e3 Genesys Logic, Inc. idProduct 0x0620 bcdDevice 93.04 iManufacturer 1 iProduct 2 iSerial 0 bNumConfigurations 1 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x001f bNumInterfaces 1 bConfigurationValue 1 iConfiguration 0 bmAttributes 0xe0 Self Powered Remote Wakeup MaxPower 0mA Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 1 bInterfaceClass 9 Hub bInterfaceSubClass 0 bInterfaceProtocol 0 Full speed (or root) hub iInterface 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x83 EP 3 IN bmAttributes 19 Transfer Type Interrupt Synch Type None Usage Type Feedback wMaxPacketSize 0x0002 1x 2 bytes bInterval 8 bMaxBurst 0 Bus 002 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 3.00 bDeviceClass 9 Hub bDeviceSubClass 0 bDeviceProtocol 3 bMaxPacketSize0 9 idVendor 0x1d6b Linux Foundation idProduct 0x0003 3.0 root hub bcdDevice 4.09 iManufacturer 3 iProduct 2 iSerial 1 bNumConfigurations 1 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x001f bNumInterfaces 1 bConfigurationValue 1 iConfiguration 0 bmAttributes 0xe0 Self Powered Remote Wakeup MaxPower 0mA Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 1 bInterfaceClass 9 Hub bInterfaceSubClass 0 bInterfaceProtocol 0 Full speed (or root) hub iInterface 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 3 Transfer Type Interrupt Synch Type None Usage Type Data wMaxPacketSize 0x0004 1x 4 bytes bInterval 12 bMaxBurst 0 Bus 001 Device 003: ID 14cd:125d Super Top Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 2.00 bDeviceClass 0 bDeviceSubClass 0 bDeviceProtocol 0 bMaxPacketSize0 64 idVendor 0x14cd Super Top idProduct 0x125d bcdDevice 1.00 iManufacturer 1 iProduct 3 iSerial 2 bNumConfigurations 1 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x0020 bNumInterfaces 1 bConfigurationValue 1 iConfiguration 0 bmAttributes 0x80 (Bus Powered) MaxPower 100mA Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 2 Couldn't open device, some information will be missing Couldn't open device, some information will be missing bInterfaceClass 8 Mass Storage bInterfaceSubClass 6 SCSI bInterfaceProtocol 80 Bulk-Only iInterface 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 2 Transfer Type Bulk Synch Type None Usage Type Data wMaxPacketSize 0x0200 1x 512 bytes bInterval 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x02 EP 2 OUT bmAttributes 2 Transfer Type Bulk Synch Type None Usage Type Data wMaxPacketSize 0x0200 1x 512 bytes bInterval 0 Bus 001 Device 002: ID 05e3:0610 Genesys Logic, Inc. 4-port hub Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 2.10 bDeviceClass 9 Hub bDeviceSubClass 0 bDeviceProtocol 2 TT per port bMaxPacketSize0 64 idVendor 0x05e3 Genesys Logic, Inc. idProduct 0x0610 4-port hub bcdDevice 93.04 iManufacturer 1 iProduct 2 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 100mA 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 4.09 iManufacturer 3 iProduct 2 iSerial 1 bNumConfigurations 1 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x0019 bNumInterfaces 1 bConfigurationValue 1 iConfiguration 0 bmAttributes 0xe0 Self Powered Remote Wakeup MaxPower 0mA Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 1 bInterfaceClass 9 Hub bInterfaceSubClass 0 bInterfaceProtocol 0 Full speed (or root) hub iInterface 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 3 Transfer Type Interrupt Synch Type None Usage Type Data wMaxPacketSize 0x0004 1x 4 bytes bInterval 12 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 1 16:28 ? 00:00:02 /sbin/init splash root 2 0 0 16:28 ? 00:00:00 [kthreadd] root 3 2 0 16:28 ? 00:00:00 [ksoftirqd/0] root 4 2 0 16:28 ? 00:00:00 [kworker/0:0] root 5 2 0 16:28 ? 00:00:00 [kworker/0:0H] root 6 2 0 16:28 ? 00:00:00 [kworker/u12:0] root 7 2 0 16:28 ? 00:00:00 [rcu_preempt] root 8 2 0 16:28 ? 00:00:00 [rcu_sched] root 9 2 0 16:28 ? 00:00:00 [rcu_bh] root 10 2 0 16:28 ? 00:00:00 [migration/0] root 11 2 0 16:28 ? 00:00:00 [lru-add-drain] root 12 2 0 16:28 ? 00:00:00 [watchdog/0] root 13 2 0 16:28 ? 00:00:00 [cpuhp/0] root 14 2 0 16:28 ? 00:00:00 [cpuhp/1] root 15 2 0 16:28 ? 00:00:00 [watchdog/1] root 16 2 0 16:28 ? 00:00:00 [migration/1] root 17 2 0 16:28 ? 00:00:00 [ksoftirqd/1] root 18 2 0 16:28 ? 00:00:00 [kworker/1:0] root 19 2 0 16:28 ? 00:00:00 [kworker/1:0H] root 20 2 0 16:28 ? 00:00:00 [cpuhp/2] root 21 2 0 16:28 ? 00:00:00 [watchdog/2] root 22 2 0 16:28 ? 00:00:00 [migration/2] root 23 2 0 16:28 ? 00:00:00 [ksoftirqd/2] root 24 2 0 16:28 ? 00:00:00 [kworker/2:0] root 25 2 0 16:28 ? 00:00:00 [kworker/2:0H] root 26 2 0 16:28 ? 00:00:00 [cpuhp/3] root 27 2 0 16:28 ? 00:00:00 [watchdog/3] root 28 2 0 16:28 ? 00:00:00 [migration/3] root 29 2 0 16:28 ? 00:00:00 [ksoftirqd/3] root 30 2 0 16:28 ? 00:00:00 [kworker/3:0] root 31 2 0 16:28 ? 00:00:00 [kworker/3:0H] root 32 2 0 16:28 ? 00:00:00 [cpuhp/4] root 33 2 0 16:28 ? 00:00:00 [watchdog/4] root 34 2 0 16:28 ? 00:00:00 [migration/4] root 35 2 0 16:28 ? 00:00:00 [ksoftirqd/4] root 36 2 0 16:28 ? 00:00:00 [kworker/4:0] root 37 2 0 16:28 ? 00:00:00 [kworker/4:0H] root 38 2 0 16:28 ? 00:00:00 [cpuhp/5] root 39 2 0 16:28 ? 00:00:00 [watchdog/5] root 40 2 0 16:28 ? 00:00:00 [migration/5] root 41 2 0 16:28 ? 00:00:00 [ksoftirqd/5] root 42 2 0 16:28 ? 00:00:00 [kworker/5:0] root 43 2 0 16:28 ? 00:00:00 [kworker/5:0H] root 44 2 0 16:28 ? 00:00:00 [kdevtmpfs] root 45 2 0 16:28 ? 00:00:00 [netns] root 46 2 0 16:28 ? 00:00:00 [kworker/u12:1] root 53 2 0 16:28 ? 00:00:00 [kworker/u12:2] root 78 2 0 16:28 ? 00:00:00 [kworker/u12:3] root 527 2 0 16:28 ? 00:00:00 [kworker/u12:4] root 1174 2 0 16:28 ? 00:00:00 [khungtaskd] root 1175 2 0 16:28 ? 00:00:00 [oom_reaper] root 1176 2 0 16:28 ? 00:00:00 [writeback] root 1178 2 0 16:28 ? 00:00:00 [kcompactd0] root 1179 2 0 16:28 ? 00:00:00 [crypto] root 1180 2 0 16:28 ? 00:00:00 [bioset] root 1182 2 0 16:28 ? 00:00:00 [kblockd] root 1212 2 0 16:28 ? 00:00:00 [devfreq_wq] root 1257 2 0 16:28 ? 00:00:00 [kworker/2:1] root 1258 2 0 16:28 ? 00:00:00 [cfg80211] root 1262 2 0 16:28 ? 00:00:00 [watchdogd] root 1349 2 0 16:28 ? 00:00:00 [gp_pll] root 1354 2 0 16:28 ? 00:00:00 [kworker/3:1] root 1358 2 0 16:28 ? 00:00:00 [kworker/0:1H] root 1391 2 0 16:28 ? 00:00:00 [kswapd0] root 1392 2 0 16:28 ? 00:00:00 [vmstat] root 1461 2 0 16:28 ? 00:00:00 [kworker/u13:0] root 1536 2 0 16:28 ? 00:00:00 [hwrng] root 1538 2 0 16:28 ? 00:00:00 [kworker/5:1] root 1548 2 0 16:28 ? 00:00:00 [kbase_job_fault] root 1550 2 0 16:28 ? 00:00:00 [kworker/u13:1] root 1554 2 0 16:28 ? 00:00:00 [bioset] root 1557 2 0 16:28 ? 00:00:00 [bioset] root 1560 2 0 16:28 ? 00:00:00 [bioset] root 1563 2 0 16:28 ? 00:00:00 [bioset] root 1566 2 0 16:28 ? 00:00:00 [bioset] root 1569 2 0 16:28 ? 00:00:00 [bioset] root 1572 2 0 16:28 ? 00:00:00 [bioset] root 1575 2 0 16:28 ? 00:00:00 [bioset] root 1597 2 0 16:28 ? 00:00:00 [eth_moniter_tx_] root 1627 2 0 16:28 ? 00:00:00 [kworker/4:1] root 1637 2 0 16:28 ? 00:00:00 [cfinteractive] root 1702 2 0 16:28 ? 00:00:00 [null] root 1703 2 0 16:28 ? 00:00:00 [null] root 1704 2 0 16:28 ? 00:00:00 [cpu_hogplug_thr] root 1717 2 0 16:28 ? 00:00:00 [vmalloc_ion] root 1718 2 0 16:28 ? 00:00:00 [codec_mm_ion] root 1722 2 0 16:28 ? 00:00:00 [ge2d_monitor] root 1732 2 0 16:28 ? 00:00:00 [kworker/0:1] root 1738 2 0 16:28 ? 00:00:00 [kthread_hdcp] root 1745 2 0 16:28 ? 00:00:00 [kthread_di] root 1782 2 0 16:28 ? 00:00:00 [videosync] root 1789 2 0 16:28 ? 00:00:00 [gdc_monitor] root 1792 2 0 16:28 ? 00:00:00 [irq/55-meson-am] root 1794 2 0 16:28 ? 00:00:00 [irq/56-meson-am] root 1795 2 0 16:28 ? 00:00:00 [kworker/0:2] root 1801 2 0 16:28 ? 00:00:00 [kworker/1:1] root 1804 2 0 16:28 ? 00:00:00 [cec_work] root 1830 2 0 16:28 ? 00:00:00 [irq/26-meson-g1] root 1836 2 0 16:28 ? 00:00:00 [cma_task0] root 1837 2 0 16:28 ? 00:00:00 [cma_task1] root 1838 2 0 16:28 ? 00:00:00 [cma_task2] root 1839 2 0 16:28 ? 00:00:00 [cma_task3] root 1840 2 0 16:28 ? 00:00:00 [cma_task4] root 1841 2 0 16:28 ? 00:00:00 [cma_task5] root 1912 2 0 16:28 ? 00:00:00 [dwc_otg] root 1968 2 0 16:28 ? 00:00:00 [kworker/3:2] root 1969 2 0 16:28 ? 00:00:00 [kworker/1:2] root 1975 2 0 16:28 ? 00:00:00 [bioset] root 1982 2 0 16:28 ? 00:00:00 [mmcqd/1] root 2000 2 0 16:28 ? 00:00:00 [kworker/4:2] root 2016 2 0 16:28 ? 00:00:00 [scsi_eh_0] root 2017 2 0 16:28 ? 00:00:00 [scsi_tmf_0] root 2018 2 0 16:28 ? 00:00:00 [usb-storage] root 2019 2 0 16:28 ? 00:00:00 [bioset] root 2021 2 0 16:28 ? 00:00:00 [kworker/1:1H] root 2041 2 0 16:28 ? 00:00:00 [jbd2/mmcblk1p2-] root 2042 2 0 16:28 ? 00:00:00 [ext4-rsv-conver] root 2070 2 0 16:28 ? 00:00:00 [loop0] root 2080 2 0 16:28 ? 00:00:00 [jbd2/mmcblk1p3-] root 2081 2 0 16:28 ? 00:00:00 [ext4-rsv-conver] root 2106 2 0 16:28 ? 00:00:00 [kworker/4:1H] root 2107 2 0 16:28 ? 00:00:00 [kworker/2:1H] root 2108 2 0 16:28 ? 00:00:00 [kworker/5:1H] root 2109 2 0 16:28 ? 00:00:00 [kworker/3:1H] root 2110 2 0 16:28 ? 00:00:00 [ipv6_addrconf] root 2131 2 0 16:28 ? 00:00:00 [kauditd] root 2134 1 0 16:28 ? 00:00:00 /lib/systemd/systemd-journald root 2149 2 0 16:28 ? 00:00:00 [rpciod] root 2150 2 0 16:28 ? 00:00:00 [xprtiod] root 2151 2 0 16:28 ? 00:00:00 [kworker/2:2] root 2155 2 0 16:28 ? 00:00:00 [kworker/4:3] root 2157 2 0 16:28 ? 00:00:00 [kworker/0:3] root 2160 1 0 16:28 ? 00:00:00 /lib/systemd/systemd-udevd root 2294 2 0 16:28 ? 00:00:00 [kworker/5:2] root 2298 1 0 16:28 ? 00:00:00 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 _rpc 2300 1 0 16:28 ? 00:00:00 /sbin/rpcbind -f -w root 2313 2 0 16:28 ? 00:00:00 [kworker/2:3] root 2319 1 0 16:28 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon avahi 2320 1 0 16:28 ? 00:00:00 avahi-daemon: running [volumio.local] message+ 2323 1 0 16:28 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 2325 1 0 16:28 ? 00:00:00 /lib/systemd/systemd-logind root 2329 1 0 16:28 ? 00:00:00 /bin/bash /bin/mpd_monitor.sh root 2335 1 0 16:28 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant nobody 2338 1 0 16:28 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* root 2342 1 0 16:28 ? 00:00:00 /bin/bash /bin/volumiologrotate volumio 2346 1 0 16:28 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 2347 2 0 16:28 ? 00:00:00 [kworker/5:3] root 2349 2 0 16:28 ? 00:00:00 [kworker/5:4] avahi 2357 2320 0 16:28 ? 00:00:00 avahi-daemon: chroot helper root 2370 1 0 16:28 ? 00:00:00 /usr/sbin/dhcpcd root 2476 2 0 16:28 ? 00:00:00 [kworker/3:3] root 2495 1 0 16:28 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 2518 1 0 16:28 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I root 2524 1 0 16:28 ? 00:00:00 /usr/sbin/lircd --nodaemon root 2525 2524 0 16:28 ? 00:00:00 [sh] root 2526 1 0 16:28 ttyS0 00:00:00 /sbin/agetty -o -p -- \u --keep-baud 115200,38400,9600 ttyS0 vt220 root 2528 1 0 16:28 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux root 2542 2 0 16:28 ? 00:00:00 [kworker/1:3] root 2548 2495 0 16:28 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group volumio 2563 1 4 16:28 ? 00:00:08 /usr/bin/node /volumio/index.js volumio 2601 2563 0 16:28 ? 00:00:00 /bin/bash /volumio/app/plugins/system_controller/network/network_monitor.sh volumio 2638 2563 0 16:28 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 2645 2638 0 16:28 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 2647 2638 0 16:28 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 2649 2638 0 16:28 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 2651 2638 0 16:28 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 2664 2638 0 16:28 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart mpd 2751 1 0 16:28 ? 00:00:01 /usr/bin/mpd --no-daemon shairpo+ 2802 1 0 16:28 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf volumio 2840 1 0 16:28 ? 00:00:00 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf root 2869 1 0 16:28 ? 00:00:00 /sbin/dhcpcd eth0 root 2912 1 0 16:28 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 2916 2912 0 16:28 ? 00:00:00 winbindd: domain child [VOLUMIO] root 2918 1 0 16:28 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 2920 2918 0 16:28 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 2921 2918 0 16:28 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 2922 2912 0 16:28 ? 00:00:00 winbindd: idmap child root 2923 2912 0 16:28 ? 00:00:00 winbindd: domain child [BUILTIN] root 2924 2918 0 16:28 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group ntp 3028 1 0 16:28 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 volumio 3068 2563 0 16:28 ? 00:00:00 /usr/bin/node /myvolumio/plugins/music_service/streaming_services/streamingProxy.js root 3387 2 0 16:31 ? 00:00:00 [irq/35-ff642000] root 3502 2342 0 16:31 ? 00:00:00 sleep 60 root 3509 2329 0 16:31 ? 00:00:00 sleep 20 volumio 3563 2601 0 16:31 ? 00:00:00 sleep 10 volumio 3568 2563 0 16:31 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'No info available' volumio 3569 3568 0 16:31 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js No info available volumio 3597 3569 0 16:31 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 3598 3597 0 16:31 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on udev 1.9G 0 1.9G 0% /dev tmpfs 372M 5.1M 367M 2% /run /dev/mmcblk1p2 2.5G 400M 2.0G 17% /imgpart /dev/loop0 378M 378M 0 100% /static overlay 131M 17M 105M 14% / tmpfs 1.9G 0 1.9G 0% /dev/shm tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 1.9G 0 1.9G 0% /sys/fs/cgroup tmpfs 20M 28K 20M 1% /var/log tmpfs 1.9G 64K 1.9G 1% /tmp tmpfs 1.9G 0 1.9G 0% /var/spool/cups tmpfs 1.9G 0 1.9G 0% /var/spool/cups/tmp /dev/mmcblk1p1 60M 22M 38M 37% /boot /dev/sda1 3.7G 3.5G 221M 95% /media/HD9 # mount --------------- sysfs on /sys type sysfs (rw,nosuid,nodev,noexec,relatime) proc on /proc type proc (rw,relatime) udev on /dev type devtmpfs (rw,nosuid,relatime,size=1892040k,nr_inodes=473010,mode=755) devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000) tmpfs on /run type tmpfs (rw,nosuid,nodev,noexec,relatime,size=380212k,mode=755) /dev/mmcblk1p2 on /imgpart type ext4 (rw,relatime,data=ordered) /dev/loop0 on /static type squashfs (ro,relatime) overlay on / type overlay (rw,relatime,lowerdir=/mnt/static,upperdir=/mnt/ext/dyn,workdir=/mnt/ext/work) securityfs on /sys/kernel/security type securityfs (rw,nosuid,nodev,noexec,relatime) selinuxfs on /sys/fs/selinux type selinuxfs (rw,relatime) tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev,noexec) tmpfs on /run/lock type tmpfs (rw,nosuid,nodev,noexec,relatime,size=5120k) tmpfs on /sys/fs/cgroup type tmpfs (ro,nosuid,nodev,noexec,mode=755) cgroup2 on /sys/fs/cgroup/unified type cgroup2 (rw,nosuid,nodev,noexec,relatime) 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) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) cgroup on /sys/fs/cgroup/net_cls type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) cgroup on /sys/fs/cgroup/memory type cgroup (rw,nosuid,nodev,noexec,relatime,memory) cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) cgroup on /sys/fs/cgroup/debug type cgroup (rw,nosuid,nodev,noexec,relatime,debug) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) 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) configfs on /sys/kernel/config type configfs (rw,relatime) mqueue on /dev/mqueue type mqueue (rw,relatime) sunrpc on /run/rpc_pipefs type rpc_pipefs (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/mmcblk1p1 on /boot type vfat (rw,nosuid,nodev,noexec,relatime,fmask=0111,dmask=0000,allow_utime=0022,codepage=437,iocharset=iso8859-1,shortname=mixed,utf8,errors=remount-ro,user) /dev/sda1 on /media/HD9 type vfat (rw,noatime,fmask=0000,dmask=0000,allow_utime=0022,codepage=437,iocharset=utf8,shortname=mixed,errors=remount-ro) # 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:49149 0.0.0.0:* LISTEN 2840/upmpdcli tcp 0 0 0.0.0.0:445 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:49152 0.0.0.0:* LISTEN 2840/upmpdcli tcp 0 0 0.0.0.0:5000 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:111 0.0.0.0:* LISTEN - tcp 0 0 127.0.0.1:47548 127.0.0.1:6599 ESTABLISHED 2840/upmpdcli tcp 0 0 127.0.0.1:55550 127.0.0.1:3000 ESTABLISHED 2563/node tcp 0 0 127.0.0.1:47544 127.0.0.1:6599 TIME_WAIT - tcp 0 0 127.0.0.1:50384 127.0.0.1:6600 ESTABLISHED 2563/node tcp 0 0 127.0.0.1:55594 127.0.0.1:3000 ESTABLISHED 2563/node tcp 0 0 127.0.0.1:55546 127.0.0.1:3000 ESTABLISHED 2346/volumio-remote tcp 0 0 127.0.0.1:47594 127.0.0.1:6599 ESTABLISHED 2840/upmpdcli tcp 0 0 127.0.0.1:50338 127.0.0.1:6600 ESTABLISHED 2563/node tcp 0 0 192.168.1.104:36678 34.120.160.131:443 ESTABLISHED 2563/node tcp6 0 0 :::3000 :::* LISTEN 2563/node tcp6 0 0 :::3001 :::* LISTEN 2638/node tcp6 0 0 :::3005 :::* LISTEN 2563/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::6599 :::* LISTEN 2563/node tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::3245 :::* LISTEN 3068/node tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 127.0.0.1:3000 127.0.0.1:55546 ESTABLISHED 2563/node tcp6 0 0 192.168.1.104:3000 192.168.1.101:38780 TIME_WAIT - tcp6 0 0 192.168.1.104:3000 192.168.1.101:53358 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:55594 ESTABLISHED 2563/node tcp6 0 0 169.254.151.150:3000 192.168.1.101:44112 ESTABLISHED 2563/node tcp6 0 0 192.168.1.104:3000 192.168.1.101:43476 ESTABLISHED 2563/node tcp6 0 0 192.168.1.104:3000 192.168.1.101:43474 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:55550 ESTABLISHED 2563/node tcp6 0 0 127.0.0.1:6600 127.0.0.1:50338 ESTABLISHED - tcp6 0 0 127.0.0.1:6599 127.0.0.1:47548 ESTABLISHED 2563/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:47594 ESTABLISHED 2563/node tcp6 0 0 127.0.0.1:6600 127.0.0.1:50384 ESTABLISHED - tcp6 0 0 192.168.1.104:3000 192.168.1.101:43464 TIME_WAIT - # cat /boot/config.txt --------------- cat: /boot/config.txt: No such file or directory # cat /boot/userconfig.txt --------------- cat: /boot/userconfig.txt: No such file or directory # 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 "AMLAUGESOUND" device 0 } # cat /var/log/mpd.log --------------- Sep 29 16:27 : zeroconf: No global port, disabling zeroconf Sep 29 16:27 : zeroconf: No global port, disabling zeroconf Sep 29 16:27 : mixer: Failed to read mixer for 'alsa': no such mixer control: PCM Sep 29 16:31 : output: Failed to open mixer for 'alsa': no such mixer control: PCM # 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: [] ------------------------------------------ # 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" } } }, "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" } } }, "system_controller": { "updater_comm": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "network": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "networkfs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "services": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "system": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "i2s_dacs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volumiodiscovery": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volumio_command_line_client": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "my_volumio": { "status": { "type": "string", "value": "STARTED" } } } } # cat /etc/mpd.conf --------------- # Volumio MPD Configuration File # Files and directories ####################################################### music_directory "/var/lib/mpd/music" playlist_directory "/var/lib/mpd/playlists" db_file "/var/lib/mpd/tag_cache" log_file "/var/log/mpd.log" #pid_file "/var/run/mpd/pid" #state_file "/var/lib/mpd/state" #sticker_file "/var/lib/mpd/sticker.sql" ############################################################################### # General music daemon options ################################################ user "mpd" group "audio" bind_to_address "any" #port "6600" log_level "default" #save_absolute_paths_in_playlists "no" #metadata_to_use "artist,album,title,track,name,genre,date,composer,performer,disc" auto_update "no" #auto_update_depth "3" ############################################################################### # Symbolic link behavior ###################################################### follow_outside_symlinks "yes" follow_inside_symlinks "yes" ############################################################################### # Input ####################################################################### # input { plugin "curl" # proxy "proxy.isp.com:8080" # proxy_user "user" # proxy_password "password" } ############################################################################### # Decoder ################################################################ decoder { plugin "faad" enabled "no" } ############################################################################### # Audio Output ################################################################ resampler { plugin "soxr" quality "high" threads "0" } audio_output { type "alsa" name "alsa" device "volumio" dop "yes" } 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, 3504 ms: OK https://www.googleapis.com, 838 ms: OK https://securetoken.googleapis.com, 1434 ms: OK https://myvolumio.firebaseio.com, 952 ms: OK https://functions.volumio.cloud, 1254 ms: OK https://oauth-performer.dfs.volumio.org, 1678 ms: OK https://browsing-performer.dfs.volumio.org, 2065 ms: OK http://cddb.volumio.org, 3490 ms: OK https://functions.volumio.cloud, 2504 ms: OK http://pushupdates.volumio.org, 2037 ms: OK http://plugins.volumio.org, 4246 ms: OK https://database.volumio.cloud, 2533 ms: OK https://radio-directory.firebaseapp.com, 2795 ms: OK ---------- REMOTE ENDPOINTS TEST OK, all Endpoints are reachable ---------- # cat /data/updatesHistoryFile --------------- --- Mon Jul 07 2025 13:42:19 GMT+0000 (Coordinated Universal Time) {"systemversion":"3.819","builddate":"Mon 07 Jul 2025 03:41:45 PM CEST","variant":"volumio","hardware":"odroidn2","os":"10"} --- # sudo journalctl -p 7 --------------- -- Logs begin at Mon 2025-09-29 16:27:34 CEST, end at Mon 2025-09-29 16:31:48 CEST. -- Sep 29 16:27:34 volumio kernel: Booting Linux on physical CPU 0x0 Sep 29 16:27:34 volumio kernel: Linux version 4.9.241+ (odroidn2@busteros) (gcc version 6.3.1 20170109 (Linaro GCC 6.3-2017.02) ) #8 SMP PREEMPT Sat Jan 21 21:01:33 CET 2023 Sep 29 16:27:34 volumio kernel: Boot CPU: AArch64 Processor [410fd034] Sep 29 16:27:34 volumio kernel: Machine model: Hardkernel ODROID-N2Plus Sep 29 16:27:34 volumio kernel: efi: Getting EFI parameters from FDT: Sep 29 16:27:34 volumio kernel: efi: UEFI not found. Sep 29 16:27:34 volumio kernel: __reserved_mem_alloc_size, start:0x000000007f800000, end:0x0000000080000000, len:8 MiB Sep 29 16:27:34 volumio kernel: 7f800000 - 80000000, 8192 KB, linux,meson-fb Sep 29 16:27:34 volumio kernel: e5800000 - ed800000, 131072 KB, linux,ion-dev Sep 29 16:27:34 volumio kernel: e3000000 - e5800000, 40960 KB, linux,di_cma Sep 29 16:27:34 volumio kernel: Reserved memory: created DMA memory pool at 0x00000000e3000000, size 0 MiB Sep 29 16:27:34 volumio kernel: e3000000 - e3000000, 0 KB, linux,ppmgr Sep 29 16:27:34 volumio kernel: cfc00000 - e3000000, 315392 KB, linux,codec_mm_cma Sep 29 16:27:34 volumio kernel: cfc00000 - cfc00000, 0 KB, linux,codec_mm_reserved Sep 29 16:27:34 volumio kernel: __reserved_mem_alloc_size, start:0x0000000005000000, end:0x0000000005400000, len:4 MiB Sep 29 16:27:34 volumio kernel: 05000000 - 05400000, 4096 KB, linux,secmon Sep 29 16:27:34 volumio kernel: cma: Reserved 8 MiB at 0x00000000cf400000 Sep 29 16:27:34 volumio kernel: On node 0 totalpages: 972800 Sep 29 16:27:34 volumio kernel: DMA zone: 15200 pages used for memmap Sep 29 16:27:34 volumio kernel: DMA zone: 0 pages reserved Sep 29 16:27:34 volumio kernel: DMA zone: 972800 pages, LIFO batch:31 Sep 29 16:27:34 volumio kernel: psci: probing for conduit method from DT. Sep 29 16:27:34 volumio kernel: psci: PSCIv1.0 detected in firmware. Sep 29 16:27:34 volumio kernel: psci: Using standard PSCI v0.2 function IDs Sep 29 16:27:34 volumio kernel: psci: MIGRATE_INFO_TYPE not supported. Sep 29 16:27:34 volumio kernel: psci: SMC Calling Convention v1.1 Sep 29 16:27:34 volumio kernel: percpu: Embedded 21 pages/cpu s47960 r8192 d29864 u86016 Sep 29 16:27:34 volumio kernel: pcpu-alloc: s47960 r8192 d29864 u86016 alloc=21*4096 Sep 29 16:27:34 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 [0] 4 [0] 5 Sep 29 16:27:34 volumio kernel: Detected VIPT I-cache on CPU0 Sep 29 16:27:34 volumio kernel: CPU features: enabling workaround for ARM erratum 845719 Sep 29 16:27:34 volumio kernel: Built 1 zonelists in Zone order, mobility grouping on. Total pages: 957600 Sep 29 16:27:34 volumio kernel: Kernel command line: imgpart=UUID=61423bfe-769e-466f-9174-5bda46f92d36 bootpart=UUID=EA72-F395 datapart=UUID=cd0dd53b-05d8-40ae-973b-95b695721e63 imgfile=/volumio_current.sqsh uuidconfig=boot.ini quiet loglevel=0 splash plymouth.ignore-serial-consoles initramfs.clear hwdevice=Odroid-N2 bootdelay=5 gpiopower=479 rootwait rw console=ttyS0,115200n8 no_console_suspend fsck.repair=yes net.ifnames=0 elevator=noop hdmimode=2160p60hz cvbsmode=576cvbs max_freq_a53=1908 max_freq_a73=2208 maxcpus=6 voutmode=hdmi disablehpd=false cvbscable= overscan=100 monitor_onoff=false logo=osd0,loaded hdmitx=cec3f sdrmode=auto Sep 29 16:27:34 volumio kernel: gpio-keypad: gpiopower_setup gpiopower : 479 Sep 29 16:27:34 volumio kernel: vout: get hdmimode: 2160p60hz Sep 29 16:27:34 volumio kernel: vout: get cvbsmode: 576cvbs Sep 29 16:27:34 volumio kernel: max cpufreq of cluster0 : 1908000kHz Sep 29 16:27:34 volumio kernel: max cpufreq of cluster1 : 2208000kHz Sep 29 16:27:34 volumio kernel: hdmitx: voutmode : 1 Sep 29 16:27:34 volumio kernel: fb: osd0 Sep 29 16:27:34 volumio kernel: fb: loaded Sep 29 16:27:34 volumio kernel: hdmitx: HDMI hdmi_cec_func_config:0x3f Sep 29 16:27:34 volumio kernel: PID hash table entries: 4096 (order: 3, 32768 bytes) Sep 29 16:27:34 volumio kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes) Sep 29 16:27:34 volumio kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes) Sep 29 16:27:34 volumio kernel: Memory: 3276176K/3891200K available (11964K kernel code, 1816K rwdata, 5136K rodata, 4992K init, 1421K bss, 107120K reserved, 507904K cma-reserved) Sep 29 16:27:34 volumio kernel: Virtual kernel memory layout: Sep 29 16:27:34 volumio kernel: modules : 0xffffff8000000000 - 0xffffff8008000000 ( 128 MB) Sep 29 16:27:34 volumio kernel: vmalloc : 0xffffff8008000000 - 0xffffffbebfff0000 ( 250 GB) Sep 29 16:27:34 volumio kernel: .text : 0xffffff8009080000 - 0xffffff8009c30000 ( 11968 KB) Sep 29 16:27:34 volumio kernel: .rodata : 0xffffff8009c30000 - 0xffffff800a140000 ( 5184 KB) Sep 29 16:27:34 volumio kernel: .init : 0xffffff800a140000 - 0xffffff800a620000 ( 4992 KB) Sep 29 16:27:34 volumio kernel: .data : 0xffffff800a620000 - 0xffffff800a7e6200 ( 1817 KB) Sep 29 16:27:34 volumio kernel: .bss : 0xffffff800a7e6200 - 0xffffff800a9498bc ( 1422 KB) Sep 29 16:27:34 volumio kernel: fixed : 0xffffffbefe7fd000 - 0xffffffbefec00000 ( 4108 KB) Sep 29 16:27:34 volumio kernel: PCI I/O : 0xffffffbefee00000 - 0xffffffbeffe00000 ( 16 MB) Sep 29 16:27:34 volumio kernel: vmemmap : 0xffffffbf00000000 - 0xffffffc000000000 ( 4 GB maximum) Sep 29 16:27:34 volumio kernel: 0xffffffbf00000000 - 0xffffffbf03b60000 ( 59 MB actual) Sep 29 16:27:34 volumio kernel: memory : 0xffffffc000000000 - 0xffffffc0ed800000 ( 3800 MB) Sep 29 16:27:34 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Sep 29 16:27:34 volumio kernel: Preemptible hierarchical RCU implementation. Sep 29 16:27:34 volumio kernel: Build-time adjustment of leaf fanout to 64. Sep 29 16:27:34 volumio kernel: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=6. Sep 29 16:27:34 volumio kernel: RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=6 Sep 29 16:27:34 volumio kernel: NR_IRQS:64 nr_irqs:64 0 Sep 29 16:27:34 volumio kernel: GPIO-INTC: support to detect double-edge trigger signal Sep 29 16:27:34 volumio kernel: g12a_aoclkc_init: register ao clk ok! Sep 29 16:27:34 volumio kernel: Meson chip version = RevC (29:C - 40:0) Sep 29 16:27:34 volumio kernel: meson_g12a_sdemmc_init: register amlogic sdemmc clk Sep 29 16:27:34 volumio kernel: meson_g12a_sdemmc_init: register amlogic sdemmc clk Sep 29 16:27:34 volumio kernel: meson_g12a_gpu_init: register meson gpu clk Sep 29 16:27:34 volumio kernel: meson_g12a_media_init: register meson media clk Sep 29 16:27:34 volumio kernel: meson_g12a_misc_init: register amlogic g12a misc clks Sep 29 16:27:34 volumio kernel: meson_g12a_misc_init: done. Sep 29 16:27:34 volumio kernel: g12a_clkc_init initialization complete Sep 29 16:27:34 volumio kernel: arm_arch_timer: Architected cp15 timer(s) running at 24.00MHz (phys). Sep 29 16:27:34 volumio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns Sep 29 16:27:34 volumio kernel: sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns Sep 29 16:27:34 volumio kernel: meson_bc_timer: mclk->mux_reg =ffffff800800c190,mclk->reg =ffffff800800e194 Sep 29 16:27:34 volumio kernel: Console: colour dummy device 80x25 Sep 29 16:27:34 volumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000) Sep 29 16:27:34 volumio kernel: pid_max: default: 32768 minimum: 301 Sep 29 16:27:34 volumio kernel: Security Framework initialized Sep 29 16:27:34 volumio kernel: SELinux: Initializing. Sep 29 16:27:34 volumio kernel: SELinux: Starting in permissive mode Sep 29 16:27:34 volumio kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes) Sep 29 16:27:34 volumio kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes) Sep 29 16:27:34 volumio kernel: ftrace: allocating 39807 entries in 156 pages Sep 29 16:27:34 volumio kernel: sched-energy: Sched-energy-costs installed from DT Sep 29 16:27:34 volumio kernel: CPU0: update cpu_capacity 631 Sep 29 16:27:34 volumio kernel: ASID allocator initialised with 65536 entries Sep 29 16:27:34 volumio kernel: secmon: clear_range:5100000 200000 Sep 29 16:27:34 volumio kernel: EFI services will not be available. Sep 29 16:27:34 volumio kernel: Detected VIPT I-cache on CPU1 Sep 29 16:27:34 volumio kernel: CPU1: update cpu_capacity 631 Sep 29 16:27:34 volumio kernel: CPU1: Booted secondary processor [410fd034] Sep 29 16:27:34 volumio kernel: Detected VIPT I-cache on CPU2 Sep 29 16:27:34 volumio kernel: CPU2: update cpu_capacity 1192 Sep 29 16:27:34 volumio kernel: CPU2: Booted secondary processor [410fd092] Sep 29 16:27:34 volumio kernel: Detected VIPT I-cache on CPU3 Sep 29 16:27:34 volumio kernel: CPU3: update cpu_capacity 1192 Sep 29 16:27:34 volumio kernel: CPU3: Booted secondary processor [410fd092] Sep 29 16:27:34 volumio kernel: Detected VIPT I-cache on CPU4 Sep 29 16:27:34 volumio kernel: CPU4: update cpu_capacity 1192 Sep 29 16:27:34 volumio kernel: CPU4: Booted secondary processor [410fd092] Sep 29 16:27:34 volumio kernel: Detected VIPT I-cache on CPU5 Sep 29 16:27:34 volumio kernel: CPU5: update cpu_capacity 1192 Sep 29 16:27:34 volumio kernel: CPU5: Booted secondary processor [410fd092] Sep 29 16:27:34 volumio kernel: Brought up 6 CPUs Sep 29 16:27:34 volumio kernel: SMP: Total of 6 processors activated. Sep 29 16:27:34 volumio kernel: CPU features: detected feature: 32-bit EL0 Support Sep 29 16:27:34 volumio kernel: CPU: All CPU(s) started at EL2 Sep 29 16:27:34 volumio kernel: alternatives: patching kernel code Sep 29 16:27:34 volumio kernel: devtmpfs: initialized Sep 29 16:27:34 volumio kernel: DMI not present or invalid. Sep 29 16:27:34 volumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Sep 29 16:27:34 volumio kernel: futex hash table entries: 2048 (order: 5, 131072 bytes) Sep 29 16:27:34 volumio kernel: pinctrl core: initialized pinctrl subsystem Sep 29 16:27:34 volumio kernel: NET: Registered protocol family 16 Sep 29 16:27:34 volumio kernel: schedtune: init normalization constants... Sep 29 16:27:34 volumio kernel: schedtune: CLUSTER[0-1] min_pwr: 0 max_pwr: 42 Sep 29 16:27:34 volumio kernel: schedtune: CPU[0] min_pwr: 0 max_pwr: 279 Sep 29 16:27:34 volumio kernel: schedtune: CPU[1] min_pwr: 0 max_pwr: 279 Sep 29 16:27:34 volumio kernel: schedtune: CLUSTER[2-5] min_pwr: 0 max_pwr: 110 Sep 29 16:27:34 volumio kernel: schedtune: CPU[2] min_pwr: 0 max_pwr: 1048 Sep 29 16:27:34 volumio kernel: schedtune: CPU[3] min_pwr: 0 max_pwr: 1048 Sep 29 16:27:34 volumio kernel: schedtune: CPU[4] min_pwr: 0 max_pwr: 1048 Sep 29 16:27:34 volumio kernel: schedtune: CPU[5] min_pwr: 0 max_pwr: 1048 Sep 29 16:27:34 volumio kernel: schedtune: SYSTEM min_pwr: 0 max_pwr: 4902 Sep 29 16:27:34 volumio kernel: schedtune: using normalization constants mul: 2882587190 sh1: 1 sh2: 12 Sep 29 16:27:34 volumio kernel: schedtune: verify normalization constants... Sep 29 16:27:34 volumio kernel: schedtune: max_pwr/2^0: 4902 => norm_pwr: 1024 Sep 29 16:27:34 volumio kernel: schedtune: max_pwr/2^1: 2451 => norm_pwr: 512 Sep 29 16:27:34 volumio kernel: schedtune: max_pwr/2^2: 1225 => norm_pwr: 255 Sep 29 16:27:34 volumio kernel: schedtune: max_pwr/2^3: 612 => norm_pwr: 127 Sep 29 16:27:34 volumio kernel: schedtune: max_pwr/2^4: 306 => norm_pwr: 63 Sep 29 16:27:34 volumio kernel: schedtune: max_pwr/2^5: 153 => norm_pwr: 31 Sep 29 16:27:34 volumio kernel: schedtune: configured to support global boosting only Sep 29 16:27:34 volumio kernel: cpuidle: using governor menu Sep 29 16:27:34 volumio kernel: register canvas platform driver Sep 29 16:27:34 volumio kernel: register rdma platform driver Sep 29 16:27:34 volumio kernel: vdso: 2 pages (1 code @ ffffff8009c37000, 1 data @ ffffff800a625000) Sep 29 16:27:34 volumio kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 29 16:27:34 volumio kernel: DMA: preallocated 2048 KiB pool for atomic allocations Sep 29 16:27:34 volumio kernel: clkmsr: clkmsr: driver init Sep 29 16:27:34 volumio kernel: codec_mm_module_init Sep 29 16:27:34 volumio kernel: media_configs_system_init Sep 29 16:27:34 volumio kernel: aml_iomap: amlogic iomap probe done Sep 29 16:27:34 volumio kernel: vpu: driver version: v20190329(8-g12b) Sep 29 16:27:34 volumio kernel: vpu: load vpu_clk: 666666667Hz(7) Sep 29 16:27:34 volumio kernel: vpu: clktree_init Sep 29 16:27:34 volumio kernel: vpu: vpu_probe OK Sep 29 16:27:34 volumio kernel: clkmsr: msr_clk_reg0=ffffff800823a004,msr_clk_reg2=ffffff800823c00c Sep 29 16:27:34 volumio kernel: clkmsr ffd18004.meson_clk_msr: failed to get msr ring reg0 Sep 29 16:27:34 volumio kernel: audio_clocks: audio_clocks_probe done Sep 29 16:27:34 volumio kernel: aml_vdac_config_probe: cpu_id:4, name:meson-g12ab-vdac Sep 29 16:27:34 volumio kernel: aml_vdac_probe: ok Sep 29 16:27:34 volumio kernel: canvas_probe reg=00000000ff638000,size=2000 Sep 29 16:27:34 volumio kernel: canvas maped reg_base =ffffff8008268000 Sep 29 16:27:34 volumio kernel: rdma_probe,cpu_type:0, ver:0, len:8 Sep 29 16:27:34 volumio kernel: rdma_register, rdma_table_addr ffffff8008277000 rdma_table_addr_phy cf600000 reg_buf ffffffc0ca2a0000 Sep 29 16:27:34 volumio kernel: rdma_register success, handle 1 table_size 32768 Sep 29 16:27:34 volumio kernel: set_rdma_handle video rdma handle = 1. Sep 29 16:27:34 volumio kernel: classs created ok Sep 29 16:27:34 volumio kernel: classs file created ok Sep 29 16:27:34 volumio kernel: aml_snd_reg_map[0], reg:ff640000, size:2000 Sep 29 16:27:34 volumio kernel: aml_snd_reg_map[1], reg:ff642000, size:2000 Sep 29 16:27:34 volumio kernel: aml_snd_reg_map[2], reg:ff64a000, size:2000 Sep 29 16:27:34 volumio kernel: aml_snd_reg_map[3], reg:ff656000, size:1800 Sep 29 16:27:34 volumio kernel: aml_snd_reg_map[4], reg:ffd01000, size:1000 Sep 29 16:27:34 volumio kernel: amlogic auge_snd_iomap probe done Sep 29 16:27:34 volumio kernel: codec_mm codec_mm: assigned reserved memory node linux,codec_mm_cma Sep 29 16:27:34 volumio kernel: codec_mm codec_mm: assigned reserved memory node linux,codec_mm_cma Sep 29 16:27:34 volumio kernel: codec_mm_probe ok Sep 29 16:27:34 volumio kernel: cvbs_out: cvbsout_probe, cpu_id:5,name:meson-g12b-cvbsout Sep 29 16:27:34 volumio kernel: cvbs_out: clk path:0 Sep 29 16:27:34 volumio kernel: cvbs_out: cvbs trimming.1.v5: 0xa0, 0x0 Sep 29 16:27:34 volumio kernel: vout: vout1: register server: cvbs_vout_server Sep 29 16:27:34 volumio kernel: cvbs_out: register cvbs module server ok Sep 29 16:27:34 volumio kernel: vout: vout2: register server: cvbs_vout2_server Sep 29 16:27:34 volumio kernel: cvbs_out: register cvbs module vout2 server ok Sep 29 16:27:34 volumio kernel: cvbs_out: chrdev devno 264241152 for disp Sep 29 16:27:34 volumio kernel: cvbs_out: create cdev cvbs Sep 29 16:27:34 volumio kernel: cvbs_out: cvbsout_probe OK Sep 29 16:27:34 volumio kernel: SCSI subsystem initialized Sep 29 16:27:34 volumio kernel: usbcore: registered new interface driver usbfs Sep 29 16:27:34 volumio kernel: usbcore: registered new interface driver hub Sep 29 16:27:34 volumio kernel: usbcore: registered new device driver usb Sep 29 16:27:34 volumio kernel: media: Linux media interface: v0.10 Sep 29 16:27:34 volumio kernel: Linux video capture interface: v2.00 Sep 29 16:27:34 volumio kernel: pps_core: LinuxPPS API ver. 1 registered Sep 29 16:27:34 volumio kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 29 16:27:34 volumio kernel: PTP clock support registered Sep 29 16:27:34 volumio kernel: dmi: Firmware registration failed. Sep 29 16:27:34 volumio kernel: secmon: reserve_mem_size:0x300000 Sep 29 16:27:34 volumio kernel: secmon secmon: assigned reserved memory node linux,secmon Sep 29 16:27:34 volumio kernel: secmon: get page:ffffffbf00140000, 5000 Sep 29 16:27:34 volumio kernel: secmon: share in base: 0xffffffc0050fe000, share out base: 0xffffffc0050ff000 Sep 29 16:27:34 volumio kernel: secmon: phy_in_base: 0x50fe000, phy_out_base: 0x50ff000 Sep 29 16:27:34 volumio kernel: hdmitx: system: amhdmitx_probe start Sep 29 16:27:34 volumio kernel: hdmitx: system: Ver: 20190815 Sep 29 16:27:34 volumio kernel: hdmitx: system: hdmitx_device.chip_type : 11 Sep 29 16:27:34 volumio kernel: hdmitx: system: not find match pwr-ctl Sep 29 16:27:34 volumio kernel: hdmitx: system: not find drm_amhdmitx Sep 29 16:27:34 volumio kernel: hdmitx: system: hpd irq = 41 Sep 29 16:27:34 volumio kernel: hdmitx: system: hdcp22_tx_skp failed to probe Sep 29 16:27:34 volumio kernel: hdmitx: system: hdcp22_tx_esm failed to probe Sep 29 16:27:34 volumio kernel: vout: vout1: register server: hdmitx_vout_server Sep 29 16:27:34 volumio kernel: vout: vout2: register server: hdmitx_vout2_server Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx20: Mapped PHY: 0xffd00000 Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx20: Mapped PHY: 0xff634400 Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx20: Mapped PHY: 0xff900000 Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx20: Mapped PHY: 0xff800000 Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx20: Mapped PHY: 0xff63c000 Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx20: Mapped PHY: 0xffd00000 Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx20: Mapped PHY: 0xff608000 Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx20: Mapped PHY: 0xff600000 Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx20: Mapped PHY: 0xffe01000 Sep 29 16:27:34 volumio kernel: hdmitx: hw: alread display in uboot 0x10 Sep 29 16:27:34 volumio kernel: hdmitx: hw: avmute set to 1 Sep 29 16:27:34 volumio kernel: hdmitx: system: fmt_attr 444,8bit Sep 29 16:27:34 volumio kernel: hdmitx: system: fmt_attr 444,8bit Sep 29 16:27:34 volumio kernel: hdmitx: system: amhdmitx_probe end Sep 29 16:27:34 volumio kernel: vout: create vout attribute OK Sep 29 16:27:34 volumio kernel: vout: vout_fops_create OK Sep 29 16:27:34 volumio kernel: vout: vout1: register server: nulldisp_vout_server Sep 29 16:27:34 volumio kernel: vout: tvout monitor interval:500(ms), timeout cnt:20 Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx_set_current_vmode[3877] Sep 29 16:27:34 volumio kernel: hdmitx: system: recalc before 2160p60hz 60 1 Sep 29 16:27:34 volumio kernel: hdmitx: system: recalc after 2160p60hz 2997 50 Sep 29 16:27:34 volumio kernel: hdmitx: system: get current mode: 2160p60hz Sep 29 16:27:34 volumio kernel: hdmitx: system: update rx hdr info 0 Sep 29 16:27:34 volumio kernel: hdmitx: system: update physcial size: 16 9 Sep 29 16:27:34 volumio kernel: hdmitx: video: already init VIC = 0 Now VIC = 97 Sep 29 16:27:34 volumio kernel: hdmitx: video: rx edid only support RGB format Sep 29 16:27:34 volumio kernel: hdmitx: hw: div40: 1 Sep 29 16:27:34 volumio kernel: hdmitx: system: irq 80000002 0 Sep 29 16:27:34 volumio kernel: hdmitx: frac_rate = 1 Sep 29 16:27:34 volumio kernel: hdmitx: config HPLL = 5940000 frac_rate = 1 Sep 29 16:27:34 volumio kernel: hdmitx: HPLLv1: 0xdb3a04f7 Sep 29 16:27:34 volumio kernel: hdmitx: config HPLL done Sep 29 16:27:34 volumio kernel: hdmitx: set_hpll_od3_clk_div[643] div = 6 Sep 29 16:27:34 volumio kernel: hdmitx: j = 9 vid_clk_div = 1 Sep 29 16:27:34 volumio kernel: hdmitx: set enc for VIC: 97 Sep 29 16:27:34 volumio kernel: hdmitx: hw: config hdmitx IP vic = 97 cd:4 cs: 2 Sep 29 16:27:34 volumio kernel: hdmitx: system: irq 80000001 0 Sep 29 16:27:34 volumio kernel: hdmitx: video: Sink is HDMI device Sep 29 16:27:34 volumio kernel: hdmitx: hw: set audio Sep 29 16:27:34 volumio kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Sep 29 16:27:34 volumio kernel: hdmitx: fs = 0, cd = 4, tmds_clk = 593407 Sep 29 16:27:34 volumio kernel: hdmitx: hw: aud_n_para = 5824 Sep 29 16:27:34 volumio kernel: hdmitx: hw: set channel status Sep 29 16:27:34 volumio kernel: vout: init mode 2160p60hz set ok Sep 29 16:27:34 volumio kernel: vout: aml_tvout_mode_monitor Sep 29 16:27:34 volumio kernel: vout: aml_vout_probe OK Sep 29 16:27:34 volumio kernel: chip type:0x29 Sep 29 16:27:34 volumio kernel: MEMORY:[0+ed800000] Sep 29 16:27:34 volumio kernel: ramdump_probe, storage device:data Sep 29 16:27:34 volumio kernel: NO valid ramdump args:0 0 Sep 29 16:27:34 volumio kernel: ramdump_probe, set sticky to 8ed8 Sep 29 16:27:34 volumio kernel: Advanced Linux Sound Architecture Driver Initialized. Sep 29 16:27:34 volumio kernel: NetLabel: Initializing Sep 29 16:27:34 volumio kernel: NetLabel: domain hash size = 128 Sep 29 16:27:34 volumio kernel: NetLabel: protocols = UNLABELED CIPSOv4 Sep 29 16:27:34 volumio kernel: NetLabel: unlabeled traffic allowed by default Sep 29 16:27:34 volumio kernel: clocksource: Switched to clocksource arch_sys_counter Sep 29 16:27:34 volumio kernel: VFS: Disk quotas dquot_6.6.0 Sep 29 16:27:34 volumio kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 29 16:27:34 volumio kernel: NET: Registered protocol family 2 Sep 29 16:27:34 volumio kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes) Sep 29 16:27:34 volumio kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes) Sep 29 16:27:34 volumio kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 29 16:27:34 volumio kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes) Sep 29 16:27:34 volumio kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes) Sep 29 16:27:34 volumio kernel: NET: Registered protocol family 1 Sep 29 16:27:34 volumio kernel: PCI: CLS 0 bytes, default 64 Sep 29 16:27:34 volumio kernel: Unpacking initramfs... Sep 29 16:27:34 volumio kernel: hdmitx: [0x10000104]: 0x00000001 Sep 29 16:27:34 volumio kernel: hdmitx: [0x10000180]: 0x000000ff Sep 29 16:27:34 volumio kernel: hdmitx: [0x10000181]: 0x000000ff Sep 29 16:27:34 volumio kernel: hdmitx: [0x10000182]: 0x00000003 Sep 29 16:27:34 volumio kernel: hdmitx: [0x10000183]: 0x00000007 Sep 29 16:27:34 volumio kernel: hdmitx: [0x10000184]: 0x0000003f Sep 29 16:27:34 volumio kernel: hdmitx: [0x10000185]: 0x00000002 Sep 29 16:27:34 volumio kernel: hdmitx: [0x10000187]: 0x000000ff Sep 29 16:27:34 volumio kernel: hdmitx: [0x10000188]: 0x00000003 Sep 29 16:27:34 volumio kernel: hdmitx: system: plugin Sep 29 16:27:34 volumio kernel: Freeing initrd memory: 13028K Sep 29 16:27:34 volumio kernel: hw perfevents: clusterb_enabled = 1 Sep 29 16:27:34 volumio kernel: hw perfevents: cpumasks 0x3, 0x3c Sep 29 16:27:34 volumio kernel: hw perfevents: cluster A irq = 18 Sep 29 16:27:34 volumio kernel: hw perfevents: cluster B irq = 19 Sep 29 16:27:34 volumio kernel: hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available Sep 29 16:27:34 volumio kernel: audit: initializing netlink subsys (disabled) Sep 29 16:27:34 volumio kernel: audit: type=2000 audit(1.036:1): initialized Sep 29 16:27:34 volumio kernel: workingset: timestamp_bits=46 max_order=20 bucket_order=0 Sep 29 16:27:34 volumio kernel: Registering sdcardfs 0.1 Sep 29 16:27:34 volumio kernel: SELinux: Registering netfilter hooks Sep 29 16:27:34 volumio kernel: NET: Registered protocol family 38 Sep 29 16:27:34 volumio kernel: Key type asymmetric registered Sep 29 16:27:34 volumio kernel: Asymmetric key parser 'x509' registered Sep 29 16:27:34 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243) Sep 29 16:27:34 volumio kernel: io scheduler noop registered (default) Sep 29 16:27:34 volumio kernel: io scheduler deadline registered Sep 29 16:27:34 volumio kernel: io scheduler cfq registered Sep 29 16:27:34 volumio kernel: pwm-gpio pwmgpio: 1 gpio pwms loaded Sep 29 16:27:34 volumio kernel: meson-pwm ff802000.pwm: pwm pinmux : can't get pinctrl Sep 29 16:27:34 volumio kernel: meson-pwm ffd1b000.pwm: pwm pinmux : can't get pinctrl Sep 29 16:27:34 volumio kernel: random: fast init done Sep 29 16:27:34 volumio kernel: random: crng init done Sep 29 16:27:34 volumio kernel: gpiomem-aml ff634000.gpiomem: Initialised: GPIO register area is 1 Sep 29 16:27:34 volumio kernel: gpiomem-aml ff634000.gpiomem: Initialised: Registers at start:0xff634000 end:0xff634fff size:0x00000fff Sep 29 16:27:34 volumio kernel: [drm] Initialized Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: Continuing without Mali regulator control Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: max pp is 2 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: set min pp to default 1 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: min pp is 1 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: min clk is 4 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: hiu io source 0xffffff80083bd000 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: hiu io source 0xffffff80083bf000 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: num of pp used most of time 1 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: clock dvfs cfg table size is 6 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: max clk set 4 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: max clk is 4 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: turbo clk set to 5 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: turbo clk is 5 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: default clk set to 4 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: default clk is 4 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: ====================0==================== clk_freq= 285714285, clk_parent=fclk_div7, voltage=1150, keep_count=5, threshod=<100 190>, clk_sample=285 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: ====================1==================== clk_freq= 400000000, clk_parent=fclk_div5, voltage=1150, keep_count=5, threshod=<152 207>, clk_sample=400 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: ====================2==================== clk_freq= 500000000, clk_parent=fclk_div4, voltage=1150, keep_count=5, threshod=<180 220>, clk_sample=500 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: ====================3==================== clk_freq= 666666666, clk_parent=fclk_div3, voltage=1150, keep_count=5, threshod=<210 236>, clk_sample=666 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: ====================4==================== clk_freq= 800000000, clk_parent=fclk_div2p5, voltage=1150, keep_count=5, threshod=<230 255>, clk_sample=800 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: ====================5==================== clk_freq= 800000000, clk_parent=fclk_div2p5, voltage=1150, keep_count=5, threshod=<230 255>, clk_sample=800 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: clock dvfs table size is 6 Sep 29 16:27:34 volumio kernel: mali_plat=ffffff800a6b8250 Sep 29 16:27:34 volumio kernel: meson_gcooldev is null, no set min status Sep 29 16:27:34 volumio kernel: gpu cooling register okay with err=0 Sep 29 16:27:34 volumio kernel: meson_gcooldev is null, no set min status Sep 29 16:27:34 volumio kernel: gpu core cooling register okay with err=0 Sep 29 16:27:34 volumio kernel: shader_present=3, tiler_present=1, l2_present=1 Sep 29 16:27:34 volumio kernel: Mali_pwr_on:gpu_irq : 200 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: GPU identified as 0x2 arch 7.2.1 r0p0 status 0 Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: Using coherency mode 0 set from dtb Sep 29 16:27:34 volumio kernel: mali ffe40000.bifrost: Probed as mali0 Sep 29 16:27:34 volumio kernel: Unable to detect cache hierarchy for CPU 0 Sep 29 16:27:34 volumio kernel: loop: module loaded Sep 29 16:27:34 volumio kernel: mtdoops: mtd device (mtddev=name/number) must be supplied Sep 29 16:27:34 volumio kernel: libphy: Fixed MDIO Bus: probed Sep 29 16:27:34 volumio kernel: REG0:Addr = ffffff80083c3540 Sep 29 16:27:34 volumio kernel: ee eth reset:Addr = ffffff80083c5008 Sep 29 16:27:34 volumio kernel: read auto_cali_idx fail Sep 29 16:27:34 volumio kernel: Not set cali_val for REG1 Sep 29 16:27:34 volumio kernel: meson6-dwmac ff3f0000.ethernet: no reset control found Sep 29 16:27:34 volumio kernel: stmmac - user ID: 0x11, Synopsys ID: 0x37 Sep 29 16:27:34 volumio kernel: Ring mode enabled Sep 29 16:27:34 volumio kernel: DMA HW capability register supported Sep 29 16:27:34 volumio kernel: Normal descriptors Sep 29 16:27:34 volumio kernel: RX Checksum Offload Engine supported Sep 29 16:27:34 volumio kernel: COE Type 2 Sep 29 16:27:34 volumio kernel: TX Checksum insertion supported Sep 29 16:27:34 volumio kernel: Wake-Up On Lan supported Sep 29 16:27:34 volumio kernel: eth%d: device MAC address 00:1e:06:42:a3:da Sep 29 16:27:34 volumio kernel: Enable RX Mitigation via HW Watchdog Timer Sep 29 16:27:34 volumio kernel: libphy: stmmac: probed Sep 29 16:27:34 volumio kernel: eth%d: PHY ID 001cc916 at 0 IRQ POLL (stmmac-0:00) active Sep 29 16:27:34 volumio kernel: eth%d: PHY ID 001cc916 at 7 IRQ POLL (stmmac-0:07) Sep 29 16:27:34 volumio kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver Sep 29 16:27:34 volumio kernel: ehci-pci: EHCI PCI platform driver Sep 29 16:27:34 volumio kernel: ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver Sep 29 16:27:34 volumio kernel: usbcore: registered new interface driver cdc_acm Sep 29 16:27:34 volumio kernel: cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters Sep 29 16:27:34 volumio kernel: usbcore: registered new interface driver usb-storage Sep 29 16:27:34 volumio kernel: usbcore: registered new interface driver usbserial Sep 29 16:27:34 volumio kernel: usbcore: registered new interface driver usbserial_generic Sep 29 16:27:34 volumio kernel: usbserial: USB Serial support registered for generic Sep 29 16:27:34 volumio kernel: mousedev: PS/2 mouse device common for all mice Sep 29 16:27:34 volumio kernel: i2c /dev entries driver Sep 29 16:27:34 volumio kernel: lirc_helper: wakeupkey 0xffffffff, protocol 0x1 Sep 29 16:27:34 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Sep 29 16:27:34 volumio kernel: hidraw: raw HID events driver (C) Jiri Kosina Sep 29 16:27:34 volumio kernel: usbcore: registered new interface driver usbhid Sep 29 16:27:34 volumio kernel: usbhid: USB HID core driver Sep 29 16:27:34 volumio kernel: meson_cpufreq_init: ignor dsu clk! Sep 29 16:27:34 volumio kernel: meson_cpufreq_init: ignor dsu pre parent clk! Sep 29 16:27:34 volumio kernel: value of voltage_tolerance 0 Sep 29 16:27:34 volumio kernel: meson_cpufreq_init:don't find the node Sep 29 16:27:34 volumio kernel: value of gp1_clk_target 0 Sep 29 16:27:34 volumio kernel: dvfs [meson_cpufreq_init] - cluster 0 freq 2016000 Sep 29 16:27:34 volumio kernel: cpu cpu0: meson_cpufreq_init: CPU 0 initialized Sep 29 16:27:34 volumio kernel: meson_cpufreq_init: ignor dsu clk! Sep 29 16:27:34 volumio kernel: meson_cpufreq_init: ignor dsu pre parent clk! Sep 29 16:27:34 volumio kernel: value of voltage_tolerance 0 Sep 29 16:27:34 volumio kernel: meson_cpufreq_init:don't find the node Sep 29 16:27:34 volumio kernel: value of gp1_clk_target 0 Sep 29 16:27:34 volumio kernel: dvfs [meson_cpufreq_init] - cluster 1 freq 2304000 Sep 29 16:27:34 volumio kernel: dvfs [meson_cpufreq_init] - cluster 1 freq 2400000 Sep 29 16:27:34 volumio kernel: cpu cpu2: meson_cpufreq_init: CPU 2 initialized Sep 29 16:27:34 volumio kernel: ff803000.serial: clock gate not found Sep 29 16:27:34 volumio kernel: meson_uart ff803000.serial: ==uart0 reg addr = ffffff80083cb000 Sep 29 16:27:34 volumio kernel: ff803000.serial: ttyS0 at MMIO 0xff803000 (irq = 31, base_baud = 1500000) is a meson_uart Sep 29 16:27:34 volumio kernel: meson_uart ff803000.serial: ttyS0 use xtal(24M) 24000000 change 0 to 115200 Sep 29 16:27:34 volumio kernel: console [ttyS0] enabled Sep 29 16:27:34 volumio kernel: amlogic-new-usb2-v2 ffe09000.usb2phy: USB2 phy probe:phy_mem:0xffe09000, iomap phy_base:0xffffff80083cd000 Sep 29 16:27:34 volumio kernel: amlogic-new-usb3-v2 ffe09080.usb3phy: USB3 phy probe:phy_mem:0xffe09080, iomap phy_base:0xffffff80083eb080 Sep 29 16:27:34 volumio kernel: hdmitx: edid: EDID Parser: Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx: reach vesa idx MAX Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx: reach vesa idx MAX Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx: reach vesa idx MAX Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx: reach vesa idx MAX Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx: reach vesa idx MAX Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx: reach vesa idx MAX Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx: reach vesa idx MAX Sep 29 16:27:34 volumio kernel: [RX]-up_phy_addr = 22 Sep 29 16:27:34 volumio kernel: hdmitx: v2 VSVDB: len=11, sup_2160p60hz=1, Interface=2 Sep 29 16:27:34 volumio kernel: hdmitx: edid: dump_dtd_info[2024] Sep 29 16:27:34 volumio kernel: hdmitx: edid: pixel_clock: 0 Sep 29 16:27:34 volumio kernel: hdmitx: edid: h_active: 0 Sep 29 16:27:34 volumio kernel: hdmitx: edid: h_blank: 0 Sep 29 16:27:34 volumio kernel: hdmitx: edid: v_active: 0 Sep 29 16:27:34 volumio kernel: hdmitx: edid: v_blank: 185 Sep 29 16:27:34 volumio kernel: hdmitx: edid: h_sync_offset: 0 Sep 29 16:27:34 volumio kernel: hdmitx: edid: h_sync: 0 Sep 29 16:27:34 volumio kernel: hdmitx: edid: v_sync_offset: 0 Sep 29 16:27:34 volumio kernel: hdmitx: edid: v_sync: 0 Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx: get PMT vic: 97 Sep 29 16:27:34 volumio kernel: hdmitx: edid: find IEEEOUT Sep 29 16:27:34 volumio kernel: hdmitx: edid: check sum valid Sep 29 16:27:34 volumio kernel: hdmitx: edid: check sum valid Sep 29 16:27:34 volumio kernel: hdmitx: edid: update rx hdr info d at edid parsing Sep 29 16:27:34 volumio kernel: hdmitx: edid: check sum valid Sep 29 16:27:34 volumio kernel: hdmitx: edid: check sum valid Sep 29 16:27:34 volumio kernel: hdmitx: edid: blk0 raw data Sep 29 16:27:34 volumio kernel: hdmitx: edid: 00ffffffffffff0011ee7600010101010020010380a05a780aee91a3544c9926 0f5054a1080031404540614071408180d1c00101010108e80030f2705a80b058 8a0040846300001e023a801871382d40582c4500c48e2100001e000000fc0044 454e4f4e2d4156520a202020000000fd0018781eff77000a202020202020015f Sep 29 16:27:34 volumio kernel: hdmitx: edid: blk1 raw data Sep 29 16:27:34 volumio kernel: hdmitx: edid: 020379f15961606665101f04130514030212202122155d5e5f6263643f403b0f 7f073d1ec01507505f7e03570603677e034d02007b045b5f7e01835f00006e03 0c002200b83c2c0080010203046ad85dc40178800b022878e20f0fe3060d01eb 0146d000480296805e6597e305c000e200cfe61146d0007000000000000000b9 Sep 29 16:27:34 volumio kernel: hdmitx: system: update physcial size: 1600 900 Sep 29 16:27:34 volumio kernel: hdmitx: video: Sink is HDMI device Sep 29 16:27:34 volumio kernel: hdmitx: hw: set audio Sep 29 16:27:34 volumio kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Sep 29 16:27:34 volumio kernel: hdmitx: fs = 0, cd = 4, tmds_clk = 593407 Sep 29 16:27:34 volumio kernel: hdmitx: hw: aud_n_para = 5824 Sep 29 16:27:34 volumio kernel: hdmitx: hw: set channel status Sep 29 16:27:34 volumio kernel: aml_dma ff63e000.aml_dma: Aml dma Sep 29 16:27:34 volumio kernel: hdmitx: system: get current mode: 2160p60hz Sep 29 16:27:34 volumio kernel: hdmitx: system: update rx hdr info d Sep 29 16:27:34 volumio kernel: hdmitx: system: update physcial size: 1600 900 Sep 29 16:27:34 volumio kernel: hdmitx: system: [set_disp_mode_auto] ALREADY init VIC = 97 Sep 29 16:27:34 volumio kernel: hdmitx: system: change to HDMI mode Sep 29 16:27:34 volumio kernel: aml_aes_dma ff63e000.aml_dma:aml_aes: Aml AES_dma Sep 29 16:27:34 volumio kernel: aml_tdes_dma ff63e000.aml_dma:aml_tdes: Aml TDES_dma Sep 29 16:27:34 volumio kernel: aml_sha_dma ff63e000.aml_dma:aml_sha: Aml SHA1/SHA224/SHA256 dma Sep 29 16:27:34 volumio kernel: gpio-keypad ff800000.gpio_keypad: power key(116) registed. Sep 29 16:27:34 volumio kernel: input: gpio_keypad as /devices/platform/ff800000.gpio_keypad/input/input0 Sep 29 16:27:34 volumio kernel: efusekeynum: 1 Sep 29 16:27:34 volumio kernel: efusekeyname: uuid offset: 0 size: 32 Sep 29 16:27:34 volumio kernel: efuse efuse: probe OK! Sep 29 16:27:34 volumio kernel: ion_dev soc:ion_dev: assigned reserved memory node linux,ion-dev Sep 29 16:27:34 volumio kernel: ge2d: ge2d_init_module Sep 29 16:27:34 volumio kernel: ge2d: ge2d_dev major:236 Sep 29 16:27:34 volumio kernel: ge2d: clock source clk_ge2d_gate ffffffc0ca0cacc0 Sep 29 16:27:34 volumio kernel: ge2d: clock clk_ge2d source ffffffc0ca0cad40 Sep 29 16:27:34 volumio kernel: ge2d: clock source clk_vapb_0 ffffffc0ca0cac00 Sep 29 16:27:34 volumio kernel: ge2d: ge2d init clock is 500000000 HZ, VPU clock is 666666656 HZ Sep 29 16:27:34 volumio kernel: ge2d: ge2d clock is 499 MHZ Sep 29 16:27:34 volumio kernel: ge2d: find address resource Sep 29 16:27:34 volumio kernel: ge2d: map io source 0x00000000ff940000,size=65536 to 0xffffff8008590000 Sep 29 16:27:34 volumio kernel: ge2d: reserved mem init failed Sep 29 16:27:34 volumio kernel: ge2d: ge2d: pdev=ffffffc0ca277000, irq=44, clk=ffffffc0ca0cacc0 Sep 29 16:27:34 volumio kernel: ge2d: ge2d start monitor Sep 29 16:27:34 volumio kernel: ge2d: ge2d workqueue monitor start Sep 29 16:27:34 volumio kernel: [tsync_pcr_init]init success. Sep 29 16:27:34 volumio kernel: amvideom vsync irq: 45 Sep 29 16:27:34 volumio kernel: create_ge2d_work_queue video task ok Sep 29 16:27:34 volumio kernel: fb: osd_init_module Sep 29 16:27:34 volumio kernel: fb: viu vsync irq: 45 Sep 29 16:27:34 volumio kernel: fb: viu2 vsync irq: 54 Sep 29 16:27:34 volumio kernel: 0x000000db:Y=db,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000dc:Y=dc,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000dd:Y=dd,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000de:Y=de,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000df:Y=df,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000e0:Y=e0,U=0,V=0 Sep 29 16:27:34 volumio kernel: fb: osd_rdma_init: rdma_table p=0xcf608000,op=0xcf608000 , v=0xffffff80083f7000 Sep 29 16:27:34 volumio kernel: rdma_register, rdma_table_addr ffffff80083f9000 rdma_table_addr_phy cf609000 reg_buf ffffffc0ca1e7000 Sep 29 16:27:34 volumio kernel: rdma_register success, handle 2 table_size 4096 Sep 29 16:27:34 volumio kernel: fb: osd_rdma_init:osd rdma handle = 2. Sep 29 16:27:34 volumio kernel: fb: mem_size: 0x800000 Sep 29 16:27:34 volumio kernel: fb: mem_size: 0x4b80000 Sep 29 16:27:34 volumio kernel: fb: mem_size: 0x100000 Sep 29 16:27:34 volumio kernel: fb: mem_size: 0x100000 Sep 29 16:27:34 volumio kernel: fb: mem_size: 0x800000 Sep 29 16:27:34 volumio kernel: meson-fb meson-fb: assigned reserved memory node linux,meson-fb Sep 29 16:27:34 volumio kernel: fb: reserved memory base:0x000000007f800000, size:800000 Sep 29 16:27:34 volumio kernel: fb: fb def : 3840 2160 3840 4320 32 Sep 29 16:27:34 volumio kernel: fb: init fbdev bpp is:32 Sep 29 16:27:34 volumio kernel: fb: malloc_osd_memory, cma:ffffff800a8a1978 Sep 29 16:27:34 volumio kernel: fb: malloc_osd_memory, 1231, base:0x000000007f800000, size:8388608 Sep 29 16:27:34 volumio kernel: fb: use ion buffer for fb memory, fb_index=0 Sep 29 16:27:34 volumio kernel: fb: OSD0 as afbcd mode,afbc_type=2 Sep 29 16:27:34 volumio kernel: meson-fb meson-fb: create ion_client ffffffc0bab74cc0, handle=ffffffc0bab76680 Sep 29 16:27:34 volumio kernel: meson-fb meson-fb: ion memory(0): created fb at 0x00000000e5800000, size 75 MiB Sep 29 16:27:34 volumio kernel: fb: 0, phy: 0x00000000e5800000, vir:0xffffff800a94f000, size=77312K Sep 29 16:27:34 volumio kernel: fb: Frame buffer memory assigned at Sep 29 16:27:34 volumio kernel: fb: 0, phy: 0x00000000e5800000, vir:0xffffff800a94f000, size=77312K Sep 29 16:27:34 volumio kernel: fb: 0, phy: 0x00000000e5800000, vir:0xffffff800a94f000, size=77312K Sep 29 16:27:34 volumio kernel: fb: logo_index=0,fb_index=0 Sep 29 16:27:34 volumio kernel: fb: ---------------clear fb0 memory ffffff800a94f000 Sep 29 16:27:34 volumio kernel: clear:c4800000, free:c4800000, tick:631710 us Sep 29 16:27:34 volumio kernel: fb: osd[0] canvas.idx =0x40 Sep 29 16:27:34 volumio kernel: fb: osd[0] canvas.addr=0xe5800000 Sep 29 16:27:34 volumio kernel: fb: osd[0] canvas.width=15360 Sep 29 16:27:34 volumio kernel: fb: osd[0] canvas.height=4320 Sep 29 16:27:34 volumio kernel: fb: osd[0] frame.width=3840 Sep 29 16:27:34 volumio kernel: fb: osd[0] frame.height=2160 Sep 29 16:27:34 volumio kernel: fb: osd[0] out_addr_id =0x1 Sep 29 16:27:34 volumio kernel: Console: switching to colour frame buffer device 480x135 Sep 29 16:27:34 volumio kernel: fb: osd[0] enable: 1 (swapper/0) Sep 29 16:27:34 volumio kernel: fb: set osd0 reverse as NONE Sep 29 16:27:34 volumio kernel: fb: osd probe OK Sep 29 16:27:34 volumio kernel: hdmitx: hdcp: hdmitx_hdcp_init Sep 29 16:27:34 volumio kernel: vout: vout2: create vout2 attribute OK Sep 29 16:27:34 volumio kernel: vout: vout2: vout2_fops_create OK Sep 29 16:27:34 volumio kernel: vout: vout2: clktree_init Sep 29 16:27:34 volumio kernel: vout: vout2: register server: nulldisp_vout2_server Sep 29 16:27:34 volumio kernel: vout: vout2: init mode null set ok Sep 29 16:27:34 volumio kernel: vout: vout2: aml_vout2_probe OK Sep 29 16:27:34 volumio kernel: DI: di_module_init ok. Sep 29 16:27:34 volumio kernel: DI: di_probe: Sep 29 16:27:34 volumio kernel: DI: di_probe: major 510 Sep 29 16:27:34 volumio kernel: deinterlace deinterlace: assigned reserved memory node linux,di_cma Sep 29 16:27:34 volumio kernel: di:flag_cma=1 Sep 29 16:27:34 volumio kernel: DI: CMA size 0x2800000. Sep 29 16:27:34 volumio kernel: pre_irq:68 Sep 29 16:27:34 volumio kernel: post_irq:69 Sep 29 16:27:34 volumio kernel: DI: di_probe allocate rdma channel 0. Sep 29 16:27:34 volumio kernel: di_get_vpu_clkb: get clk vpu error. Sep 29 16:27:34 volumio kernel: DI: vpu clkb <334000000, 667000000> Sep 29 16:27:34 volumio kernel: get clkb rate:333333328 Sep 29 16:27:34 volumio kernel: DI:enable vpu clkb. Sep 29 16:27:34 volumio kernel: 0x000000e1:Y=e1,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000e2:Y=e2,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000e3:Y=e3,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000f0:Y=f0,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000f1:Y=f1,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000f2:Y=f2,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000f3:Y=f3,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000f4:Y=f4,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000f5:Y=f5,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000f6:Y=f6,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000f7:Y=f7,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000f8:Y=f8,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000f9:Y=f9,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000fa:Y=fa,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000fb:Y=fb,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000fc:Y=fc,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000fd:Y=fd,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000fe:Y=fe,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x000000ff:Y=ff,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x0000003a:Y=3a,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x0000003b:Y=3b,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x0000003c:Y=3c,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x0000003d:Y=3d,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x0000003e:Y=3e,U=0,V=0 Sep 29 16:27:34 volumio kernel: 0x0000003f:Y=3f,U=0,V=0 Sep 29 16:27:34 volumio kernel: DI: support multi decoding 61~62~63. Sep 29 16:27:34 volumio kernel: DI: di_probe:Di use HRTIMER Sep 29 16:27:34 volumio kernel: DI: di_probe:ok Sep 29 16:27:34 volumio kernel: dim:dim_module_init Sep 29 16:27:34 volumio kernel: dim:dim_module_init finish Sep 29 16:27:34 volumio kernel: dil:dil_init. Sep 29 16:27:34 volumio kernel: dil:dil_init ok. Sep 29 16:27:34 volumio kernel: vdin_drv_init: major 509 Sep 29 16:27:34 volumio kernel: vdin_drv_init: vdin driver init done Sep 29 16:27:34 volumio kernel: [viuin..]viuin_init_module viuin module init Sep 29 16:27:34 volumio kernel: [viuin..]viuin_probe probe ok. Sep 29 16:27:34 volumio kernel: [RX]-hdmirx: hdmirx_init. Sep 29 16:27:34 volumio kernel: ESM HLD: Initializing... Sep 29 16:27:34 volumio kernel: amlvid:info: amlvideo_init called Sep 29 16:27:34 volumio kernel: amlvid:info: amlvideo_create_instance called Sep 29 16:27:34 volumio kernel: amlvid:info: v4l2_dev.name=:amlvideo-000 Sep 29 16:27:34 volumio kernel: amlvideo-000: V4L2 device registered as video10 Sep 29 16:27:34 volumio kernel: amlvid:info: amlvideo_create_instance called Sep 29 16:27:34 volumio kernel: amlvid:info: v4l2_dev.name=:amlvideo-001 Sep 29 16:27:34 volumio kernel: amlvideo-001: V4L2 device registered as video23 Sep 29 16:27:34 volumio kernel: PPMGRDRV: warn: ppmgr module init func called Sep 29 16:27:34 volumio kernel: PPMGRDRV: info: ppmgr_driver_probe called Sep 29 16:27:34 volumio kernel: Reserved memory: failed to init DMA memory pool at 0x00000000e3000000, size 0 MiB Sep 29 16:27:34 volumio kernel: PPMGRDRV: info: ppmgr_dev major:507 Sep 29 16:27:34 volumio kernel: ionvideo-000: V4L2 device registered as video13 Sep 29 16:27:34 volumio kernel: ionvideo-001: V4L2 device registered as video14 Sep 29 16:27:34 volumio kernel: ionvideo-002: V4L2 device registered as video15 Sep 29 16:27:34 volumio kernel: ionvideo-003: V4L2 device registered as video16 Sep 29 16:27:34 volumio kernel: ionvideo-004: V4L2 device registered as video17 Sep 29 16:27:34 volumio kernel: ionvideo-005: V4L2 device registered as video18 Sep 29 16:27:34 volumio kernel: ionvideo-006: V4L2 device registered as video19 Sep 29 16:27:34 volumio kernel: ionvideo-007: V4L2 device registered as video20 Sep 29 16:27:34 volumio kernel: ionvideo-008: V4L2 device registered as video21 Sep 29 16:27:34 volumio kernel: ionvid: info: Video Technology Magazine Ion Video Sep 29 16:27:34 volumio kernel: ionvid: info: Capture Board ver 1.0 successfully loaded Sep 29 16:27:34 volumio kernel: videosync_create_instance dev_s ffffffc0c9f74400,dev_s->dev ffffffc0280e8380 Sep 29 16:27:34 volumio kernel: videosync_create_instance reg videosync.0 Sep 29 16:27:34 volumio kernel: aml_vecm_init:module init Sep 29 16:27:34 volumio kernel: videosync_thread started Sep 29 16:27:34 volumio kernel: VECM probe start Sep 29 16:27:34 volumio kernel: Can't find detect_colorbar. Sep 29 16:27:34 volumio kernel: Can't find detect_face. Sep 29 16:27:34 volumio kernel: Can't find detect_corn. Sep 29 16:27:34 volumio kernel: Can't find wb_sel. Sep 29 16:27:34 volumio kernel: hdr:Can't find cfg_en_osd_100. Sep 29 16:27:34 volumio kernel: Can't find tx_op_color_primary. Sep 29 16:27:34 volumio kernel: amlogic, vecm Sep 29 16:27:34 volumio kernel: vlock dt support: 1 Sep 29 16:27:34 volumio kernel: vlock dt new_fsm: 0 Sep 29 16:27:34 volumio kernel: vlock dt hwver: 0 Sep 29 16:27:34 volumio kernel: vlock dt phlock_en: 0 Sep 29 16:27:34 volumio kernel: Can't find vlock_en. Sep 29 16:27:34 volumio kernel: Can't find vlock_mode. Sep 29 16:27:34 volumio kernel: Can't find vlock_pll_m_limit. Sep 29 16:27:34 volumio kernel: Can't find vlock_line_limit. Sep 29 16:27:34 volumio kernel: param_config vlock_en:1 md=0x4 Sep 29 16:27:34 volumio kernel: vlock: maxLine 524,maxPixel 1715 Sep 29 16:27:34 volumio kernel: vlock_status_init vlock_en:1 Sep 29 16:27:34 volumio kernel: aml_vecm_probe: ok Sep 29 16:27:34 volumio kernel: amdolby_vision_init:module init Sep 29 16:27:34 volumio kernel: amdolby_vision probe start & ver: 20181220 Sep 29 16:27:34 volumio kernel: cpu_id=2 tvmode=0 Sep 29 16:27:34 volumio kernel: dolby_vision_init_receiver(dvel) Sep 29 16:27:34 volumio kernel: dolby_vision_init_receiver: dvel Sep 29 16:27:34 volumio kernel: amdolby_vision_probe: ok Sep 29 16:27:34 volumio kernel: dovi disable in uboot Sep 29 16:27:34 volumio kernel: reg base = ffffff8008587000 Sep 29 16:27:34 volumio kernel: meson-mmc: mmc driver version: 3.02, 2017-05-15: New Emmc Host Controller Sep 29 16:27:34 volumio kernel: meson-mmc: >>>>>>>>hostbase ffffff80085a1000, dmode Sep 29 16:27:34 volumio kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Sep 29 16:27:34 volumio kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Sep 29 16:27:34 volumio kernel: hdmitx: hdmitx_set_drm_pkt: tf=1, cf=1, colormetry=0 Sep 29 16:27:34 volumio kernel: meson-mmc: meson_mmc_probe() : success! Sep 29 16:27:34 volumio kernel: meson-mmc: >>>>>>>>hostbase ffffff80085aa000, dmode Sep 29 16:27:34 volumio kernel: meson-mmc: gpio_cd = 1ca Sep 29 16:27:34 volumio kernel: meson-mmc: emmc: resp_timeout,vstat:0xa1ff2800,virqc:3fff Sep 29 16:27:34 volumio kernel: meson-mmc: emmc: err: wait for irq service, bus_fsm:0x8 Sep 29 16:27:34 volumio kernel: meson-mmc: meson_mmc_irq_thread_v3() 653: set 1st retry! Sep 29 16:27:34 volumio kernel: meson-mmc: retry cmd 1 the 3-th time(s) Sep 29 16:27:34 volumio kernel: meson-mmc: emmc: resp_timeout,vstat:0x9dff0800,virqc:3fff Sep 29 16:27:34 volumio kernel: meson-mmc: emmc: err: wait for desc write back, bus_fsm:0x7 Sep 29 16:27:34 volumio kernel: meson-mmc: retry cmd 1 the 2-th time(s) Sep 29 16:27:34 volumio kernel: meson-mmc: emmc: resp_timeout,vstat:0x9dff0800,virqc:3fff Sep 29 16:27:34 volumio kernel: meson-mmc: emmc: err: wait for desc write back, bus_fsm:0x7 Sep 29 16:27:34 volumio kernel: meson-mmc: retry cmd 1 the 1-th time(s) Sep 29 16:27:34 volumio kernel: meson-mmc: emmc: resp_timeout,vstat:0x9dff0800,virqc:3fff Sep 29 16:27:34 volumio kernel: meson-mmc: emmc: err: wait for desc write back, bus_fsm:0x7 Sep 29 16:27:34 volumio kernel: meson-mmc: Command retried failed line:675, cmd:1 Sep 29 16:27:34 volumio kernel: meson-mmc: meson_mmc_probe() : success! Sep 29 16:27:34 volumio kernel: cectx ff80023c.aocec: cec driver date:2019/10/22: finetune ARB rising time Sep 29 16:27:34 volumio kernel: cectx ff80023c.aocec: compatible:amlogic, aocec-g12a Sep 29 16:27:34 volumio kernel: cectx ff80023c.aocec: cecb_ver:0x1 Sep 29 16:27:34 volumio kernel: cectx ff80023c.aocec: line_reg:0x1 Sep 29 16:27:34 volumio kernel: cectx ff80023c.aocec: line_bit:0x3 Sep 29 16:27:34 volumio kernel: cectx ff80023c.aocec: ee_to_ao:0x1 Sep 29 16:27:34 volumio kernel: input: cec_input as /devices/virtual/input/input1 Sep 29 16:27:34 volumio kernel: cectx ff80023c.aocec: not find 'port_num' Sep 29 16:27:34 volumio kernel: cectx ff80023c.aocec: using cec:1 Sep 29 16:27:34 volumio kernel: cectx ff80023c.aocec: no hdmirx regs Sep 29 16:27:34 volumio kernel: cectx ff80023c.aocec: no hhi regs Sep 29 16:27:34 volumio kernel: cectx ff80023c.aocec: not find 'output' Sep 29 16:27:34 volumio kernel: irq cnt:2, a:43, b42 Sep 29 16:27:34 volumio kernel: cectx ff80023c.aocec: wakeup_reason:0x0 Sep 29 16:27:34 volumio kernel: cectx ff80023c.aocec: cev val1: 0x0;val2: 0x0 Sep 29 16:27:34 volumio kernel: cectx ff80023c.aocec: aml_cec_probe success end Sep 29 16:27:34 volumio kernel: unifykey: storage in base: 0xffffffc005000000 Sep 29 16:27:34 volumio kernel: unifykey: storage out base: 0xffffffc005040000 Sep 29 16:27:34 volumio kernel: unifykey: storage block base: 0xffffffc005080000 Sep 29 16:27:34 volumio kernel: unifykey: probe done! Sep 29 16:27:34 volumio kernel: unifykey: aml_unifykeys_init done! Sep 29 16:27:34 volumio kernel: meson ts init Sep 29 16:27:34 volumio kernel: tsensor id: 0 Sep 29 16:27:34 volumio kernel: r1p1_tsensor_read valid cnt is 0 Sep 29 16:27:34 volumio kernel: tsensor trim info: 0xfa0080df! Sep 29 16:27:34 volumio kernel: tsensor hireboot: 0xc0ff2b00 Sep 29 16:27:34 volumio kernel: meson ts init Sep 29 16:27:34 volumio kernel: tsensor id: 1 Sep 29 16:27:34 volumio kernel: r1p1_tsensor_read valid cnt is 0 Sep 29 16:27:34 volumio kernel: tsensor trim info: 0xfa008116! Sep 29 16:27:34 volumio kernel: tsensor hireboot: 0xc0ff2b10 Sep 29 16:27:34 volumio kernel: audio_dsp: [dsp]register dsp to char divece(257) Sep 29 16:27:34 volumio kernel: amaudio: amaudio: driver amaudio init! Sep 29 16:27:34 volumio kernel: amaudio: amaudio_init - amaudio: driver amaudio succuess! Sep 29 16:27:34 volumio kernel: amlkaraoke init success! Sep 29 16:27:34 volumio kernel: sysled: module init Sep 29 16:27:34 volumio kernel: meson_wdt ffd0f0d0.watchdog: start watchdog Sep 29 16:27:34 volumio kernel: meson_wdt ffd0f0d0.watchdog: creat work queue for watch dog Sep 29 16:27:34 volumio kernel: meson_wdt ffd0f0d0.watchdog: AML Watchdog Timer probed done Sep 29 16:27:34 volumio kernel: meson-saradc ff809000.saradc: set delay per tick to <1ms> by default. Sep 29 16:27:34 volumio kernel: meson-saradc ff809000.saradc: set ticks per period to <1> by default. Sep 29 16:27:34 volumio kernel: dmc_monitor_probe Sep 29 16:27:34 volumio kernel: atv_demod: aml_atvdemod_init: OK, atv demod version: V2.15. Sep 29 16:27:34 volumio kernel: defendkey ff630218.defendkey: Reserved memory is not enough! Sep 29 16:27:34 volumio kernel: defendkey: probe of ff630218.defendkey failed with error -22 Sep 29 16:27:34 volumio kernel: usbcore: registered new interface driver snd-usb-audio Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: aml_T9015_audio_codec_probe Sep 29 16:27:34 volumio kernel: T9015 acodec used by auge, tdmout:2 Sep 29 16:27:34 volumio kernel: Error: Driver 'spdif-dit' is already registered, aborting... Sep 29 16:27:34 volumio kernel: asoc debug: aml_audio_controller_probe-130 Sep 29 16:27:34 volumio kernel: aml_tdm_platform_probe, tdm ID = 2, lane_cnt = 4 Sep 29 16:27:34 volumio kernel: TDM id 2 i2s2hdmi:0 Sep 29 16:27:34 volumio kernel: snd_tdm ff642000.audiobus:tdmc: lane_mask_out = 1, lane_oe_mask_out = 0 Sep 29 16:27:34 volumio kernel: snd_tdm ff642000.audiobus:tdmc: neither mclk_pad nor mclk2pad set Sep 29 16:27:34 volumio kernel: snd_tdm ff642000.audiobus:tdmc: aml_tdm_get_pins error! Sep 29 16:27:34 volumio kernel: aml_tdm_platform_probe(), share en = 1 Sep 29 16:27:34 volumio kernel: No channel mask node Channel_Mask Sep 29 16:27:34 volumio kernel: aml_spdif_platform_probe, spdif ID = 0 Sep 29 16:27:34 volumio kernel: SPDIF id 0 asrc_id:0 auto_asrc:0 Sep 29 16:27:34 volumio kernel: spdifout_play_with_zerodata, spdif id:0 enable:0 Sep 29 16:27:34 volumio kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Sep 29 16:27:34 volumio kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Sep 29 16:27:34 volumio kernel: hdmitx: audio: aout notify format CT_PCM Sep 29 16:27:34 volumio kernel: hdmitx: hw: set audio Sep 29 16:27:34 volumio kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Sep 29 16:27:34 volumio kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 593407 Sep 29 16:27:34 volumio kernel: hdmitx: hw: aud_n_para = 5824 Sep 29 16:27:34 volumio kernel: hdmitx: hw: set channel status Sep 29 16:27:34 volumio kernel: hdmitx: audio: Audio Type: PCM Sep 29 16:27:34 volumio kernel: hdmitx: audio: set audio param Sep 29 16:27:34 volumio kernel: spdif_a is set to enable Sep 29 16:27:34 volumio kernel: aml_spdif_platform_probe, register soc platform Sep 29 16:27:34 volumio kernel: aml_spdif_platform_probe, spdif ID = 1 Sep 29 16:27:34 volumio kernel: spdifout_play_with_zerodata, spdif id:1 enable:0 Sep 29 16:27:34 volumio kernel: spdif_b fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Sep 29 16:27:34 volumio kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Sep 29 16:27:34 volumio kernel: spdif_b is set to enable Sep 29 16:27:34 volumio kernel: aml_spdif_platform_probe, register soc platform Sep 29 16:27:34 volumio kernel: audio-ddr-manager ff642000.audiobus:ddr_manager: 0, irqs toddr 32, frddr 35 Sep 29 16:27:34 volumio kernel: audio-ddr-manager ff642000.audiobus:ddr_manager: 1, irqs toddr 33, frddr 36 Sep 29 16:27:34 volumio kernel: audio-ddr-manager ff642000.audiobus:ddr_manager: 2, irqs toddr 34, frddr 37 Sep 29 16:27:34 volumio kernel: audiolocker_platform_probe Sep 29 16:27:34 volumio kernel: Register vad Sep 29 16:27:34 volumio kernel: meson-mmc: card IN Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Left DAC Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Left DAC -> LOLP_SEL_DACL -> Lineout left P switch Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Left DAC Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Left DAC -> LOLP_SEL_DACL_INV -> Lineout left P switch Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Left DAC Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Left DAC -> LOLN_SEL_DACL_INV -> Lineout left N switch Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Left DAC Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Left DAC -> LOLN_SEL_DACL -> Lineout left N switch Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Right DAC Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Right DAC -> LORP_SEL_DACR -> Lineout right P switch Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Right DAC Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Right DAC -> LORP_SEL_DACR_INV -> Lineout right P switch Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Right DAC Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Right DAC -> LORN_SEL_DACR_INV -> Lineout right N switch Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: no source widget found for Right DAC Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: Failed to add route Right DAC -> LORN_SEL_DACR -> Lineout right N switch Sep 29 16:27:34 volumio kernel: aml_dai_spdif_probe Sep 29 16:27:34 volumio kernel: aml_dai_spdif_probe Sep 29 16:27:34 volumio kernel: set normal 512 fs /4 fs Sep 29 16:27:34 volumio kernel: set spdifout clk:6144000, mpll:24576000 Sep 29 16:27:34 volumio kernel: get spdifout clk:6143997, mpll:24575987 Sep 29 16:27:34 volumio kernel: aml_dai_set_spdif_fmt , fmt 0x4010 Sep 29 16:27:34 volumio kernel: aml_spdif_new spdif_b, clk continuous:1 Sep 29 16:27:34 volumio kernel: spdifout_play_with_zerodata_free, spdif id:1 Sep 29 16:27:34 volumio kernel: asoc-aml-card auge_sound: dit-hifi <-> SPDIF-B mapping ok Sep 29 16:27:34 volumio kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Sep 29 16:27:34 volumio kernel: aml_dai_set_tdm_slot(), txmask(0x3), rxmask(0x3) Sep 29 16:27:34 volumio kernel: lanes_out_cnt(1), lanes_in_cnt(1) Sep 29 16:27:34 volumio kernel: lanes_lb_cnt(0) Sep 29 16:27:34 volumio kernel: slots(2), slot_width(32) Sep 29 16:27:34 volumio kernel: lanes_oe_out_cnt(0), lanes_oe_in_cnt(0) Sep 29 16:27:34 volumio kernel: asoc aml_dai_set_tdm_fmt, 0x4011, ffffffc02813e818, id(2), clksel(2) Sep 29 16:27:34 volumio kernel: master_mode(1), binv(1), finv(1) out_skew(2), in_skew(3) Sep 29 16:27:34 volumio kernel: asoc-aml-card auge_sound: T9015-audio-hifi <-> TDM-C mapping ok Sep 29 16:27:34 volumio kernel: set normal 512 fs /4 fs Sep 29 16:27:34 volumio kernel: set spdifout clk:6144000, mpll:24576000 Sep 29 16:27:34 volumio kernel: get spdifout clk:6143997, mpll:24575987 Sep 29 16:27:34 volumio kernel: aml_dai_set_spdif_fmt , fmt 0x4010 Sep 29 16:27:34 volumio kernel: aml_spdif_new spdif_a, clk continuous:1 Sep 29 16:27:34 volumio kernel: spdifout_play_with_zerodata_free, spdif id:0 Sep 29 16:27:34 volumio kernel: asoc-aml-card auge_sound: dit-hifi <-> SPDIF mapping ok Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: mux Lineout left P switch has no paths Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: mux Lineout left N switch has no paths Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: mux Lineout right P switch has no paths Sep 29 16:27:34 volumio kernel: aml_codec_T9015 ff632000.t9015: ASoC: mux Lineout right N switch has no paths Sep 29 16:27:34 volumio kernel: snd_card_add_kcontrols card:ffffffc0ca30a018 Sep 29 16:27:34 volumio kernel: effect_v2 is not init Sep 29 16:27:34 volumio kernel: Not init vad Sep 29 16:27:34 volumio kernel: Failed to add VAD controls Sep 29 16:27:34 volumio kernel: eq/drc v1 function enable Sep 29 16:27:34 volumio kernel: Netfilter messages via NETLINK v0.30. Sep 29 16:27:34 volumio kernel: Initializing XFRM netlink socket Sep 29 16:27:34 volumio kernel: NET: Registered protocol family 17 Sep 29 16:27:34 volumio kernel: Key type dns_resolver registered Sep 29 16:27:34 volumio kernel: Registered swp emulation handler Sep 29 16:27:34 volumio kernel: Registered cp15_barrier emulation handler Sep 29 16:27:34 volumio kernel: Registered setend emulation handler Sep 29 16:27:34 volumio kernel: registered taskstats version 1 Sep 29 16:27:34 volumio kernel: dwc3 ff500000.dwc3: Configuration mismatch. dr_mode forced to host Sep 29 16:27:34 volumio kernel: xhci-hcd xhci-hcd.0.auto: xHCI Host Controller Sep 29 16:27:34 volumio kernel: xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1 Sep 29 16:27:34 volumio kernel: usb: xhci: determined sg_tablesize: 4294967295 Sep 29 16:27:34 volumio kernel: xhci-hcd xhci-hcd.0.auto: hcc params 0x0228fe6c hci version 0x110 quirks 0x20010010 Sep 29 16:27:34 volumio kernel: xhci-hcd xhci-hcd.0.auto: irq 23, io mem 0xff500000 Sep 29 16:27:34 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 Sep 29 16:27:34 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 29 16:27:34 volumio kernel: usb usb1: Product: xHCI Host Controller Sep 29 16:27:34 volumio kernel: usb usb1: Manufacturer: Linux 4.9.241+ xhci-hcd Sep 29 16:27:34 volumio kernel: usb usb1: SerialNumber: xhci-hcd.0.auto Sep 29 16:27:34 volumio kernel: hub 1-0:1.0: USB hub found Sep 29 16:27:34 volumio kernel: hub 1-0:1.0: 2 ports detected Sep 29 16:27:34 volumio kernel: xhci-hcd xhci-hcd.0.auto: xHCI Host Controller Sep 29 16:27:34 volumio kernel: xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2 Sep 29 16:27:34 volumio kernel: usb: xhci: determined sg_tablesize: 4294967295 Sep 29 16:27:34 volumio kernel: usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. Sep 29 16:27:34 volumio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003 Sep 29 16:27:34 volumio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Sep 29 16:27:34 volumio kernel: usb usb2: Product: xHCI Host Controller Sep 29 16:27:34 volumio kernel: usb usb2: Manufacturer: Linux 4.9.241+ xhci-hcd Sep 29 16:27:34 volumio kernel: usb usb2: SerialNumber: xhci-hcd.0.auto Sep 29 16:27:34 volumio kernel: hub 2-0:1.0: USB hub found Sep 29 16:27:34 volumio kernel: hub 2-0:1.0: 1 port detected Sep 29 16:27:34 volumio kernel: hctosys: unable to open rtc device (rtc0) Sep 29 16:27:34 volumio kernel: dwc_otg ff400000.dwc2_a: dwc_otg_driver_probe(ffffffc0ca136c00) Sep 29 16:27:34 volumio kernel: dwc_otg: usb0: type: 2 speed: 0, config: 0, dma: 0, id: 0, phy: ffe09000, ctrl: 0 Sep 29 16:27:34 volumio kernel: dwc_otg ff400000.dwc2_a: base=0xffffff8008c00000 Sep 29 16:27:34 volumio kernel: dwc_otg ff400000.dwc2_a: dwc_otg_device=0xffffffc0bab29e00 Sep 29 16:27:34 volumio kernel: meson-mmc: normal card in Sep 29 16:27:34 volumio kernel: dwc_otg: Core Release: 3.30a Sep 29 16:27:34 volumio kernel: dwc_otg: Setting default values for core params Sep 29 16:27:34 volumio kernel: dwc_otg: curmode: 0, host_only: 0 Sep 29 16:27:34 volumio kernel: dwc_otg ff400000.dwc2_a: DMA config: BURST_DEFAULT Sep 29 16:27:34 volumio kernel: dwc_otg: Using Buffer DMA mode Sep 29 16:27:34 volumio kernel: dwc_otg: OTG VER PARAM: 1, OTG VER FLAG: 1 Sep 29 16:27:34 volumio kernel: dwc_otg: Working on port type = SLAVE Sep 29 16:27:34 volumio kernel: dwc_otg: Dedicated Tx FIFOs mode Sep 29 16:27:34 volumio kernel: meson_cdev probe Sep 29 16:27:34 volumio kernel: meson_cdev index: 0 Sep 29 16:27:34 volumio kernel: thermal thermal_zone0: binding zone soc_thermal with cdev thermal-cpufreq-0 failed:-22 Sep 29 16:27:34 volumio kernel: meson_cdev index: 1 Sep 29 16:27:34 volumio kernel: thermal: no cluster id, cpucore as one cooldev Sep 29 16:27:34 volumio kernel: meson_cdev index: 2 Sep 29 16:27:34 volumio kernel: cpucore_cooling_register, max_cpu_core_num:6 Sep 29 16:27:34 volumio kernel: cpucore_cooling_register, clutser[1] core num:4 Sep 29 16:27:34 volumio kernel: cpucore_cooling_register, clutser[0] core num:2 Sep 29 16:27:34 volumio kernel: meson_cdev index: 3 Sep 29 16:27:34 volumio kernel: meson_cdev index: 4 Sep 29 16:27:34 volumio kernel: find tzd id: 0 Sep 29 16:27:34 volumio kernel: find tzd id: 0 Sep 29 16:27:34 volumio kernel: find tzd id: 0 Sep 29 16:27:34 volumio kernel: meson_cdev probe done Sep 29 16:27:34 volumio kernel: gxbb_pm: enter meson_pm_probe! Sep 29 16:27:34 volumio kernel: no vddio3v3_en pin Sep 29 16:27:34 volumio kernel: pm-meson aml_pm: Can't get switch_clk81 Sep 29 16:27:34 volumio kernel: gxbb_pm: meson_pm_probe done Sep 29 16:27:34 volumio kernel: ALSA device list: Sep 29 16:27:34 volumio kernel: #0: AML-AUGESOUND Sep 29 16:27:34 volumio kernel: meson_uart ff803000.serial: ttyS0 use xtal(24M) 24000000 change 115200 to 115200 Sep 29 16:27:34 volumio kernel: Freeing unused kernel memory: 4992K Sep 29 16:27:34 volumio kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Sep 29 16:27:34 volumio kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Sep 29 16:27:34 volumio kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Sep 29 16:27:34 volumio kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Sep 29 16:27:34 volumio kernel: meson-aml-mmc ffe05000.sd: divider requested rate 200000000 != actual rate 199999997: ret=0 Sep 29 16:27:34 volumio kernel: meson-mmc: actual_clock :199999997, HHI_nand: 0x80 Sep 29 16:27:34 volumio kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x10000245 Sep 29 16:27:34 volumio kernel: meson-mmc: Data 1 aligned delay is 0 Sep 29 16:27:34 volumio kernel: meson-mmc: sd: clk 199999997 tuning start Sep 29 16:27:34 volumio kernel: meson-mmc: sd: adj_win: < 1 2 3 4 > Sep 29 16:27:34 volumio kernel: meson-mmc: step:4, delay1:0x4104104, delay2:0x4004104 Sep 29 16:27:34 volumio kernel: meson-mmc: sd: adj_win: < 1 2 3 4 > Sep 29 16:27:34 volumio kernel: meson-mmc: step:8, delay1:0x8208208, delay2:0x8008208 Sep 29 16:27:34 volumio kernel: meson-mmc: sd: adj_win: < 1 2 3 4 > Sep 29 16:27:34 volumio kernel: meson-mmc: step:12, delay1:0xc30c30c, delay2:0xc00c30c Sep 29 16:27:34 volumio kernel: meson-mmc: sd: adj_win: < 0 1 2 3 4 > Sep 29 16:27:34 volumio kernel: meson-mmc: step:16, delay1:0x10410410, delay2:0x10010410 Sep 29 16:27:34 volumio kernel: meson-mmc: sd: adj_win: < 0 1 2 3 4 > Sep 29 16:27:34 volumio kernel: meson-mmc: step:20, delay1:0x14514514, delay2:0x14014514 Sep 29 16:27:34 volumio kernel: meson-mmc: sd: adj_win: < 0 2 3 4 > Sep 29 16:27:34 volumio kernel: meson-mmc: left:3, right:0, mid:4, size:4 Sep 29 16:27:34 volumio kernel: meson-mmc: step:0, delay1:0x0, delay2:0x0 Sep 29 16:27:34 volumio kernel: meson-mmc: sd: sd_emmc_regs->gclock=0x10000245,sd_emmc_regs->gadjust=0x32000 Sep 29 16:27:34 volumio kernel: meson-mmc: delay1:0x0, delay2:0x0 Sep 29 16:27:34 volumio kernel: sd: new ultra high speed SDR104 SDHC card at address 59b4 Sep 29 16:27:34 volumio kernel: sd: clock 199999997, 4-bit-bus-width Sep 29 16:27:34 volumio kernel: mmcblk1: sd:59b4 USDU1 28.3 GiB Sep 29 16:27:34 volumio kernel: mmcblk1: p1 p2 p3 Sep 29 16:27:34 volumio kernel: usb 1-1: new high-speed USB device number 2 using xhci-hcd Sep 29 16:27:34 volumio kernel: usb 1-1: New USB device found, idVendor=05e3, idProduct=0610 Sep 29 16:27:34 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Sep 29 16:27:34 volumio kernel: usb 1-1: Product: USB2.1 Hub Sep 29 16:27:34 volumio kernel: usb 1-1: Manufacturer: GenesysLogic Sep 29 16:27:34 volumio kernel: hub 1-1:1.0: USB hub found Sep 29 16:27:34 volumio kernel: hub 1-1:1.0: 4 ports detected Sep 29 16:27:34 volumio kernel: usb 2-1: new SuperSpeed USB device number 2 using xhci-hcd Sep 29 16:27:34 volumio kernel: usb 2-1: New USB device found, idVendor=05e3, idProduct=0620 Sep 29 16:27:34 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Sep 29 16:27:34 volumio kernel: usb 2-1: Product: USB3.1 Hub Sep 29 16:27:34 volumio kernel: usb 2-1: Manufacturer: GenesysLogic Sep 29 16:27:34 volumio kernel: hub 2-1:1.0: USB hub found Sep 29 16:27:34 volumio kernel: hub 2-1:1.0: 4 ports detected Sep 29 16:27:34 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 29 16:27:34 volumio kernel: usb 1-1.3: new high-speed USB device number 3 using xhci-hcd Sep 29 16:27:34 volumio kernel: usb 1-1.3: New USB device found, idVendor=14cd, idProduct=125d Sep 29 16:27:34 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Sep 29 16:27:34 volumio kernel: usb 1-1.3: Product: Mass Storage Device Sep 29 16:27:34 volumio kernel: usb 1-1.3: Manufacturer: Generic Sep 29 16:27:34 volumio kernel: usb 1-1.3: SerialNumber: 125D20140310 Sep 29 16:27:34 volumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected Sep 29 16:27:34 volumio kernel: scsi host0: usb-storage 1-1.3:1.0 Sep 29 16:27:34 volumio kernel: scsi 0:0:0:0: Direct-Access Mass Storage Device PQ: 0 ANSI: 0 CCS Sep 29 16:27:34 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 29 16:27:34 volumio kernel: sd 0:0:0:0: [sda] 7716864 512-byte logical blocks: (3.95 GB/3.68 GiB) Sep 29 16:27:34 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 29 16:27:34 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 Sep 29 16:27:34 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Sep 29 16:27:34 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Sep 29 16:27:34 volumio kernel: sda: sda1 Sep 29 16:27:34 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Sep 29 16:27:34 volumio kernel: usb 1-1.3: reset high-speed USB device number 3 using xhci-hcd Sep 29 16:27:34 volumio kernel: usb 1-1.3: reset high-speed USB device number 3 using xhci-hcd Sep 29 16:27:34 volumio kernel: EXT4-fs (mmcblk1p2): recovery complete Sep 29 16:27:34 volumio kernel: EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null) Sep 29 16:27:34 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Sep 29 16:27:34 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Sep 29 16:27:34 volumio kernel: fb: malloc_osd_memory, cma:ffffff800a8a1978 Sep 29 16:27:34 volumio kernel: fb: malloc_osd_memory, 1231, base:0x000000007f800000, size:8388608 Sep 29 16:27:34 volumio kernel: fb: use ion buffer for fb memory, fb_index=1 Sep 29 16:27:34 volumio kernel: fb: OSD1 as afbcd mode,afbc_type=2 Sep 29 16:27:34 volumio kernel: meson-fb meson-fb: create ion_client ffffffc0bab74cc0, handle=ffffffc0282a2580 Sep 29 16:27:34 volumio kernel: meson-fb meson-fb: ion memory(1): created fb at 0x00000000ea400000, size 1 MiB Sep 29 16:27:34 volumio kernel: fb: 1, phy: 0x00000000ea400000, vir:0xffffff8008c41000, size=1024K Sep 29 16:27:34 volumio kernel: fb: Frame buffer memory assigned at Sep 29 16:27:34 volumio kernel: fb: 1, phy: 0x00000000ea400000, vir:0xffffff8008c41000, size=1024K Sep 29 16:27:34 volumio kernel: fb: 1, phy: 0x00000000ea400000, vir:0xffffff8008c41000, size=1024K Sep 29 16:27:34 volumio kernel: fb: logo_index=0,fb_index=1 Sep 29 16:27:34 volumio kernel: fb: ---------------clear fb1 memory ffffff8008c41000 Sep 29 16:27:34 volumio kernel: fb: osd[1] canvas.idx =0x43 Sep 29 16:27:34 volumio kernel: fb: osd[1] canvas.addr=0xea400000 Sep 29 16:27:34 volumio kernel: fb: osd[1] canvas.width=128 Sep 29 16:27:34 volumio kernel: fb: osd[1] canvas.height=32 Sep 29 16:27:34 volumio kernel: fb: osd[1] frame.width=32 Sep 29 16:27:34 volumio kernel: fb: osd[1] frame.height=32 Sep 29 16:27:34 volumio kernel: fb: osd[1] out_addr_id =0x2 Sep 29 16:27:34 volumio kernel: fb: malloc_osd_memory, cma:ffffff800a8a1978 Sep 29 16:27:34 volumio kernel: fb: malloc_osd_memory, 1231, base:0x000000007f800000, size:8388608 Sep 29 16:27:34 volumio kernel: fb: use ion buffer for fb memory, fb_index=2 Sep 29 16:27:34 volumio kernel: fb: OSD2 as afbcd mode,afbc_type=2 Sep 29 16:27:34 volumio kernel: meson-fb meson-fb: create ion_client ffffffc0bab74cc0, handle=ffffffc0282a26c0 Sep 29 16:27:34 volumio kernel: meson-fb meson-fb: ion memory(2): created fb at 0x00000000ea500000, size 1 MiB Sep 29 16:27:34 volumio kernel: fb: 2, phy: 0x00000000ea500000, vir:0xffffff8008d42000, size=1024K Sep 29 16:27:34 volumio kernel: fb: Frame buffer memory assigned at Sep 29 16:27:34 volumio kernel: fb: 2, phy: 0x00000000ea500000, vir:0xffffff8008d42000, size=1024K Sep 29 16:27:34 volumio kernel: fb: 2, phy: 0x00000000ea500000, vir:0xffffff8008d42000, size=1024K Sep 29 16:27:34 volumio kernel: fb: logo_index=0,fb_index=2 Sep 29 16:27:34 volumio kernel: fb: ---------------clear fb2 memory ffffff8008d42000 Sep 29 16:27:34 volumio kernel: fb: osd[2] canvas.idx =0x41 Sep 29 16:27:34 volumio kernel: fb: osd[2] canvas.addr=0xea500000 Sep 29 16:27:34 volumio kernel: fb: osd[2] canvas.width=128 Sep 29 16:27:34 volumio kernel: fb: osd[2] canvas.height=32 Sep 29 16:27:34 volumio kernel: fb: osd[2] frame.width=32 Sep 29 16:27:34 volumio kernel: fb: osd[2] frame.height=32 Sep 29 16:27:34 volumio kernel: fb: osd[2] out_addr_id =0x3 Sep 29 16:27:34 volumio kernel: fb: vpu clkc clock is 199 MHZ Sep 29 16:27:34 volumio kernel: fb: malloc_osd_memory, cma:ffffff800a8a1978 Sep 29 16:27:34 volumio kernel: fb: malloc_osd_memory, 1231, base:0x000000007f800000, size:8388608 Sep 29 16:27:34 volumio kernel: fb: use ion buffer for fb memory, fb_index=3 Sep 29 16:27:34 volumio kernel: fb: OSD3 as afbcd mode,afbc_type=2 Sep 29 16:27:34 volumio kernel: meson-fb meson-fb: create ion_client ffffffc0bab74cc0, handle=ffffffc0282a2800 Sep 29 16:27:34 volumio kernel: meson-fb meson-fb: ion memory(3): created fb at 0x00000000ea600000, size 8 MiB Sep 29 16:27:34 volumio kernel: fb: 3, phy: 0x00000000ea600000, vir:0xffffff800f4d0000, size=8192K Sep 29 16:27:34 volumio kernel: fb: Frame buffer memory assigned at Sep 29 16:27:34 volumio kernel: fb: 3, phy: 0x00000000ea600000, vir:0xffffff800f4d0000, size=8192K Sep 29 16:27:34 volumio kernel: fb: 3, phy: 0x00000000ea600000, vir:0xffffff800f4d0000, size=8192K Sep 29 16:27:34 volumio kernel: fb: logo_index=0,fb_index=3 Sep 29 16:27:34 volumio kernel: fb: ---------------clear fb3 memory ffffff800f4d0000 Sep 29 16:27:34 volumio kernel: fb: osd[3] canvas.idx =0x42 Sep 29 16:27:34 volumio kernel: fb: osd[3] canvas.addr=0xea600000 Sep 29 16:27:34 volumio kernel: fb: osd[3] canvas.width=128 Sep 29 16:27:34 volumio kernel: fb: osd[3] canvas.height=32 Sep 29 16:27:34 volumio kernel: fb: osd[3] frame.width=32 Sep 29 16:27:34 volumio kernel: fb: osd[3] frame.height=32 Sep 29 16:27:34 volumio kernel: fb: osd[3] out_addr_id =0x4 Sep 29 16:27:34 volumio kernel: EXT4-fs (mmcblk1p3): recovery complete Sep 29 16:27:34 volumio kernel: EXT4-fs (mmcblk1p3): mounted filesystem with ordered data mode. Opts: (null) Sep 29 16:27:34 volumio systemd[1]: System time before build time, advancing clock. Sep 29 16:27:34 volumio kernel: NET: Registered protocol family 10 Sep 29 16:27:34 volumio kernel: ip_tables: (C) 2000-2006 Netfilter Core Team Sep 29 16:27:34 volumio kernel: cgroup: cgroup2: unknown option "nsdelegate" Sep 29 16:27:34 volumio 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) Sep 29 16:27:34 volumio systemd[1]: Detected architecture arm64. Sep 29 16:27:34 volumio systemd[1]: Set hostname to . Sep 29 16:27:34 volumio kernel: cectx ff80023c.aocec: bad iniator with self 0x0 Sep 29 16:27:34 volumio systemd[1]: File /lib/systemd/system/systemd-journald.service:12 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling. Sep 29 16:27:34 volumio systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) Sep 29 16:27:34 volumio 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. Sep 29 16:27:34 volumio systemd[1]: Listening on fsck to fsckd communication Socket. Sep 29 16:27:34 volumio systemd[1]: Reached target System Time Synchronized. Sep 29 16:27:34 volumio systemd[1]: Listening on udev Control Socket. Sep 29 16:27:34 volumio systemd[1]: Listening on Journal Audit Socket. Sep 29 16:27:34 volumio kernel: Removed cp15_barrier emulation handler Sep 29 16:27:34 volumio kernel: Enabled cp15_barrier support Sep 29 16:27:34 volumio kernel: RPC: Registered named UNIX socket transport module. Sep 29 16:27:34 volumio kernel: RPC: Registered udp transport module. Sep 29 16:27:34 volumio kernel: RPC: Registered tcp transport module. Sep 29 16:27:34 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Sep 29 16:27:34 volumio systemd-journald[2134]: Journal started Sep 29 16:27:34 volumio systemd-journald[2134]: Runtime journal (/run/log/journal/dbae15dfd9f6eb5a44711553686bce20) is 3.7M, max 30.0M, 26.2M free. Sep 29 16:27:34 volumio fake-hwclock[2133]: Mon Sep 29 14:27:34 UTC 2025 Sep 29 16:27:34 volumio systemd[1]: Started Create System Users. Sep 29 16:27:34 volumio systemd[1]: Started udev Coldplug all Devices. Sep 29 16:27:34 volumio systemd[1]: Starting Helper to synchronize boot up for ifupdown... Sep 29 16:27:34 volumio systemd[1]: Starting Create Static Device Nodes in /dev... Sep 29 16:27:34 volumio systemd[1]: Started Helper to synchronize boot up for ifupdown. Sep 29 16:27:34 volumio systemd[1]: Started Create Static Device Nodes in /dev. Sep 29 16:27:34 volumio systemd[1]: Starting udev Kernel Device Manager... Sep 29 16:27:34 volumio systemd[1]: Reached target Local File Systems (Pre). Sep 29 16:27:34 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Sep 29 16:27:34 volumio systemd[1]: Mounting /var/log... Sep 29 16:27:34 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Sep 29 16:27:34 volumio systemd[1]: Mounting /tmp... Sep 29 16:27:34 volumio systemd[1]: Mounting /var/spool/cups... Sep 29 16:27:34 volumio systemd[1]: Mounted /var/log. Sep 29 16:27:34 volumio systemd[1]: Starting Flush Journal to Persistent Storage... Sep 29 16:27:34 volumio systemd-udevd[2160]: Network interface NamePolicy= disabled on kernel command line, ignoring. Sep 29 16:27:34 volumio systemd[1]: Mounted /tmp. Sep 29 16:27:34 volumio systemd[1]: Mounted /var/spool/cups. Sep 29 16:27:34 volumio systemd[1]: Mounting /var/spool/cups/tmp... Sep 29 16:27:34 volumio systemd[1]: Started udev Kernel Device Manager. Sep 29 16:27:34 volumio systemd[1]: Starting Show Plymouth Boot Screen... Sep 29 16:27:34 volumio systemd[1]: Mounted /var/spool/cups/tmp. Sep 29 16:27:34 volumio systemd-journald[2134]: Runtime journal (/run/log/journal/dbae15dfd9f6eb5a44711553686bce20) is 3.7M, max 30.0M, 26.2M free. Sep 29 16:27:34 volumio systemd[1]: Started Flush Journal to Persistent Storage. Sep 29 16:27:34 volumio systemd[1]: plymouth-start.service: Succeeded. Sep 29 16:27:34 volumio systemd[1]: Started Show Plymouth Boot Screen. Sep 29 16:27:34 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Sep 29 16:27:34 volumio systemd[1]: Reached target Local Encrypted Volumes. Sep 29 16:27:34 volumio systemd[1]: Reached target Paths. Sep 29 16:27:34 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Sep 29 16:27:34 volumio systemd-udevd[2173]: Using default interface naming scheme 'v240'. Sep 29 16:27:34 volumio systemd-udevd[2173]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 29 16:27:34 volumio systemd-udevd[2173]: link_config: could not get ethtool features for eth0 Sep 29 16:27:34 volumio systemd-udevd[2173]: Could not set offload features of eth0: Device or resource busy Sep 29 16:27:34 volumio systemd[1]: Starting Show Plymouth Boot Screen... Sep 29 16:27:34 volumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Sep 29 16:27:34 volumio systemd[1]: Condition check resulted in Huge Pages File System being skipped. Sep 29 16:27:34 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Sep 29 16:27:34 volumio systemd[1]: Condition check resulted in FUSE Control File System being skipped. Sep 29 16:27:34 volumio systemd-udevd[2181]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Sep 29 16:27:34 volumio systemd[1]: plymouth-start.service: Succeeded. Sep 29 16:27:34 volumio systemd[1]: Started Show Plymouth Boot Screen. Sep 29 16:27:34 volumio kernel: rtc-pcf8563 1-0051: rtc core: registered rtc-pcf8563 as rtc0 Sep 29 16:27:34 volumio kernel: ionvid: dbg: ionvideo open Sep 29 16:27:34 volumio kernel: ionvid: dbg: vidioc_close!!!! Sep 29 16:27:34 volumio kernel: ionvid: dbg: vidioc_close Sep 29 16:27:34 volumio kernel: ionvid: dbg: ionvideo open Sep 29 16:27:34 volumio kernel: ionvid: dbg: vidioc_close!!!! Sep 29 16:27:34 volumio kernel: ionvid: dbg: vidioc_close Sep 29 16:27:34 volumio kernel: ionvid: dbg: ionvideo open Sep 29 16:27:34 volumio kernel: ionvid: dbg: vidioc_close!!!! Sep 29 16:27:34 volumio kernel: ionvid: dbg: vidioc_close Sep 29 16:27:34 volumio kernel: ionvid: dbg: ionvideo open Sep 29 16:27:34 volumio kernel: ionvid: dbg: vidioc_close!!!! Sep 29 16:27:34 volumio kernel: ionvid: dbg: vidioc_close Sep 29 16:27:34 volumio kernel: ionvid: dbg: ionvideo open Sep 29 16:27:34 volumio kernel: ionvid: dbg: vidioc_close!!!! Sep 29 16:27:34 volumio kernel: ionvid: dbg: vidioc_close Sep 29 16:27:34 volumio kernel: ionvid: dbg: ionvideo open Sep 29 16:27:34 volumio kernel: ionvid: dbg: vidioc_close!!!! Sep 29 16:27:34 volumio kernel: ionvid: dbg: vidioc_close Sep 29 16:27:34 volumio kernel: ionvid: dbg: ionvideo open Sep 29 16:27:34 volumio kernel: ionvid: dbg: vidioc_close!!!! Sep 29 16:27:34 volumio kernel: ionvid: dbg: vidioc_close Sep 29 16:27:34 volumio kernel: ionvid: dbg: ionvideo open Sep 29 16:27:34 volumio kernel: ionvid: dbg: vidioc_close!!!! Sep 29 16:27:34 volumio kernel: ionvid: dbg: vidioc_close Sep 29 16:27:34 volumio kernel: ionvid: dbg: ionvideo open Sep 29 16:27:34 volumio kernel: ionvid: dbg: vidioc_close!!!! Sep 29 16:27:34 volumio kernel: ionvid: dbg: vidioc_close Sep 29 16:27:34 volumio systemd[1]: Found device /dev/ttyS0. Sep 29 16:27:34 volumio systemd[1]: Found device /dev/disk/by-uuid/EA72-F395. Sep 29 16:27:34 volumio kernel: out of value, fixed it Sep 29 16:27:34 volumio kernel: id=0 set inskew=0 Sep 29 16:27:34 volumio kernel: AED req_sel0 module:TDMOUT_B Sep 29 16:27:34 volumio kernel: spk_mute_set: mute flag = 0 Sep 29 16:27:34 volumio systemd[1]: Starting File System Check on /dev/disk/by-uuid/EA72-F395... Sep 29 16:27:34 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Sep 29 16:27:34 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Sep 29 16:27:34 volumio systemd-udevd[2193]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 29 16:27:34 volumio systemd-udevd[2170]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Sep 29 16:27:34 volumio systemd[1]: Found device /sys/subsystem/net/devices/eth0. Sep 29 16:27:34 volumio systemd[1]: Started File System Check Daemon to report status. Sep 29 16:27:34 volumio systemd-fsck[2252]: fsck.fat 4.1 (2017-01-24) Sep 29 16:27:34 volumio systemd-fsck[2252]: /dev/mmcblk1p1: 35 files, 44239/120958 clusters Sep 29 16:27:34 volumio systemd[1]: Started File System Check on /dev/disk/by-uuid/EA72-F395. Sep 29 16:27:34 volumio systemd[1]: Mounting /boot... Sep 29 16:27:34 volumio systemd[1]: Mounted /boot. Sep 29 16:27:34 volumio systemd[1]: Reached target Local File Systems. Sep 29 16:27:34 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Sep 29 16:27:34 volumio systemd[1]: Starting Raise network interfaces... Sep 29 16:27:34 volumio systemd[1]: Starting Preprocess NFS configuration... Sep 29 16:27:34 volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Sep 29 16:27:34 volumio systemd[1]: Started ifup for eth0. Sep 29 16:27:34 volumio systemd[1]: Starting Create Volatile Files and Directories... Sep 29 16:27:34 volumio systemd[1]: Received SIGRTMIN+20 from PID 2013 (plymouthd). Sep 29 16:27:34 volumio systemd[1]: plymouth-read-write.service: Succeeded. Sep 29 16:27:34 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Sep 29 16:27:34 volumio systemd[1]: nfs-config.service: Succeeded. Sep 29 16:27:34 volumio systemd[1]: Started Preprocess NFS configuration. Sep 29 16:27:34 volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Sep 29 16:27:34 volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Sep 29 16:27:34 volumio systemd[1]: Reached target NFS client services. Sep 29 16:27:34 volumio ifup[2259]: command failed: No such device (-19) Sep 29 16:27:34 volumio sh[2263]: command failed: No such device (-19) Sep 29 16:27:34 volumio systemd[1]: Started Create Volatile Files and Directories. Sep 29 16:27:34 volumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Sep 29 16:27:34 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Sep 29 16:27:34 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Sep 29 16:27:34 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Sep 29 16:27:34 volumio systemd[1]: Starting RPC bind portmap service... Sep 29 16:27:34 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Sep 29 16:27:34 volumio systemd[1]: Reached target System Initialization. Sep 29 16:27:34 volumio systemd[1]: Started Daily apt download activities. Sep 29 16:27:34 volumio systemd[1]: Started Daily apt upgrade and clean activities. Sep 29 16:27:34 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Sep 29 16:27:34 volumio systemd[1]: Reached target Timers. Sep 29 16:27:34 volumio systemd[1]: Listening on triggerhappy.socket. Sep 29 16:27:34 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Sep 29 16:27:34 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Sep 29 16:27:34 volumio systemd[1]: Listening on lircd.socket. Sep 29 16:27:34 volumio systemd[1]: Reached target Sockets. Sep 29 16:27:34 volumio systemd[1]: Reached target Basic System. Sep 29 16:27:34 volumio systemd[1]: Starting Volumio Time Update Utility... Sep 29 16:27:34 volumio systemd[1]: Started Manage Sound Card State (restore and store). Sep 29 16:27:34 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Sep 29 16:27:34 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Sep 29 16:27:34 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 29 16:27:34 volumio systemd[1]: Started D-Bus System Message Bus. Sep 29 16:27:34 volumio alsactl[2319]: alsactl 1.1.8 daemon started Sep 29 16:27:34 volumio systemd[1]: Starting Login Service... Sep 29 16:27:34 volumio systemd[1]: Started Volumio Iptables Module. Sep 29 16:27:34 volumio alsactl[2319]: /usr/sbin/alsactl: set_control:1461Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Sep 29 16:27:34 volumio kernel: vout: aml_tvout_mode_work: monitor_timeout Sep 29 16:27:34 volumio kernel: out of value, fixed it Sep 29 16:27:34 volumio kernel: id=0 set inskew=0 Sep 29 16:27:34 volumio kernel: AED req_sel0 module:TDMOUT_B Sep 29 16:27:34 volumio kernel: spk_mute_set: mute flag = 0 Sep 29 16:27:34 volumio alsactl[2319]: /usr/sbin/alsactl: set_control:1461Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Sep 29 16:27:34 volumio alsactl[2319]: /usr/sbin/alsactl: set_control:1461Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Sep 29 16:27:34 volumio volumio-time-update[2318]: volumio-time-update-util: Fetching time from Volumio... Sep 29 16:27:34 volumio systemd[1]: Started MPD Monitor Service. Sep 29 16:27:34 volumio systemd[1]: Starting WPA supplicant... Sep 29 16:27:34 volumio systemd[1]: Starting lircd(8) initialization helper tool... Sep 29 16:27:34 volumio mpd_monitor.sh[2329]: MPD Monitor Service: Starting MPD Monitor Service Sep 29 16:27:34 volumio ifup[2259]: command failed: No such device (-19) Sep 29 16:27:34 volumio systemd[1]: Starting triggerhappy global hotkey daemon... Sep 29 16:27:34 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Sep 29 16:27:34 volumio systemd[1]: Starting DHCP Client Daemon... Sep 29 16:27:34 volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Sep 29 16:27:34 volumio systemd[1]: Started Volumio Log Rotation Service. Sep 29 16:27:34 volumio systemd[1]: Starting Save/Restore Sound Card State... Sep 29 16:27:34 volumio thd[2338]: Unable to parse trigger line: Sep 29 16:27:34 volumio thd[2338]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Sep 29 16:27:34 volumio thd[2338]: Unable to parse trigger line: Sep 29 16:27:34 volumio thd[2338]: Found socket passed from systemd Sep 29 16:27:34 volumio systemd[1]: Starting Wireless Services... Sep 29 16:27:34 volumio systemd[1]: Started volumio-remote-updater.service. Sep 29 16:27:34 volumio systemd[1]: Started triggerhappy global hotkey daemon. Sep 29 16:27:35 volumio systemd-logind[2325]: Watching system buttons on /dev/input/event0 (gpio_keypad) Sep 29 16:27:35 volumio systemd-logind[2325]: Watching system buttons on /dev/input/event1 (cec_input) Sep 29 16:27:35 volumio systemd-logind[2325]: New seat seat0. Sep 29 16:27:35 volumio kernel: meson6-dwmac ff3f0000.ethernet eth0: fail to init PTP. Sep 29 16:27:35 volumio kernel: out of value, fixed it Sep 29 16:27:35 volumio kernel: id=0 set inskew=0 Sep 29 16:27:35 volumio kernel: AED req_sel0 module:TDMOUT_B Sep 29 16:27:35 volumio kernel: spk_mute_set: mute flag = 0 Sep 29 16:27:35 volumio dhcpcd[2293]: eth0: waiting for carrier Sep 29 16:27:35 volumio alsactl[2343]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Sep 29 16:27:35 volumio alsactl[2343]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Sep 29 16:27:35 volumio alsactl[2343]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Sep 29 16:27:35 volumio sh[2263]: eth0: waiting for carrier Sep 29 16:27:35 volumio sh[2263]: eth0: carrier acquired Sep 29 16:27:35 volumio dhcpcd[2293]: eth0: carrier acquired Sep 29 16:27:35 volumio avahi-daemon[2320]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Sep 29 16:27:35 volumio mpd_monitor.sh[2329]: MPD error: Cannot assign requested address Sep 29 16:27:35 volumio avahi-daemon[2320]: Successfully dropped root privileges. Sep 29 16:27:35 volumio mpd_monitor.sh[2329]: MPD Monitor Service: MPD Appears to be inactive, restarting Sep 29 16:27:35 volumio avahi-daemon[2320]: avahi-daemon 0.7 starting up. Sep 29 16:27:35 volumio systemd[1]: Started Login Service. Sep 29 16:27:35 volumio sh[2263]: DUID 00:01:00:01:2f:fe:8b:1a:00:1e:06:42:a3:da Sep 29 16:27:35 volumio sh[2263]: eth0: IAID 06:42:a3:da Sep 29 16:27:35 volumio dhcpcd[2293]: DUID 00:01:00:01:2f:fe:8b:1a:00:1e:06:42:a3:da Sep 29 16:27:35 volumio dhcpcd[2293]: eth0: IAID 06:42:a3:da Sep 29 16:27:35 volumio systemd[1]: Started RPC bind portmap service. Sep 29 16:27:35 volumio systemd[1]: Started Save/Restore Sound Card State. Sep 29 16:27:35 volumio sh[2263]: eth0: adding address fe80::5944:9b7c:4e9f:2ee1 Sep 29 16:27:35 volumio sh[2263]: ipv6_addaddr1: Permission denied Sep 29 16:27:35 volumio sh[2263]: eth0: carrier lost Sep 29 16:27:35 volumio dhcpcd[2293]: eth0: adding address fe80::5944:9b7c:4e9f:2ee1 Sep 29 16:27:35 volumio dhcpcd[2293]: ipv6_addaddr1: Permission denied Sep 29 16:27:35 volumio dhcpcd[2293]: eth0: carrier lost Sep 29 16:27:35 volumio systemd[1]: Reached target Sound Card. Sep 29 16:27:35 volumio systemd[1]: Reached target RPC Port Mapper. Sep 29 16:27:35 volumio systemd[1]: Reached target Remote File Systems (Pre). Sep 29 16:27:35 volumio systemd[1]: Reached target Remote File Systems. Sep 29 16:27:35 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Sep 29 16:27:35 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Sep 29 16:27:35 volumio avahi-daemon[2320]: Successfully called chroot(). Sep 29 16:27:35 volumio avahi-daemon[2320]: Successfully dropped remaining capabilities. Sep 29 16:27:35 volumio avahi-daemon[2320]: No service file found in /etc/avahi/services. Sep 29 16:27:35 volumio avahi-daemon[2320]: Network interface enumeration completed. Sep 29 16:27:35 volumio avahi-daemon[2320]: Server startup complete. Host name is volumio.local. Local service cookie is 1671707600. Sep 29 16:27:35 volumio dhcpcd[2340]: no interfaces have a carrier Sep 29 16:27:35 volumio dhcpcd[2340]: no interfaces have a carrier Sep 29 16:27:35 volumio sh[2263]: cat: /run/dhcpcd/resolv.conf.eth0.link: No such file or directory Sep 29 16:27:35 volumio dhcpcd[2340]: forked to background, child pid 2370 Sep 29 16:27:35 volumio dhcpcd[2340]: forked to background, child pid 2370 Sep 29 16:27:35 volumio systemd[1]: Started DHCP Client Daemon. Sep 29 16:27:35 volumio dhcpcd[2370]: eth0: waiting for carrier Sep 29 16:27:35 volumio dhcpcd[2370]: eth0: carrier acquired Sep 29 16:27:35 volumio dhcpcd[2370]: DUID 00:01:00:01:2f:fe:8b:1a:00:1e:06:42:a3:da Sep 29 16:27:35 volumio dhcpcd[2370]: eth0: IAID 06:42:a3:da Sep 29 16:27:35 volumio dhcpcd[2370]: eth0: adding address fe80::5944:9b7c:4e9f:2ee1 Sep 29 16:27:35 volumio dhcpcd[2370]: ipv6_addaddr1: Permission denied Sep 29 16:27:35 volumio dhcpcd[2370]: eth0: carrier lost Sep 29 16:27:35 volumio mpd_monitor.sh[2329]: mpd: no process found Sep 29 16:27:35 volumio systemd[1]: Listening on mpd.socket. Sep 29 16:27:35 volumio kernel: nf_tables: (c) 2007-2009 Patrick McHardy Sep 29 16:27:35 volumio kernel: nf_tables_compat: (c) 2012 Pablo Neira Ayuso Sep 29 16:27:35 volumio systemd[1]: Started Raise network interfaces. Sep 29 16:27:35 volumio kernel: nf_conntrack version 0.5.0 (16384 buckets, 65536 max) Sep 29 16:27:35 volumio systemd[1]: Started WPA supplicant. Sep 29 16:27:35 volumio systemd[1]: Reached target Network. Sep 29 16:27:35 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Sep 29 16:27:35 volumio wpa_supplicant[2335]: Successfully initialized wpa_supplicant Sep 29 16:27:35 volumio systemd[1]: Starting Network Time Service... Sep 29 16:27:35 volumio systemd[1]: Starting Music Player Daemon... Sep 29 16:27:35 volumio systemd[1]: Starting Permit User Sessions... Sep 29 16:27:35 volumio systemd[1]: Reached target Network is Online. Sep 29 16:27:35 volumio volumio-time-update[2318]: volumio-time-update-util: Date not found in response Sep 29 16:27:35 volumio volumio-time-update[2318]: volumio-time-update-util: Retrying in 5 seconds... Sep 29 16:27:35 volumio volumio-remote-updater[2346]: Error: No active session Sep 29 16:27:35 volumio systemd[1]: Starting /etc/rc.local Compatibility... Sep 29 16:27:35 volumio volumio-remote-updater[2346]: [2025-09-29 16:27:35] [info] asio async_connect error: system:111 (Connection refused) Sep 29 16:27:35 volumio volumio-remote-updater[2346]: [2025-09-29 16:27:35] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 29 16:27:35 volumio volumio-remote-updater[2346]: [2025-09-29 16:27:35] [error] handle_connect error: Underlying Transport Error Sep 29 16:27:35 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Sep 29 16:27:35 volumio systemd[1]: Starting Samba NMB Daemon... Sep 29 16:27:35 volumio ntpd[2457]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 29 16:27:35 volumio ntpd[2457]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 29 16:27:35 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 29 16:27:35 volumio volumio[2322]: Could not open config: /tmp/upmpdcli.conf Sep 29 16:27:35 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Sep 29 16:27:35 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Sep 29 16:27:35 volumio systemd[1]: Started Network Time Service. Sep 29 16:27:35 volumio systemd[1]: Started Permit User Sessions. Sep 29 16:27:35 volumio ntpd[2486]: proto: precision = 0.458 usec (-21) Sep 29 16:27:35 volumio systemd[1]: Started /etc/rc.local Compatibility. Sep 29 16:27:35 volumio ntpd[2486]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 29 16:27:35 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Sep 29 16:27:35 volumio ntpd[2486]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 29 16:27:35 volumio ntpd[2486]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1007 days ago Sep 29 16:27:35 volumio ntpd[2486]: Listen and drop on 0 v6wildcard [::]:123 Sep 29 16:27:35 volumio systemd[1]: Starting Hold until boot process finishes up... Sep 29 16:27:35 volumio ntpd[2486]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 29 16:27:35 volumio ntpd[2486]: Listen normally on 2 lo 127.0.0.1:123 Sep 29 16:27:35 volumio ntpd[2486]: Listening on routing socket on fd #19 for interface updates Sep 29 16:27:35 volumio ntpd[2486]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 29 16:27:35 volumio ntpd[2486]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 29 16:27:35 volumio sudo[2459]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 29 16:27:35 volumio sudo[2459]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:27:35 volumio sudo[2459]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 29 16:27:35 volumio sudo[2459]: pam_unix(sudo:session): session closed for user root Sep 29 16:27:35 volumio loadcpufreq[2361]: Loading cpufreq kernel modules...done (none). Sep 29 16:27:35 volumio ifplugd(eth0)[2518]: ifplugd 0.28 initializing. Sep 29 16:27:35 volumio ifplugd(eth0)[2518]: Using interface eth0/00:1E:06:42:A3:DA with driver (version: Jan_2016) Sep 29 16:27:35 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Sep 29 16:27:35 volumio ifplugd(eth0)[2518]: Using detection mode: SIOCETHTOOL Sep 29 16:27:35 volumio ifplugd(eth0)[2518]: Initialization complete, link beat not detected. Sep 29 16:27:35 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Sep 29 16:27:35 volumio systemd[1]: Received SIGRTMIN+21 from PID 2013 (plymouthd). Sep 29 16:27:35 volumio ifplugd[2479]: Network Interface Plugging Daemon...start eth0...done. Sep 29 16:27:35 volumio lircd-setup[2337]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Sep 29 16:27:35 volumio lircd-setup[2337]: parser = configparser.SafeConfigParser() Sep 29 16:27:35 volumio systemd[1]: Started LSB: Brings up/down network automatically. Sep 29 16:27:35 volumio systemd[1]: lircd-setup.service: Succeeded. Sep 29 16:27:35 volumio systemd[1]: Started lircd(8) initialization helper tool. Sep 29 16:27:35 volumio systemd[1]: Starting Flexible IR remote input/output application support... Sep 29 16:27:35 volumio systemd[1]: plymouth-quit.service: Succeeded. Sep 29 16:27:35 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Sep 29 16:27:35 volumio kernel: fb: osd_release now.index=3,open_count=1 Sep 29 16:27:35 volumio kernel: fb: osd_release now.index=2,open_count=1 Sep 29 16:27:35 volumio kernel: fb: osd_release now.index=1,open_count=1 Sep 29 16:27:35 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Sep 29 16:27:35 volumio systemd[1]: Started Hold until boot process finishes up. Sep 29 16:27:35 volumio systemd[1]: Received SIGRTMIN+21 from PID 2013 (n/a). Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Info: lircd: Opening log, level: Info Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Version: lircd 0.10.1 Sep 29 16:27:35 volumio systemd[1]: Started Serial Getty on ttyS0. Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: System info: Linux volumio 4.9.241+ #8 SMP PREEMPT Sat Jan 21 21:01:33 CET 2023 aarch64 GNU/Linux Sep 29 16:27:35 volumio systemd[1]: Started Getty on tty1. Sep 29 16:27:35 volumio systemd[1]: Reached target Login Prompts. Sep 29 16:27:35 volumio cpufrequtils[2521]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...CPU4...CPU5...done. Sep 29 16:27:35 volumio lircd[2524]: lircd-0.10.1[2524]: Info: lircd: Opening log, level: Info Sep 29 16:27:35 volumio lircd[2524]: lircd-0.10.1[2524]: Notice: Using systemd fd Sep 29 16:27:35 volumio lircd[2524]: lircd-0.10.1[2524]: Warning: Running as root Sep 29 16:27:35 volumio lircd[2524]: lircd-0.10.1[2524]: Info: Using remote: odroid. Sep 29 16:27:35 volumio lircd[2524]: lircd-0.10.1[2524]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Info: Initial device: auto Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Info: Initial device: auto Sep 29 16:27:35 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Options: driver: devinput Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Options: output: /var/run/lirc/lircd Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Options: nodaemon: 1 Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Options: logfile: syslog Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Options: immediate-init: 0 Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Options: permission: 666 Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Options: driver-options: Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Options: listen: 0 Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Options: connect: (null) Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Options: userelease: 0 Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Options: effective_user: (null) Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Options: release_suffix: _EVUP Sep 29 16:27:35 volumio systemd[1]: Started Flexible IR remote input/output application support. Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Options: allow_simulate: 0 Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Options: repeat_max: 600 Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Options: configfile: /etc/lirc/lircd.conf Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Options: dynamic_codes: (null) Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Current driver: devinput Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Driver API version: 4 Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Driver version: 0.10.0 Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/devinput.html Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Info: lircd: Opening log, level: Info Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: Using systemd fd Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Warning: Running as root Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Info: Using remote: odroid. Sep 29 16:27:35 volumio lircd-0.10.1[2524]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd Sep 29 16:27:35 volumio haveged[2298]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.3.0 CTV); collect: 128K Sep 29 16:27:35 volumio haveged[2298]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 12/40; sz: 15012/57848 Sep 29 16:27:35 volumio haveged[2298]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99958 Sep 29 16:27:35 volumio haveged[2298]: haveged: fills: 0, generated: 0 Sep 29 16:27:35 volumio nmbd[2495]: [2025/09/29 16:27:35.773359, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Sep 29 16:27:35 volumio nmbd[2495]: started asyncdns process 2548 Sep 29 16:27:35 volumio nmbd[2495]: [2025/09/29 16:27:35.774820, 0] ../lib/util/become_daemon.c:149(daemon_status) Sep 29 16:27:35 volumio nmbd[2495]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Sep 29 16:27:35 volumio nmbd[2495]: [2025/09/29 16:27:35.774930, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Sep 29 16:27:35 volumio nmbd[2495]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Sep 29 16:27:36 volumio systemd[1]: iptables.service: Succeeded. Sep 29 16:27:36 volumio wireless.js[2344]: WIRELESS: No wireless interface, exiting Sep 29 16:27:36 volumio iw[2557]: command failed: No such device (-19) Sep 29 16:27:36 volumio iwconfig[2560]: Error for wireless request "Set Power Management" (8B2C) : Sep 29 16:27:36 volumio iwconfig[2560]: SET failed on device wlan0 ; No such device. Sep 29 16:27:36 volumio systemd[1]: wireless.service: Succeeded. Sep 29 16:27:36 volumio systemd[1]: Started Wireless Services. Sep 29 16:27:36 volumio systemd[1]: Started Volumio Backend Module. Sep 29 16:27:36 volumio systemd[1]: Started Volumio Cpu Tweaker. Sep 29 16:27:36 volumio volumio-cpu-tweak[2564]: Setting RT Priority for mpd Sep 29 16:27:36 volumio volumio-cpu-tweak[2564]: Setting MPD Affinity Sep 29 16:27:36 volumio volumio-cpu-tweak[2564]: pid 2520's current affinity mask: 3f Sep 29 16:27:36 volumio volumio-cpu-tweak[2564]: pid 2520's new affinity mask: 3 Sep 29 16:27:36 volumio volumio-cpu-tweak[2564]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Sep 29 16:27:36 volumio volumio-cpu-tweak[2564]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Sep 29 16:27:36 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Sep 29 16:27:36 volumio ntpd[2486]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Sep 29 16:27:37 volumio volumio[2563]: info: ------------------------------------------- Sep 29 16:27:37 volumio volumio[2563]: info: ----- Volumio3 ---- Sep 29 16:27:37 volumio volumio[2563]: info: ------------------------------------------- Sep 29 16:27:37 volumio volumio[2563]: info: ----- System startup ---- Sep 29 16:27:37 volumio volumio[2563]: info: ------------------------------------------- Sep 29 16:27:37 volumio ntpd[2486]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Sep 29 16:27:37 volumio mpd[2520]: Sep 29 16:27 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 29 16:27:37 volumio systemd[1]: Started Music Player Daemon. Sep 29 16:27:37 volumio mpd_monitor.sh[2329]: MPD Monitor Service: MPD restarted due to no mpc output. Sep 29 16:27:37 volumio volumio[2563]: info: MYVOLUMIO Environment detected Sep 29 16:27:37 volumio volumio[2563]: info: Plugin folders cleanup Sep 29 16:27:37 volumio volumio[2563]: info: Scanning into folder /volumio/app/plugins/ Sep 29 16:27:37 volumio volumio[2563]: info: Scanning category audio_interface Sep 29 16:27:37 volumio volumio[2563]: info: Scanning category miscellanea Sep 29 16:27:37 volumio volumio[2563]: info: Scanning category music_service Sep 29 16:27:37 volumio volumio[2563]: info: Scanning category plugins.json Sep 29 16:27:37 volumio volumio[2563]: info: Scanning category system_controller Sep 29 16:27:37 volumio volumio[2563]: info: Scanning category user_interface Sep 29 16:27:37 volumio volumio[2563]: info: Scanning into folder /data/plugins/ Sep 29 16:27:37 volumio volumio[2563]: info: Plugin folders cleanup completed Sep 29 16:27:37 volumio volumio[2563]: info: ------------------------------------------- Sep 29 16:27:37 volumio volumio[2563]: info: ----- Core plugins startup ---- Sep 29 16:27:37 volumio volumio[2563]: info: ------------------------------------------- Sep 29 16:27:37 volumio volumio[2563]: info: Loading plugins from folder /volumio/app/plugins/ Sep 29 16:27:37 volumio volumio[2563]: info: Adding plugin upnp to MyMusic Plugins Sep 29 16:27:37 volumio volumio[2563]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 29 16:27:37 volumio volumio[2563]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 29 16:27:37 volumio volumio[2563]: info: Loading plugins from folder /data/plugins/ Sep 29 16:27:37 volumio volumio[2563]: info: Loading plugin "system"... Sep 29 16:27:37 volumio volumio[2563]: info: Loading plugin "appearance"... Sep 29 16:27:38 volumio ntpd[2486]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 29 16:27:38 volumio volumio[2563]: info: Loading plugin "network"... Sep 29 16:27:38 volumio volumio[2563]: info: Refreshing Cached IP Addresses Sep 29 16:27:38 volumio sudo[2597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 29 16:27:38 volumio sudo[2597]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:27:38 volumio sudo[2600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 29 16:27:38 volumio sudo[2597]: pam_unix(sudo:session): session closed for user root Sep 29 16:27:38 volumio sudo[2600]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:27:38 volumio volumio[2563]: info: Loading plugin "services"... Sep 29 16:27:38 volumio sudo[2600]: pam_unix(sudo:session): session closed for user root Sep 29 16:27:38 volumio volumio[2563]: info: Loading plugin "alsa_controller"... Sep 29 16:27:38 volumio sudo[2610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 29 16:27:38 volumio sudo[2610]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:27:38 volumio sudo[2610]: pam_unix(sudo:session): session closed for user root Sep 29 16:27:38 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 29 16:27:38 volumio volumio[2563]: info: Loading plugin "wizard"... Sep 29 16:27:38 volumio volumio[2563]: info: Loading plugin "networkfs"... Sep 29 16:27:38 volumio volumio[2563]: info: Starting Udev Watcher for removable devices Sep 29 16:27:38 volumio volumio[2563]: info: Mounting Device HD9 Sep 29 16:27:38 volumio sudo[2631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/HD9 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 29 16:27:38 volumio sudo[2631]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:27:38 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Sep 29 16:27:38 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Sep 29 16:27:38 volumio sudo[2631]: pam_unix(sudo:session): session closed for user root Sep 29 16:27:38 volumio volumio[2563]: info: Ignoring mount for partition: boot Sep 29 16:27:38 volumio volumio[2563]: info: Ignoring mount for partition: volumio Sep 29 16:27:38 volumio volumio[2563]: info: Ignoring mount for partition: volumio_data Sep 29 16:27:38 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 29 16:27:38 volumio volumio[2563]: info: Loading plugin "volumio_command_line_client"... Sep 29 16:27:38 volumio volumio[2563]: info: Loading plugin "upnp"... Sep 29 16:27:38 volumio volumio[2563]: info: [1759156058869] Starting Upmpd Daemon Sep 29 16:27:38 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 29 16:27:38 volumio volumio[2563]: info: Loading plugin "my_music"... Sep 29 16:27:38 volumio volumio[2563]: info: Loading plugin "mpd"... Sep 29 16:27:39 volumio volumio[2563]: info: Loading plugin "upnp_browser"... Sep 29 16:27:39 volumio kernel: meson6-dwmac ff3f0000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Sep 29 16:27:39 volumio sh[2263]: eth0: carrier acquired Sep 29 16:27:39 volumio dhcpcd[2293]: eth0: carrier acquired Sep 29 16:27:39 volumio dhcpcd[2370]: eth0: carrier acquired Sep 29 16:27:39 volumio dhcpcd[2370]: eth0: IAID 06:42:a3:da Sep 29 16:27:39 volumio sh[2263]: eth0: IAID 06:42:a3:da Sep 29 16:27:39 volumio dhcpcd[2293]: eth0: IAID 06:42:a3:da Sep 29 16:27:39 volumio dhcpcd[2370]: eth0: soliciting an IPv6 router Sep 29 16:27:39 volumio volumio[2563]: info: Loading plugin "alarm-clock"... Sep 29 16:27:39 volumio volumio[2563]: info: Loading plugin "airplay_emulation"... Sep 29 16:27:39 volumio volumio[2563]: info: Starting Shairport Sync Sep 29 16:27:39 volumio volumio[2563]: info: Loading plugin "last_100"... Sep 29 16:27:39 volumio volumio[2563]: info: Loading plugin "webradio"... Sep 29 16:27:39 volumio sh[2263]: eth0: soliciting an IPv6 router Sep 29 16:27:39 volumio dhcpcd[2293]: eth0: soliciting an IPv6 router Sep 29 16:27:39 volumio ntpd[2486]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 29 16:27:39 volumio volumio[2563]: info: Loading plugin "i2s_dacs"... Sep 29 16:27:39 volumio volumio[2563]: info: I2S DAC not set, start Auto-detection Sep 29 16:27:39 volumio volumio[2563]: info: Loading plugin "volumiodiscovery"... Sep 29 16:27:39 volumio volumio[2563]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 29 16:27:39 volumio volumio[2563]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 29 16:27:39 volumio volumio[2563]: *** WARNING *** For more information see Sep 29 16:27:39 volumio volumio[2563]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 29 16:27:39 volumio volumio[2563]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 29 16:27:39 volumio node[2563]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 29 16:27:39 volumio volumio[2563]: *** WARNING *** For more information see Sep 29 16:27:39 volumio node[2563]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 29 16:27:39 volumio node[2563]: *** WARNING *** For more information see Sep 29 16:27:39 volumio node[2563]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 29 16:27:39 volumio node[2563]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 29 16:27:39 volumio node[2563]: *** WARNING *** For more information see Sep 29 16:27:39 volumio volumio[2563]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 29 16:27:39 volumio volumio[2563]: info: Discovery: Started advertising with name: Volumio Sep 29 16:27:39 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 29 16:27:39 volumio volumio[2563]: info: Loading plugin "outputs"... Sep 29 16:27:39 volumio volumio[2563]: info: Loading plugin "albumart"... Sep 29 16:27:39 volumio volumio[2563]: info: Plugin example_plugin is not enabled Sep 29 16:27:39 volumio volumio[2563]: info: Loading plugin "inputs"... Sep 29 16:27:39 volumio volumio[2563]: info: Loading plugin "updater_comm"... Sep 29 16:27:39 volumio ifplugd(eth0)[2518]: Link beat detected. Sep 29 16:27:39 volumio volumio[2563]: info: Plugin mpdemulation is not enabled Sep 29 16:27:39 volumio volumio[2563]: info: Loading plugin "rest_api"... Sep 29 16:27:39 volumio sh[2263]: eth0: soliciting a DHCP lease Sep 29 16:27:39 volumio dhcpcd[2293]: eth0: soliciting a DHCP lease Sep 29 16:27:39 volumio volumio[2563]: info: Loading plugin "websocket"... Sep 29 16:27:39 volumio volumio[2563]: info: Starting Socket.io Server version 2.3.0 Sep 29 16:27:39 volumio volumio[2563]: info: Loading i18n strings for locale en Sep 29 16:27:39 volumio volumio[2563]: Updating browse sources language Sep 29 16:27:39 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 29 16:27:39 volumio volumio[2563]: Forking 5 albumart workers Sep 29 16:27:39 volumio ifplugd(eth0)[2518]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Sep 29 16:27:39 volumio ifplugd(eth0)[2518]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Sep 29 16:27:39 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 29 16:27:39 volumio volumio[2563]: info: CoreCommandRouter::initPlayerControls Sep 29 16:27:39 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:39 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:39 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:39 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:39 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:39 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:39 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:39 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:39 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 29 16:27:39 volumio volumio[2563]: Express server listening on port 3000 Sep 29 16:27:39 volumio volumio[2563]: [Metrics] WebUI: 2s 863.70ms Sep 29 16:27:39 volumio volumio[2563]: info: CoreStateMachine::resetVolumioState Sep 29 16:27:39 volumio volumio[2563]: info: CoreStateMachine::getcurrentVolume Sep 29 16:27:39 volumio volumio[2563]: info: CoreCommandRouter::volumioRetrievevolume Sep 29 16:27:39 volumio volumio[2563]: info: CoreStateMachine::pushState Sep 29 16:27:39 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:27:39 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 29 16:27:39 volumio volumio[2563]: info: CoreCommandRouter::volumioPushState Sep 29 16:27:39 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 29 16:27:39 volumio volumio[2563]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 29 16:27:39 volumio volumio[2563]: info: Completed loading Core Plugins Sep 29 16:27:39 volumio volumio[2563]: info: Preparing to generate the ALSA configuration file Sep 29 16:27:39 volumio dhcpcd[2370]: eth0: soliciting a DHCP lease Sep 29 16:27:39 volumio volumio[2563]: info: Volumio Network Manager: Network status updated: 0 Sep 29 16:27:39 volumio volumio[2563]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 29 16:27:39 volumio volumio[2563]: wlan0 Interface doesn't support scanning. Sep 29 16:27:39 volumio volumio[2563]: info: Cannot use regular scanning, forcing with ap-force Sep 29 16:27:39 volumio sudo[2690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 29 16:27:39 volumio sudo[2690]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:27:39 volumio sudo[2690]: pam_unix(sudo:session): session closed for user root Sep 29 16:27:39 volumio volumio[2563]: command failed: No such device (-19) Sep 29 16:27:39 volumio volumio[2563]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 29 16:27:39 volumio volumio[2563]: command failed: No such device (-19) Sep 29 16:27:39 volumio volumio[2563]: info: Asound.conf file unchanged, so no further update is needed Sep 29 16:27:40 volumio volumio[2563]: info: Output device has changed, restarting MPD Sep 29 16:27:40 volumio sudo[2700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 29 16:27:40 volumio volumio[2563]: info: Output device has changed, restarting Shairport Sync Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 29 16:27:40 volumio sudo[2700]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:27:40 volumio sudo[2700]: pam_unix(sudo:session): session closed for user root Sep 29 16:27:40 volumio sudo[2701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 29 16:27:40 volumio sudo[2701]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:27:40 volumio volumio[2563]: Starting albumart workers Sep 29 16:27:40 volumio volumio[2563]: Starting albumart workers Sep 29 16:27:40 volumio volumio[2563]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 29 16:27:40 volumio volumio[2563]: info: ___________ START PLUGINS ___________ Sep 29 16:27:40 volumio volumio[2563]: info: ControllerMpd::onStart: Initializing MPD Sep 29 16:27:40 volumio volumio[2563]: info: Creating MPD Configuration file Sep 29 16:27:40 volumio systemd[1]: Stopping Music Player Daemon... Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 29 16:27:40 volumio volumio[2563]: info: [1759156060133] CoreMusicLibrary::Adding element Media Servers Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 29 16:27:40 volumio sudo[2709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 29 16:27:40 volumio sudo[2709]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:27:40 volumio sudo[2710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 29 16:27:40 volumio sudo[2710]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:27:40 volumio sudo[2709]: pam_unix(sudo:session): session closed for user root Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: Starting albumart workers Sep 29 16:27:40 volumio volumio[2563]: Starting albumart workers Sep 29 16:27:40 volumio volumio[2563]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 29 16:27:40 volumio volumio[2563]: info: [1759156060298] CoreMusicLibrary::Adding element Last_100 Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 29 16:27:40 volumio volumio[2563]: info: [1759156060300] CoreMusicLibrary::Adding element Webradio Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 29 16:27:40 volumio volumio[2563]: info: Initializing BBC Radios Sep 29 16:27:40 volumio systemd[1]: mpd.service: Succeeded. Sep 29 16:27:40 volumio systemd[1]: Stopped Music Player Daemon. Sep 29 16:27:40 volumio volumio-time-update[2318]: volumio-time-update-util: Fetching time from Volumio... Sep 29 16:27:40 volumio systemd[1]: Starting Music Player Daemon... Sep 29 16:27:40 volumio volumio-remote-updater[2346]: [2025-09-29 16:27:40] [connect] Successful connection Sep 29 16:27:40 volumio volumio-time-update[2318]: volumio-time-update-util: Date not found in response Sep 29 16:27:40 volumio volumio-time-update[2318]: volumio-time-update-util: Retrying in 5 seconds... Sep 29 16:27:40 volumio sudo[2719]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 29 16:27:40 volumio sudo[2719]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:27:40 volumio volumio[2563]: Starting albumart workers Sep 29 16:27:40 volumio sudo[2719]: pam_unix(sudo:session): session closed for user root Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: Volumio Calling Home Sep 29 16:27:40 volumio volumio[2563]: info: CoreStateMachine::setRepeat null single undefined Sep 29 16:27:40 volumio volumio[2563]: info: CoreStateMachine::pushState Sep 29 16:27:40 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::volumioPushState Sep 29 16:27:40 volumio volumio[2563]: info: CoreStateMachine::setRandom null Sep 29 16:27:40 volumio volumio[2563]: info: CoreStateMachine::pushState Sep 29 16:27:40 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::volumioPushState Sep 29 16:27:40 volumio volumio[2563]: Unhandled rejection Error: No sockets available, cannot start. Sep 29 16:27:40 volumio volumio[2563]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Sep 29 16:27:40 volumio volumio[2563]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Sep 29 16:27:40 volumio volumio[2563]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Sep 29 16:27:40 volumio volumio[2563]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Sep 29 16:27:40 volumio volumio[2563]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Sep 29 16:27:40 volumio volumio[2563]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Sep 29 16:27:40 volumio volumio[2563]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Sep 29 16:27:40 volumio volumio[2563]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Sep 29 16:27:40 volumio volumio[2563]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Sep 29 16:27:40 volumio volumio[2563]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Sep 29 16:27:40 volumio volumio[2563]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Sep 29 16:27:40 volumio volumio[2563]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 29 16:27:40 volumio volumio[2563]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 29 16:27:40 volumio volumio[2563]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Sep 29 16:27:40 volumio volumio[2563]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Sep 29 16:27:40 volumio volumio[2563]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 29 16:27:40 volumio volumio[2563]: info: MPD Permissions set Sep 29 16:27:40 volumio volumio[2563]: info: MPD Permissions set Sep 29 16:27:40 volumio volumio-remote-updater[2346]: [2025-09-29 16:27:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1759156060 101 Sep 29 16:27:40 volumio volumio[2563]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 29 16:27:40 volumio volumio[2563]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:27:40 volumio volumio[2563]: info: Starting Shairport Sync Sep 29 16:27:40 volumio volumio[2563]: info: Starting Shairport Sync Sep 29 16:27:40 volumio volumio[2563]: info: Starting Shairport Sync Sep 29 16:27:40 volumio sudo[2793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 29 16:27:40 volumio sudo[2793]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:27:40 volumio sudo[2795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 29 16:27:40 volumio sudo[2795]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:27:40 volumio volumio[2563]: info: CoreCommandRouter::volumioGetState Sep 29 16:27:40 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:27:40 volumio sudo[2798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 29 16:27:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 29 16:27:40 volumio sudo[2798]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:27:40 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 29 16:27:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 29 16:27:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 29 16:27:40 volumio sudo[2793]: pam_unix(sudo:session): session closed for user root Sep 29 16:27:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 29 16:27:40 volumio sudo[2795]: pam_unix(sudo:session): session closed for user root Sep 29 16:27:40 volumio volumio[2563]: info: Shairport-Sync Started Sep 29 16:27:40 volumio volumio[2563]: Error adding Membership: Error: addMembership EINVAL Sep 29 16:27:40 volumio volumio[2563]: info: Shairport-Sync Started Sep 29 16:27:40 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 29 16:27:40 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 29 16:27:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 29 16:27:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 29 16:27:40 volumio sudo[2798]: pam_unix(sudo:session): session closed for user root Sep 29 16:27:40 volumio volumio[2563]: info: Shairport-Sync Started Sep 29 16:27:41 volumio mpd[2751]: Sep 29 16:27 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 29 16:27:41 volumio systemd[1]: Started Music Player Daemon. Sep 29 16:27:41 volumio sudo[2701]: pam_unix(sudo:session): session closed for user root Sep 29 16:27:41 volumio sudo[2710]: pam_unix(sudo:session): session closed for user root Sep 29 16:27:41 volumio volumio[2563]: info: Completed starting Core Plugins Sep 29 16:27:41 volumio volumio[2563]: info: ------------------------------------------- Sep 29 16:27:41 volumio volumio[2563]: info: ----- MyVolumio plugins startup ---- Sep 29 16:27:41 volumio volumio[2563]: info: ------------------------------------------- Sep 29 16:27:41 volumio volumio[2563]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 29 16:27:41 volumio volumio[2563]: error: MPD error: The expression evaluated to a falsy value: Sep 29 16:27:41 volumio volumio[2563]: assert.ok(self.idling) Sep 29 16:27:41 volumio volumio[2563]: error: The expression evaluated to a falsy value: Sep 29 16:27:41 volumio volumio[2563]: assert.ok(self.idling) Sep 29 16:27:41 volumio volumio[2563]: error: updateQueue error: null Sep 29 16:27:41 volumio volumio[2563]: info: MPD running with PID2751 Sep 29 16:27:41 volumio volumio[2563]: ,establishing connection Sep 29 16:27:41 volumio volumio[2563]: error: updateQueue error: null Sep 29 16:27:44 volumio sh[2263]: eth0: probing for an IPv4LL address Sep 29 16:27:44 volumio dhcpcd[2293]: eth0: probing for an IPv4LL address Sep 29 16:27:44 volumio dhcpcd[2370]: eth0: probing for an IPv4LL address Sep 29 16:27:45 volumio volumio-time-update[2318]: volumio-time-update-util: Fetching time from Volumio... Sep 29 16:27:45 volumio volumio-time-update[2318]: volumio-time-update-util: Date not found in response Sep 29 16:27:45 volumio volumio-time-update[2318]: volumio-time-update-util: Retrying in 5 seconds... Sep 29 16:27:47 volumio kernel: cectx ff80023c.aocec: bad iniator with self 0x0 Sep 29 16:27:48 volumio sudo[2831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 29 16:27:48 volumio sudo[2831]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:27:48 volumio sudo[2832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 29 16:27:48 volumio sudo[2832]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:27:48 volumio sudo[2831]: pam_unix(sudo:session): session closed for user root Sep 29 16:27:48 volumio sudo[2837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 29 16:27:48 volumio sudo[2832]: pam_unix(sudo:session): session closed for user root Sep 29 16:27:48 volumio sudo[2837]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:27:48 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 29 16:27:48 volumio sudo[2837]: pam_unix(sudo:session): session closed for user root Sep 29 16:27:48 volumio volumio[2563]: info: Upmpdcli Daemon Started Sep 29 16:27:49 volumio sh[2263]: eth0: using IPv4LL address 169.254.151.150 Sep 29 16:27:49 volumio sh[2263]: eth0: adding route to 169.254.0.0/16 Sep 29 16:27:49 volumio sh[2263]: eth0: adding default route Sep 29 16:27:49 volumio dhcpcd[2293]: eth0: using IPv4LL address 169.254.151.150 Sep 29 16:27:49 volumio avahi-daemon[2320]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.151.150. Sep 29 16:27:49 volumio avahi-daemon[2320]: New relevant interface eth0.IPv4 for mDNS. Sep 29 16:27:49 volumio avahi-daemon[2320]: Registering new address record for 169.254.151.150 on eth0.IPv4. Sep 29 16:27:49 volumio dhcpcd[2293]: eth0: adding route to 169.254.0.0/16 Sep 29 16:27:49 volumio dhcpcd[2293]: eth0: adding default route Sep 29 16:27:49 volumio sh[2263]: forked to background, child pid 2869 Sep 29 16:27:49 volumio dhcpcd[2293]: forked to background, child pid 2869 Sep 29 16:27:49 volumio ifplugd(eth0)[2518]: client: ifup: interface eth0 already configured Sep 29 16:27:49 volumio sh[2263]: eth0=eth0 Sep 29 16:27:49 volumio ifplugd(eth0)[2518]: Program executed successfully. Sep 29 16:27:49 volumio dhcpcd[2370]: eth0: using IPv4LL address 169.254.151.150 Sep 29 16:27:49 volumio dhcpcd[2370]: eth0: adding route to 169.254.0.0/16 Sep 29 16:27:49 volumio dhcpcd[2370]: eth0: adding default route Sep 29 16:27:50 volumio ntpd[2486]: Listen normally on 3 eth0 169.254.151.150:123 Sep 29 16:27:50 volumio ntpd[2486]: new interface(s) found: waking up resolver Sep 29 16:27:50 volumio volumio-time-update[2318]: volumio-time-update-util: Fetching time from Volumio... Sep 29 16:27:50 volumio volumio[2563]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=odroidn2&variante=volumio&version=3.819&uuid=1a5aba6c2e2b0dd1f153b1be20876911" http://updates.volumio.org/downloader-v1/track-device Sep 29 16:27:50 volumio volumio[2563]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 29 16:27:50 volumio volumio[2563]: Dload Upload Total Spent Left Speed Sep 29 16:27:50 volumio volumio[2563]: [132B blob data] Sep 29 16:27:50 volumio volumio[2563]: retrying in 5 seconds, trial 0 Sep 29 16:27:50 volumio volumio[2563]: info: Volumio Calling Home Sep 29 16:27:50 volumio nmbd[2495]: [2025/09/29 16:27:50.779245, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 29 16:27:50 volumio nmbd[2495]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 29 16:27:50 volumio systemd[1]: Started Samba NMB Daemon. Sep 29 16:27:50 volumio systemd[1]: Starting Samba Winbind Daemon... Sep 29 16:27:50 volumio winbindd[2912]: [2025/09/29 16:27:50.914012, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 29 16:27:50 volumio winbindd[2912]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 29 16:27:50 volumio winbindd[2912]: [2025/09/29 16:27:50.922064, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 29 16:27:50 volumio winbindd[2912]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 29 16:27:50 volumio systemd[1]: Started Samba Winbind Daemon. Sep 29 16:27:50 volumio systemd[1]: Starting Samba SMB Daemon... Sep 29 16:27:51 volumio smbd[2918]: [2025/09/29 16:27:51.165929, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 29 16:27:51 volumio systemd[1]: Started Samba SMB Daemon. Sep 29 16:27:51 volumio smbd[2918]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 29 16:27:52 volumio dhcpcd[2869]: eth0: offered 192.168.1.104 from 192.168.1.1 Sep 29 16:27:52 volumio dhcpcd[2869]: eth0: probing address 192.168.1.104/24 Sep 29 16:27:52 volumio dhcpcd[2370]: eth0: offered 192.168.1.104 from 192.168.1.1 Sep 29 16:27:52 volumio dhcpcd[2370]: eth0: probing address 192.168.1.104/24 Sep 29 16:27:53 volumio volumio[2840]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 29 16:27:55 volumio volumio[2840]: ......................................................++++ Sep 29 16:27:56 volumio dhcpcd[2869]: eth0: leased 192.168.1.104 for 86400 seconds Sep 29 16:27:56 volumio dhcpcd[2869]: eth0: adding route to 192.168.1.0/24 Sep 29 16:27:56 volumio avahi-daemon[2320]: Registering new address record for 192.168.1.104 on eth0.IPv4. Sep 29 16:27:56 volumio dhcpcd[2869]: eth0: changing default route via 192.168.1.1 Sep 29 16:27:56 volumio dhcpcd[2370]: eth0: deleted default route Sep 29 16:27:56 volumio volumio[2563]: verbose: New Socket.io Connection to 192.168.1.104 from 192.168.1.101 UA: Mozilla/5.0 (X11; Linux x86_64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 3 Sep 29 16:27:56 volumio ntpd[2486]: ntpd exiting on signal 15 (Terminated) Sep 29 16:27:56 volumio systemd[1]: Stopping Network Time Service... Sep 29 16:27:56 volumio systemd[1]: ntp.service: Succeeded. Sep 29 16:27:56 volumio systemd[1]: Stopped Network Time Service. Sep 29 16:27:56 volumio systemd[1]: Starting Network Time Service... Sep 29 16:27:56 volumio ntpd[2963]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 29 16:27:56 volumio ntpd[2963]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 29 16:27:56 volumio systemd[1]: Started Network Time Service. Sep 29 16:27:56 volumio dhcpcd[2869]: eth0: deleting route to 169.254.0.0/16 Sep 29 16:27:56 volumio avahi-daemon[2320]: Withdrawing address record for 169.254.151.150 on eth0. Sep 29 16:27:56 volumio avahi-daemon[2320]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.151.150. Sep 29 16:27:56 volumio avahi-daemon[2320]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.104. Sep 29 16:27:56 volumio ntpd[2969]: proto: precision = 0.791 usec (-20) Sep 29 16:27:56 volumio ntpd[2969]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 29 16:27:56 volumio ntpd[2969]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 29 16:27:56 volumio ntpd[2969]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1007 days ago Sep 29 16:27:56 volumio ntpd[2969]: Listen and drop on 0 v6wildcard [::]:123 Sep 29 16:27:56 volumio ntpd[2969]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 29 16:27:56 volumio ntpd[2969]: Listen normally on 2 lo 127.0.0.1:123 Sep 29 16:27:56 volumio ntpd[2969]: Listen normally on 3 eth0 192.168.1.104:123 Sep 29 16:27:56 volumio ntpd[2969]: Listening on routing socket on fd #20 for interface updates Sep 29 16:27:56 volumio ntpd[2969]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 29 16:27:56 volumio ntpd[2969]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 29 16:27:57 volumio dhcpcd[2370]: eth0: leased 192.168.1.104 for 86400 seconds Sep 29 16:27:57 volumio dhcpcd[2370]: eth0: adding route to 192.168.1.0/24 Sep 29 16:27:57 volumio dhcpcd[2370]: eth0: adding default route via 192.168.1.1 Sep 29 16:27:57 volumio ntpd[2969]: ntpd exiting on signal 15 (Terminated) Sep 29 16:27:57 volumio systemd[1]: Stopping Network Time Service... Sep 29 16:27:57 volumio systemd[1]: ntp.service: Succeeded. Sep 29 16:27:57 volumio systemd[1]: Stopped Network Time Service. Sep 29 16:27:57 volumio systemd[1]: Starting Network Time Service... Sep 29 16:27:57 volumio ntpd[3022]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 29 16:27:57 volumio ntpd[3022]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Sep 29 16:27:57 volumio systemd[1]: Started Network Time Service. Sep 29 16:27:57 volumio dhcpcd[2370]: eth0: deleting route to 169.254.0.0/16 Sep 29 16:27:57 volumio ntpd[3028]: proto: precision = 0.791 usec (-20) Sep 29 16:27:57 volumio ntpd[3028]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Sep 29 16:27:57 volumio ntpd[3028]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Sep 29 16:27:57 volumio ntpd[3028]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1007 days ago Sep 29 16:27:57 volumio ntpd[3028]: Listen and drop on 0 v6wildcard [::]:123 Sep 29 16:27:57 volumio ntpd[3028]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 29 16:27:57 volumio ntpd[3028]: Listen normally on 2 lo 127.0.0.1:123 Sep 29 16:27:57 volumio ntpd[3028]: Listen normally on 3 eth0 192.168.1.104:123 Sep 29 16:27:57 volumio ntpd[3028]: Listening on routing socket on fd #20 for interface updates Sep 29 16:27:57 volumio ntpd[3028]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 29 16:27:57 volumio ntpd[3028]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 29 16:27:58 volumio volumio[2563]: info: Volumio Network Manager: Network status updated: 1 Sep 29 16:27:59 volumio volumio[2840]: ....................................................................................................................++++ Sep 29 16:27:59 volumio volumio[2840]: e is 65537 (0x010001) Sep 29 16:27:59 volumio volumio[2840]: writing RSA key Sep 29 16:27:59 volumio ntpd[3028]: Soliciting pool server 195.228.75.149 Sep 29 16:27:59 volumio ntpd[3028]: Soliciting pool server 81.0.124.200 Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 29 16:28:00 volumio volumio-time-update[2318]: volumio-time-update-util: Setting system time to: 2025-09-29 16:28:51 Sep 29 16:28:00 volumio volumio[2563]: info: Adding plugin bluetooth to MyMusic Plugins Sep 29 16:28:00 volumio volumio[2563]: info: Adding plugin multiroom to MyMusic Plugins Sep 29 16:28:00 volumio volumio[2563]: info: Adding plugin metavolumio to MyMusic Plugins Sep 29 16:28:00 volumio volumio[2563]: info: Adding plugin cd_controller to MyMusic Plugins Sep 29 16:28:00 volumio sudo[3063]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-29 16:28:51 Sep 29 16:28:00 volumio sudo[3063]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:28:00 volumio volumio[2563]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 29 16:28:00 volumio volumio[2563]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 29 16:28:00 volumio volumio[2563]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 29 16:28:00 volumio volumio[2563]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 29 16:28:00 volumio dbus-daemon[2323]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.10' (uid=0 pid=3064 comm="timedatectl set-time 2025-09-29 16:28:51 " label="kernel") Sep 29 16:28:00 volumio systemd[1]: Starting Time & Date Service... Sep 29 16:28:00 volumio ntpd[3028]: Soliciting pool server 188.156.114.7 Sep 29 16:28:00 volumio ntpd[3028]: Soliciting pool server 62.112.195.26 Sep 29 16:28:00 volumio ntpd[3028]: Soliciting pool server 91.82.109.180 Sep 29 16:28:01 volumio volumio[2563]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 29 16:28:01 volumio volumio[2563]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 29 16:28:01 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 29 16:28:01 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 29 16:28:01 volumio volumio[2563]: info: Starting MyVolumio Remote Streaming Endpoints Sep 29 16:28:01 volumio volumio[2563]: info: MyVolumio not started Sep 29 16:28:01 volumio volumio[2563]: info: Initializing device activation check Sep 29 16:28:01 volumio volumio[2563]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 29 16:28:01 volumio volumio[2563]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 29 16:28:01 volumio ntpd[3028]: Soliciting pool server 193.227.197.2 Sep 29 16:28:01 volumio dbus-daemon[2323]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 29 16:28:01 volumio systemd[1]: Started Time & Date Service. Sep 29 16:28:51 volumio systemd-timedated[3065]: Changed local time to Mon Sep 29 16:28:51 2025 Sep 29 16:28:51 volumio sudo[3063]: pam_unix(sudo:session): session closed for user root Sep 29 16:28:51 volumio volumio-time-update[2318]: volumio-time-update-util: System time updated successfully. Sep 29 16:28:51 volumio systemd[1]: Started Volumio Time Update Utility. Sep 29 16:28:51 volumio systemd[1]: Reached target Multi-User System. Sep 29 16:28:51 volumio systemd[1]: Reached target Graphical Interface. Sep 29 16:28:51 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 29 16:28:51 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 29 16:28:51 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 29 16:28:51 volumio systemd[1]: Startup finished in 10.160s (kernel) + 28.094s (userspace) = 38.255s. Sep 29 16:28:51 volumio kernel: cectx ff80023c.aocec: bad iniator with self 0x0 Sep 29 16:28:51 volumio ntpd[3028]: Soliciting pool server 193.224.163.77 Sep 29 16:28:51 volumio ntpd[3028]: Soliciting pool server 92.249.148.253 Sep 29 16:28:51 volumio volumio[2563]: info: Starting Streaming Service Transparent Proxy Sep 29 16:28:51 volumio ntpd[3028]: Soliciting pool server 80.249.164.126 Sep 29 16:28:51 volumio volumio[2563]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 29 16:28:51 volumio volumio[2563]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 29 16:28:51 volumio volumio[2563]: info: Streaming services startup Sep 29 16:28:51 volumio volumio[2563]: info: Starting Streaming Daemon Sep 29 16:28:51 volumio sudo[3075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 29 16:28:51 volumio sudo[3075]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:28:51 volumio sudo[3075]: pam_unix(sudo:session): session closed for user root Sep 29 16:28:51 volumio volumio[2563]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 29 16:28:51 volumio volumio[2563]: info: Discovery: adding af96edf5-b8d1-462e-9623-5b95d21d7aeb Sep 29 16:28:51 volumio volumio[2563]: info: Discovery: Found device Volumio Sep 29 16:28:51 volumio volumio[2563]: info: CoreCommandRouter::volumioGetState Sep 29 16:28:51 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:28:51 volumio volumio[2563]: error: Cannot start Volumio Streaming Daemon Sep 29 16:28:51 volumio volumio[2563]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 29 16:28:51 volumio volumio[2563]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 29 16:28:51 volumio volumio[2563]: info: Volumio called home Sep 29 16:28:51 volumio volumio[2563]: STREAMING PROXY: Starting server on port 3245 Sep 29 16:28:51 volumio volumio[2563]: Node JS runtime: 14 Sep 29 16:28:51 volumio ntpd[3028]: Soliciting pool server 194.38.104.150 Sep 29 16:28:52 volumio ntpd[3028]: Soliciting pool server 89.107.253.4 Sep 29 16:28:52 volumio ntpd[3028]: Soliciting pool server 195.111.92.55 Sep 29 16:28:52 volumio ntpd[3028]: Soliciting pool server 82.141.152.3 Sep 29 16:28:53 volumio ntpd[3028]: Soliciting pool server 62.112.193.9 Sep 29 16:28:54 volumio systemd[1]: systemd-fsckd.service: Succeeded. Sep 29 16:28:54 volumio ntpd[3028]: Soliciting pool server 2a01:6ee0:1::13:f Sep 29 16:28:55 volumio volumio[2563]: verbose: New Socket.io Connection to 192.168.1.104 from 192.168.1.101 UA: Mozilla/5.0 (X11; Linux x86_64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 4 Sep 29 16:28:55 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 29 16:28:55 volumio volumio[2563]: info: CoreCommandRouter::volumioGetVisibleSources Sep 29 16:28:55 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 29 16:28:55 volumio volumio[2563]: info: CoreCommandRouter::volumioGetState Sep 29 16:28:55 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:28:55 volumio volumio[2563]: info: CoreCommandRouter::volumioGetState Sep 29 16:28:55 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:28:55 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 29 16:28:55 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 29 16:28:55 volumio volumio[2563]: info: Received Get System Info Sep 29 16:28:55 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 29 16:28:55 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 29 16:28:55 volumio volumio[2563]: info: Discovery: Getting this device information Sep 29 16:28:55 volumio volumio[2563]: info: CoreCommandRouter::volumioGetState Sep 29 16:28:55 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:28:55 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 29 16:28:55 volumio volumio[2563]: info: CoreCommandRouter::volumioGetState Sep 29 16:28:55 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:28:55 volumio volumio[2563]: info: Listing playlists Sep 29 16:28:55 volumio volumio[2563]: info: CoreCommandRouter::volumioGetQueue Sep 29 16:28:55 volumio volumio[2563]: info: CoreStateMachine::getQueue Sep 29 16:28:55 volumio volumio[2563]: info: CorePlayQueue::getQueue Sep 29 16:28:55 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 29 16:28:58 volumio ntpd[3028]: receive: Unexpected origin timestamp 0xec851629.0d2f9a27 does not match aorg 0000000000.00000000 from server@82.141.152.3 xmt 0xec85162a.889aa80f Sep 29 16:28:58 volumio ntpd[3028]: receive: Unexpected origin timestamp 0xec851629.0d326138 does not match aorg 0000000000.00000000 from server@193.227.197.2 xmt 0xec85162a.8850032a Sep 29 16:29:01 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 29 16:29:01 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 29 16:29:01 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 29 16:29:01 volumio volumio-remote-updater[2346]: No test mode Sep 29 16:29:01 volumio volumio-remote-updater[2346]: No alpha test mode Sep 29 16:29:01 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 29 16:29:02 volumio volumio[2563]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n
    \n
  • Fix plugin installations due to apt repository
    • \n
  • Fix for Chromecast playback
    • \n
\n

NEW ADDITIONS

\n
    \n
  • Add link for downloading CORRD in the side-menu
    • \n
\n","title":"Update v3.832","updateavailable":true} Sep 29 16:29:02 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 29 16:29:06 volumio kernel: cectx ff80023c.aocec: bad iniator with self 0x0 Sep 29 16:29:06 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 29 16:29:06 volumio volumio[2563]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 29 16:29:06 volumio volumio[2563]: info: CoreCommandRouter::volumioGetState Sep 29 16:29:06 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:29:07 volumio nmbd[2495]: [2025/09/29 16:29:07.561378, 0] ../source3/libsmb/nmblib.c:917(send_udp) Sep 29 16:29:07 volumio nmbd[2495]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Sep 29 16:29:11 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:29:11 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:29:11 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 29 16:29:11 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 29 16:29:11 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 29 16:29:11 volumio volumio[2563]: info: CoreCommandRouter::volumioGetBrowseSources Sep 29 16:29:11 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 29 16:29:12 volumio volumio[2563]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 29 16:29:12 volumio volumio[2563]: info: Completed starting MyVolumio Plugin Sep 29 16:29:12 volumio volumio[2563]: [Metrics] CommandRouter: 44s 542.87ms Sep 29 16:29:12 volumio volumio[2563]: info: CoreCommandRouter::volumiosetStartupVolume Sep 29 16:29:12 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 29 16:29:12 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 29 16:29:12 volumio volumio[2563]: info: CoreCommandRouter::Close All Modals sent Sep 29 16:29:12 volumio volumio[2563]: info: CoreCommandRouter::Close All Modals sent Sep 29 16:29:12 volumio kernel: aml_spdif_open Sep 29 16:29:12 volumio kernel: audio_ddr_mngr: frddrs[0] registered by device ff642000.audiobus:spdif_b Sep 29 16:29:12 volumio kernel: set normal 512 fs /4 fs Sep 29 16:29:12 volumio kernel: set spdifout clk:5644800, mpll:22579200 Sep 29 16:29:12 volumio kernel: get spdifout clk:5644797, mpll:22579186 Sep 29 16:29:12 volumio kernel: aml_dai_set_spdif_fmt , fmt 0x4010 Sep 29 16:29:12 volumio kernel: set normal 512 fs /4 fs Sep 29 16:29:12 volumio kernel: set spdifout clk:5644800, mpll:22579200 Sep 29 16:29:12 volumio kernel: get spdifout clk:5644797, mpll:22579186 Sep 29 16:29:12 volumio kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Sep 29 16:29:12 volumio kernel: hdmitx: hw: set audio Sep 29 16:29:12 volumio kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Sep 29 16:29:12 volumio kernel: hdmitx: fs = 2, cd = 4, tmds_clk = 593407 Sep 29 16:29:12 volumio kernel: hdmitx: hw: aud_n_para = 8918 Sep 29 16:29:12 volumio kernel: hdmitx: hw: set channel status Sep 29 16:29:12 volumio kernel: hdmitx: audio: Audio Type: PCM Sep 29 16:29:12 volumio kernel: hdmitx: audio: set audio param Sep 29 16:29:12 volumio kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Sep 29 16:29:12 volumio kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Sep 29 16:29:13 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 29 16:29:13 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 29 16:29:13 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 29 16:29:16 volumio kernel: fb: mem_free_work, free memory: addr:800000 Sep 29 16:29:17 volumio kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Sep 29 16:29:17 volumio kernel: spdif_b keep clk continuous Sep 29 16:29:17 volumio kernel: aml_spdif_close Sep 29 16:29:17 volumio kernel: audio_ddr_mngr: frddrs[0] released by device ff642000.audiobus:spdif_b Sep 29 16:29:19 volumio volumio[2563]: info: BOOT COMPLETED Sep 29 16:29:20 volumio kernel: cectx ff80023c.aocec: bad iniator with self 0x0 Sep 29 16:29:22 volumio systemd[1]: systemd-timedated.service: Succeeded. Sep 29 16:29:34 volumio kernel: cectx ff80023c.aocec: bad iniator with self 0x0 Sep 29 16:29:48 volumio kernel: cectx ff80023c.aocec: bad iniator with self 0x0 Sep 29 16:30:02 volumio kernel: cectx ff80023c.aocec: bad iniator with self 0x0 Sep 29 16:30:16 volumio kernel: cectx ff80023c.aocec: bad iniator with self 0x0 Sep 29 16:30:30 volumio kernel: cectx ff80023c.aocec: bad iniator with self 0x0 Sep 29 16:30:44 volumio kernel: cectx ff80023c.aocec: bad iniator with self 0x0 Sep 29 16:30:55 volumio volumio[2563]: info: CoreCommandRouter::volumioGetState Sep 29 16:30:55 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:30:58 volumio kernel: cectx ff80023c.aocec: bad iniator with self 0x0 Sep 29 16:31:03 volumio volumio[2563]: info: Preload queue cleared Sep 29 16:31:03 volumio volumio[2563]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 29 16:31:03 volumio volumio[2563]: info: CoreStateMachine::ClearQueue Sep 29 16:31:03 volumio volumio[2563]: info: CoreStateMachine::stop Sep 29 16:31:03 volumio volumio[2563]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 29 16:31:03 volumio volumio[2563]: info: CorePlayQueue::clearPlayQueue Sep 29 16:31:03 volumio volumio[2563]: info: CorePlayQueue::saveQueue Sep 29 16:31:03 volumio volumio[2563]: info: CoreCommandRouter::volumioPushQueue Sep 29 16:31:03 volumio volumio[2563]: info: CoreStateMachine::addQueueItems Sep 29 16:31:03 volumio volumio[2563]: info: CorePlayQueue::addQueueItems Sep 29 16:31:03 volumio volumio[2563]: info: Preload queue cleared Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/DSD64-2-Good Morning.dsf Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/DSD64-2-Good Morning.dsf in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=Norah%20Jones/%E2%80%A6Little%20Broken%20Hearts/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2FDSD64-2-Good%20Morning.dsf&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/DSD64-2-Good Morning.dsf Sep 29 16:31:03 volumio volumio[2563]: info: CoreCommandRouter::volumioPushQueue Sep 29 16:31:03 volumio volumio[2563]: info: CorePlayQueue::saveQueue Sep 29 16:31:03 volumio volumio[2563]: info: CoreStateMachine::updateTrackBlock Sep 29 16:31:03 volumio volumio[2563]: info: CorePlayQueue::getTrackBlock Sep 29 16:31:03 volumio volumio[2563]: info: CoreCommandRouter::volumioPlay Sep 29 16:31:03 volumio volumio[2563]: info: CoreStateMachine::play index 0 Sep 29 16:31:03 volumio volumio[2563]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 29 16:31:03 volumio volumio[2563]: info: CoreStateMachine::addQueueItems Sep 29 16:31:03 volumio volumio[2563]: info: CorePlayQueue::addQueueItems Sep 29 16:31:03 volumio volumio[2563]: info: Preload queue cleared Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/2_16_44-Y'a d'la rumba dans l'air.flac Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/2_16_44-Y'a d'la rumba dans l'air.flac in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/DSD128-2-When We Were Young.dsf Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/DSD128-2-When We Were Young.dsf in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/2_24_48-A Slow One.flac Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/2_24_48-A Slow One.flac in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/DSD64-6-Black Byrd (4.0 mix).dsf Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/DSD64-6-Black Byrd (4.0 mix).dsf in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/DTS-6_24_44-Vivace – Presto.wav Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/DTS-6_24_44-Vivace – Presto.wav in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/6_24_96-The Anchor Song (5.1 mix).dts.flac Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/6_24_96-The Anchor Song (5.1 mix).dts.flac in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/2_16_44-Mamma Mia.flac Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/2_16_44-Mamma Mia.flac in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/2_16_44-Better Days.flac Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/2_16_44-Better Days.flac in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/2_24_88-So Sorry.flac Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/2_24_88-So Sorry.flac in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/DSD256-2-Choclo.dsf Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/DSD256-2-Choclo.dsf in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/DSD256-2-Pedacito de Cielo.dsf Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/DSD256-2-Pedacito de Cielo.dsf in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/2_24_44-I Can't Give You Anything but Love.flac Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/2_24_44-I Can't Give You Anything but Love.flac in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/DTS-6_20_44-Take the a Train.wav Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/DTS-6_20_44-Take the a Train.wav in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/6_16_44-I’ve Grown Accustomed to His Face.flac Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/6_16_44-I’ve Grown Accustomed to His Face.flac in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/2_24_96-Sóhaj.flac Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/2_24_96-Sóhaj.flac in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/6_24_96-Wildflower #2 (5.1 mix).flac Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/6_24_96-Wildflower #2 (5.1 mix).flac in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/6_24_48-Les Brown and His Band of Renown - I'm Forever Blowing Bubbles.flac Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/6_24_48-Les Brown and His Band of Renown - I'm Forever Blowing Bubbles.flac in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/DSD128-2-The Shadow of Your Smile (Love Theme from the Sandpiper).dsf Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/DSD128-2-The Shadow of Your Smile (Love Theme from the Sandpiper).dsf in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/2_24_96-Bebel Gilberto - Nada não.flac Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/2_24_96-Bebel Gilberto - Nada não.flac in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: Adding Item to queue: music-library/USB/HD9/media/Music/2_24_48-Blue Skies.flac Sep 29 16:31:03 volumio volumio[2563]: info: Exploding uri music-library/USB/HD9/media/Music/2_24_48-Blue Skies.flac in service mpd Sep 29 16:31:03 volumio volumio[2563]: info: CoreStateMachine::stop Sep 29 16:31:03 volumio volumio[2563]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 29 16:31:03 volumio volumio[2563]: info: CoreStateMachine::play index undefined Sep 29 16:31:03 volumio volumio[2563]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 29 16:31:03 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:03 volumio volumio[2563]: info: CoreStateMachine::startPlaybackTimer Sep 29 16:31:03 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:03 volumio volumio[2563]: verbose: ControllerMpd::clearAddPlayTracks USB/HD9/media/Music/DSD64-2-Good Morning.dsf Sep 29 16:31:03 volumio volumio[2563]: verbose: ControllerMpd::sendMpdCommand stop Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=Alain%20Souchon/20%20Chansons/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2F2_16_44-Y'a%20d'la%20rumba%20dans%20l'air.flac&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/2_16_44-Y'a d'la rumba dans l'air.flac Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=Adele/25/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2FDSD128-2-When%20We%20Were%20Young.dsf&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/DSD128-2-When We Were Young.dsf Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=CCCN%20Jazz%20Orchestra%20%26%20Hendrik%20Meurkens/Big%20Band%20Brasil/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2F2_24_48-A%20Slow%20One.flac&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/2_24_48-A Slow One.flac Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=Donald%20Byrd/Black%20Byrd/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2FDSD64-6-Black%20Byrd%20(4.0%20mix).dsf&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/DSD64-6-Black Byrd (4.0 mix).dsf Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=Jon%20Lord/Concerto%20for%20Group%20and%20Orchestra/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2FDTS-6_24_44-Vivace%20%E2%80%93%20Presto.wav&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/DTS-6_24_44-Vivace – Presto.wav Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=Bj%C3%B6rk/Debut%3A%20Surrounded/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2F6_24_96-The%20Anchor%20Song%20(5.1%20mix).dts.flac&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/6_24_96-The Anchor Song (5.1 mix).dts.flac Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=ABBA/Gold%3A%20Greatest%20Hits/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2F2_16_44-Mamma%20Mia.flac&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/2_16_44-Mamma Mia.flac Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=Ay%E1%BB%8D/Gravity%20at%20Last/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2F2_16_44-Better%20Days.flac&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/2_16_44-Better Days.flac Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=Sophie%20Milman/In%20the%20Moonlight/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2F2_24_88-So%20Sorry.flac&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/2_24_88-So Sorry.flac Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=Trio%20de%20Curda/Libertango/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2FDSD256-2-Choclo.dsf&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/DSD256-2-Choclo.dsf Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=Trio%20de%20Curda/Libertango/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2FDSD256-2-Pedacito%20de%20Cielo.dsf&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/DSD256-2-Pedacito de Cielo.dsf Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=Marta%20Sierra/Paris%20Connection/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2F2_24_44-I%20Can't%20Give%20You%20Anything%20but%20Love.flac&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/2_24_44-I Can't Give You Anything but Love.flac Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=DMP%20Big%20Band/Potpourri/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2FDTS-6_20_44-Take%20the%20a%20Train.wav&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/DTS-6_20_44-Take the a Train.wav Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=Diana%20Krall/Quiet%20Nights/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2F6_16_44-I%E2%80%99ve%20Grown%20Accustomed%20to%20His%20Face.flac&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/6_16_44-I’ve Grown Accustomed to His Face.flac Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=Rhoda%20Scott/Rhoda%20Scott%20in%20Budapest/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2F2_24_96-S%C3%B3haj.flac&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/2_24_96-Sóhaj.flac Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=The%20Carl%20Verheyen%20Band/Rumor%20Mill/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2F6_24_96-Wildflower%20%232%20(5.1%20mix).flac&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/6_24_96-Wildflower #2 (5.1 mix).flac Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=Les%20Brown%20and%20His%20Band%20of%20Renown/Session%20%2355/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2F6_24_48-Les%20Brown%20and%20His%20Band%20of%20Renown%20-%20I'm%20Forever%20Blowing%20Bubbles.flac&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/6_24_48-Les Brown and His Band of Renown - I'm Forever Blowing Bubbles.flac Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=Astrud%20Gilberto/The%20Shadow%20of%20Your%20Smile/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2FDSD128-2-The%20Shadow%20of%20Your%20Smile%20(Love%20Theme%20from%20the%20Sandpiper).dsf&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/DSD128-2-The Shadow of Your Smile (Love Theme from the Sandpiper).dsf Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=Bebel%20Gilberto/Tudo/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2F2_24_96-Bebel%20Gilberto%20-%20Nada%20n%C3%A3o.flac&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/2_24_96-Bebel Gilberto - Nada não.flac Sep 29 16:31:03 volumio volumio[2563]: info: ALBUMART /albumart?cacheid=157&web=Eva%20Cassidy/Walkin'%20After%20Midnight/extralarge&path=%2Fmnt%2FUSB%2FHD9%2Fmedia%2FMusic%2F2_24_48-Blue%20Skies.flac&metadata=false Sep 29 16:31:03 volumio volumio[2563]: info: URI /mnt/USB/HD9/media/Music/2_24_48-Blue Skies.flac Sep 29 16:31:03 volumio volumio[2563]: info: sendMpdCommand stop took 8 milliseconds Sep 29 16:31:03 volumio volumio[2563]: verbose: ControllerMpd::sendMpdCommand clear Sep 29 16:31:03 volumio volumio[2563]: info: CoreCommandRouter::volumioPushQueue Sep 29 16:31:03 volumio volumio[2563]: info: CorePlayQueue::saveQueue Sep 29 16:31:03 volumio volumio[2563]: info: CoreStateMachine::updateTrackBlock Sep 29 16:31:03 volumio volumio[2563]: info: CorePlayQueue::getTrackBlock Sep 29 16:31:04 volumio volumio[2563]: info: Sep 29 16:31:04 volumio volumio[2563]: ---------------------------- MPD announces system playlist update Sep 29 16:31:04 volumio volumio[2563]: info: Ignoring MPD Status Update Sep 29 16:31:04 volumio volumio[2563]: info: sendMpdCommand clear took 11 milliseconds Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::sendMpdCommand add "USB/HD9/media/Music/DSD64-2-Good Morning.dsf" Sep 29 16:31:04 volumio volumio[2563]: info: Sep 29 16:31:04 volumio volumio[2563]: ---------------------------- MPD announces system playlist update Sep 29 16:31:04 volumio volumio[2563]: info: Ignoring MPD Status Update Sep 29 16:31:04 volumio volumio[2563]: info: Sep 29 16:31:04 volumio volumio[2563]: ---------------------------- MPD announces system playlist update Sep 29 16:31:04 volumio volumio[2563]: info: Ignoring MPD Status Update Sep 29 16:31:04 volumio volumio[2563]: error: updateQueue error: null Sep 29 16:31:04 volumio volumio[2563]: info: Sep 29 16:31:04 volumio volumio[2563]: ---------------------------- MPD announces system playlist update Sep 29 16:31:04 volumio volumio[2563]: info: Ignoring MPD Status Update Sep 29 16:31:04 volumio volumio[2563]: info: ------------------------------ 5ms Sep 29 16:31:04 volumio volumio[2563]: info: sendMpdCommand add "USB/HD9/media/Music/DSD64-2-Good Morning.dsf" took 4 milliseconds Sep 29 16:31:04 volumio volumio[2563]: info: ------------------------------ 4ms Sep 29 16:31:04 volumio volumio[2563]: info: ------------------------------ 3ms Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::sendMpdCommand play Sep 29 16:31:04 volumio volumio[2563]: info: Sep 29 16:31:04 volumio volumio[2563]: ---------------------------- MPD announces system playlist update Sep 29 16:31:04 volumio volumio[2563]: info: Ignoring MPD Status Update Sep 29 16:31:04 volumio volumio[2563]: info: Sep 29 16:31:04 volumio volumio[2563]: ---------------------------- MPD announces system playlist update Sep 29 16:31:04 volumio volumio[2563]: info: Ignoring MPD Status Update Sep 29 16:31:04 volumio volumio[2563]: error: Upnp client error: Error: This socket has been ended by the other party Sep 29 16:31:04 volumio volumio[2563]: info: ------------------------------ 6ms Sep 29 16:31:04 volumio volumio[2563]: info: sendMpdCommand play took 4 milliseconds Sep 29 16:31:04 volumio volumio[2563]: info: ------------------------------ 4ms Sep 29 16:31:04 volumio volumio[2563]: info: ------------------------------ 3ms Sep 29 16:31:04 volumio volumio[2563]: info: Sep 29 16:31:04 volumio volumio[2563]: ---------------------------- MPD announces state update: player Sep 29 16:31:04 volumio kernel: aml_spdif_open Sep 29 16:31:04 volumio kernel: audio_ddr_mngr: frddrs[0] registered by device ff642000.audiobus:spdif_b Sep 29 16:31:04 volumio kernel: set normal 512 fs /4 fs Sep 29 16:31:04 volumio kernel: set spdifout clk:22579200, mpll:90316800 Sep 29 16:31:04 volumio kernel: get spdifout clk:22579187, mpll:90316746 Sep 29 16:31:04 volumio kernel: aml_dai_set_spdif_fmt , fmt 0x4010 Sep 29 16:31:04 volumio kernel: set normal 512 fs /4 fs Sep 29 16:31:04 volumio kernel: set spdifout clk:22579200, mpll:90316800 Sep 29 16:31:04 volumio kernel: get spdifout clk:22579187, mpll:90316746 Sep 29 16:31:04 volumio kernel: spdif_info: rate: 176400, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xc00, ch1_r:0xc00 Sep 29 16:31:04 volumio kernel: hdmitx: hw: set audio Sep 29 16:31:04 volumio kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Sep 29 16:31:04 volumio kernel: hdmitx: fs = 6, cd = 4, tmds_clk = 593407 Sep 29 16:31:04 volumio kernel: hdmitx: hw: aud_n_para = 35672 Sep 29 16:31:04 volumio kernel: hdmitx: hw: set channel status Sep 29 16:31:04 volumio kernel: hdmitx: audio: Audio Type: PCM Sep 29 16:31:04 volumio kernel: hdmitx: audio: set audio param Sep 29 16:31:04 volumio kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Sep 29 16:31:04 volumio volumio[2563]: info: ControllerMpd::getState Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::sendMpdCommand status Sep 29 16:31:04 volumio volumio[2563]: info: Sep 29 16:31:04 volumio volumio[2563]: ---------------------------- MPD announces state update: player Sep 29 16:31:04 volumio volumio[2563]: info: ControllerMpd::getState Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::sendMpdCommand status Sep 29 16:31:04 volumio volumio[2563]: info: Sep 29 16:31:04 volumio volumio[2563]: ---------------------------- MPD announces state update: player Sep 29 16:31:04 volumio volumio[2563]: info: ControllerMpd::getState Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::sendMpdCommand status Sep 29 16:31:04 volumio volumio[2563]: info: Sep 29 16:31:04 volumio volumio[2563]: ---------------------------- MPD announces state update: player Sep 29 16:31:04 volumio volumio[2563]: info: sendMpdCommand status took 6 milliseconds Sep 29 16:31:04 volumio volumio[2563]: info: ControllerMpd::getState Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::sendMpdCommand status Sep 29 16:31:04 volumio volumio[2563]: info: sendMpdCommand status took 3 milliseconds Sep 29 16:31:04 volumio volumio[2563]: info: sendMpdCommand status took 3 milliseconds Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::parseState Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::parseState Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::parseState Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 29 16:31:04 volumio volumio[2563]: info: Sep 29 16:31:04 volumio volumio[2563]: ---------------------------- MPD announces state update: player Sep 29 16:31:04 volumio volumio[2563]: info: ControllerMpd::getState Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::sendMpdCommand status Sep 29 16:31:04 volumio volumio[2563]: info: Sep 29 16:31:04 volumio volumio[2563]: ---------------------------- MPD announces state update: player Sep 29 16:31:04 volumio volumio[2563]: info: ControllerMpd::getState Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::sendMpdCommand status Sep 29 16:31:04 volumio volumio[2563]: info: sendMpdCommand status took 6 milliseconds Sep 29 16:31:04 volumio volumio[2563]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 29 16:31:04 volumio volumio[2563]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 29 16:31:04 volumio volumio[2563]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 29 16:31:04 volumio volumio[2563]: info: sendMpdCommand status took 3 milliseconds Sep 29 16:31:04 volumio volumio[2563]: info: sendMpdCommand status took 3 milliseconds Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::parseState Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::parseTrackInfo Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::parseTrackInfo Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::parseTrackInfo Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::parseState Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::parseState Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 29 16:31:04 volumio volumio[2563]: info: ControllerMpd::pushState Sep 29 16:31:04 volumio volumio[2563]: info: CoreCommandRouter::servicePushState Sep 29 16:31:04 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:04 volumio volumio[2563]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Good Morning","artist":"Norah Jones","album":"…Little Broken Hearts","uri":"USB/HD9/media/Music/DSD64-2-Good Morning.dsf","trackType":"dsf"} Sep 29 16:31:04 volumio volumio[2563]: verbose: CURRENT POSITION 0 Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::syncState stateService play Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::syncState currentStatus stop Sep 29 16:31:04 volumio volumio[2563]: info: ControllerMpd::pushState Sep 29 16:31:04 volumio volumio[2563]: info: CoreCommandRouter::servicePushState Sep 29 16:31:04 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:04 volumio volumio[2563]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Good Morning","artist":"Norah Jones","album":"…Little Broken Hearts","uri":"USB/HD9/media/Music/DSD64-2-Good Morning.dsf","trackType":"dsf"} Sep 29 16:31:04 volumio volumio[2563]: verbose: CURRENT POSITION 0 Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::syncState stateService play Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::syncState currentStatus play Sep 29 16:31:04 volumio volumio[2563]: info: Received an update from plugin. extracting info from payload Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::pushState Sep 29 16:31:04 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:04 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 29 16:31:04 volumio volumio[2563]: info: CoreCommandRouter::volumioPushState Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::pushState Sep 29 16:31:04 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:04 volumio volumio[2563]: info: CoreCommandRouter::volumioPushState Sep 29 16:31:04 volumio volumio[2563]: info: ControllerMpd::pushState Sep 29 16:31:04 volumio volumio[2563]: info: CoreCommandRouter::servicePushState Sep 29 16:31:04 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:04 volumio volumio[2563]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Good Morning","artist":"Norah Jones","album":"…Little Broken Hearts","uri":"USB/HD9/media/Music/DSD64-2-Good Morning.dsf","trackType":"dsf"} Sep 29 16:31:04 volumio volumio[2563]: verbose: CURRENT POSITION 0 Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::syncState stateService play Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::syncState currentStatus play Sep 29 16:31:04 volumio volumio[2563]: info: Received an update from plugin. extracting info from payload Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::pushState Sep 29 16:31:04 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:04 volumio volumio[2563]: info: CoreCommandRouter::volumioPushState Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::pushState Sep 29 16:31:04 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:04 volumio volumio[2563]: info: CoreCommandRouter::volumioPushState Sep 29 16:31:04 volumio volumio[2563]: info: ------------------------------ 27ms Sep 29 16:31:04 volumio volumio[2563]: info: ------------------------------ 29ms Sep 29 16:31:04 volumio volumio[2563]: info: ------------------------------ 28ms Sep 29 16:31:04 volumio volumio[2563]: info: sendMpdCommand playlistinfo took 20 milliseconds Sep 29 16:31:04 volumio volumio[2563]: info: sendMpdCommand playlistinfo took 20 milliseconds Sep 29 16:31:04 volumio volumio[2563]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::parseTrackInfo Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::parseTrackInfo Sep 29 16:31:04 volumio volumio[2563]: verbose: ControllerMpd::parseTrackInfo Sep 29 16:31:04 volumio volumio[2563]: info: ControllerMpd::pushState Sep 29 16:31:04 volumio volumio[2563]: info: CoreCommandRouter::servicePushState Sep 29 16:31:04 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:04 volumio volumio[2563]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Good Morning","artist":"Norah Jones","album":"…Little Broken Hearts","uri":"USB/HD9/media/Music/DSD64-2-Good Morning.dsf","trackType":"dsf"} Sep 29 16:31:04 volumio volumio[2563]: verbose: CURRENT POSITION 0 Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::syncState stateService play Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::syncState currentStatus play Sep 29 16:31:04 volumio volumio[2563]: info: Received an update from plugin. extracting info from payload Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::pushState Sep 29 16:31:04 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:04 volumio volumio[2563]: info: CoreCommandRouter::volumioPushState Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::pushState Sep 29 16:31:04 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:04 volumio volumio[2563]: info: CoreCommandRouter::volumioPushState Sep 29 16:31:04 volumio volumio[2563]: info: ControllerMpd::pushState Sep 29 16:31:04 volumio volumio[2563]: info: CoreCommandRouter::servicePushState Sep 29 16:31:04 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:04 volumio volumio[2563]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Good Morning","artist":"Norah Jones","album":"…Little Broken Hearts","uri":"USB/HD9/media/Music/DSD64-2-Good Morning.dsf","trackType":"dsf"} Sep 29 16:31:04 volumio volumio[2563]: verbose: CURRENT POSITION 0 Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::syncState stateService play Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::syncState currentStatus play Sep 29 16:31:04 volumio volumio[2563]: info: Received an update from plugin. extracting info from payload Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::pushState Sep 29 16:31:04 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:04 volumio volumio[2563]: info: CoreCommandRouter::volumioPushState Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::pushState Sep 29 16:31:04 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:04 volumio volumio[2563]: info: CoreCommandRouter::volumioPushState Sep 29 16:31:04 volumio volumio[2563]: info: ControllerMpd::pushState Sep 29 16:31:04 volumio volumio[2563]: info: CoreCommandRouter::servicePushState Sep 29 16:31:04 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:04 volumio volumio[2563]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Good Morning","artist":"Norah Jones","album":"…Little Broken Hearts","uri":"USB/HD9/media/Music/DSD64-2-Good Morning.dsf","trackType":"dsf"} Sep 29 16:31:04 volumio volumio[2563]: verbose: CURRENT POSITION 0 Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::syncState stateService play Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::syncState currentStatus play Sep 29 16:31:04 volumio volumio[2563]: info: Received an update from plugin. extracting info from payload Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::pushState Sep 29 16:31:04 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:04 volumio volumio[2563]: info: CoreCommandRouter::volumioPushState Sep 29 16:31:04 volumio volumio[2563]: info: CoreStateMachine::pushState Sep 29 16:31:04 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:04 volumio volumio[2563]: info: CoreCommandRouter::volumioPushState Sep 29 16:31:04 volumio volumio[2563]: info: ------------------------------ 49ms Sep 29 16:31:04 volumio volumio[2563]: info: ------------------------------ 46ms Sep 29 16:31:04 volumio volumio[2563]: info: ------------------------------ 45ms Sep 29 16:31:04 volumio kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Sep 29 16:31:12 volumio kernel: cectx ff80023c.aocec: bad iniator with self 0x0 Sep 29 16:31:17 volumio volumio[2563]: verbose: New Socket.io Connection to 192.168.1.104 from 192.168.1.101 UA: Mozilla/5.0 (X11; Linux x86_64; rv:140.0) Gecko/20100101 Firefox/140.0 Engine version: 3 Transport: polling Total Clients: 4 Sep 29 16:31:17 volumio volumio[2563]: info: CoreCommandRouter::volumioGetState Sep 29 16:31:17 volumio volumio[2563]: info: CorePlayQueue::getTrack 0 Sep 29 16:31:17 volumio volumio[2563]: info: CoreCommandRouter::volumioGetQueue Sep 29 16:31:17 volumio volumio[2563]: info: CoreStateMachine::getQueue Sep 29 16:31:17 volumio volumio[2563]: info: CorePlayQueue::getQueue Sep 29 16:31:17 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Sep 29 16:31:17 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Sep 29 16:31:17 volumio volumio[2563]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Sep 29 16:31:17 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Sep 29 16:31:17 volumio volumio[2563]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Sep 29 16:31:17 volumio volumio[2563]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Sep 29 16:31:17 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Sep 29 16:31:26 volumio kernel: cectx ff80023c.aocec: bad iniator with self 0x0 Sep 29 16:31:40 volumio kernel: cectx ff80023c.aocec: bad iniator with self 0x0 Sep 29 16:31:41 volumio volumio[2563]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Sep 29 16:31:41 volumio volumio[2563]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Sep 29 16:31:41 volumio sudo[3585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Sep 29 16:31:41 volumio sudo[3585]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 29 16:31:41 volumio sudo[3585]: pam_unix(sudo:session): session closed for user root Sep 29 16:31:48 volumio sudo[3658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Sep 29 16:31:48 volumio sudo[3658]: pam_unix(sudo:session): session opened for user root by (uid=0)