Tue Apr 12 19:15:35 UTC 2022 Description="'frequent freezing of audio'" # cat /proc/version --------------- Linux version 5.10.92-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1514 SMP Mon Jan 17 17:38:03 GMT 2022 # cat /etc/os-release --------------- PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="1c5fa5a8788b087ca429be9d10851b25c5cc4f5b" VOLUMIO_FE_VERSION="e5ce74e254a237ac98580c4437c4ed223c7d5cd9" VOLUMIO_FE3_VERSION="a86adf3c7ed2d6c50d9bca768f58ab2f56832dc1" VOLUMIO_BE_VERSION="c8882d89d3d4e0a1571e0dc9701741128f5a1656" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 31 Mar 2022 02:51:41 PM CEST" VOLUMIO_VERSION="3.251" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e83cc5fcd68eb3ee4105ab0b8b3779ea" # ifconfig --------------- eth0: flags=4163 mtu 1500 inet 192.168.1.121 netmask 255.255.255.0 broadcast 192.168.1.255 ether dc:a6:32:52:37:2d txqueuelen 1000 (Ethernet) RX packets 609 bytes 166785 (162.8 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 786 bytes 508054 (496.1 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 lo: flags=73 mtu 65536 inet 127.0.0.1 netmask 255.0.0.0 loop txqueuelen 1000 (Local Loopback) RX packets 946 bytes 96566 (94.3 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 946 bytes 96566 (94.3 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 wlan0: flags=4163 mtu 1500 inet 192.168.211.1 netmask 255.255.255.0 broadcast 192.168.211.255 ether dc:a6:32:52:37:2e txqueuelen 1000 (Ethernet) RX packets 0 bytes 0 (0.0 B) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 80 bytes 15773 (15.4 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 # sudo iwconfig --------------- lo no wireless extensions. eth0 no wireless extensions. wlan0 IEEE 802.11 Mode:Master Tx-Power=31 dBm Retry short limit:7 RTS thr:off Fragment thr:off Power Management:off # aplay -l --------------- **** List of PLAYBACK Hardware Devices **** card 0: b1 [bcm2835 HDMI 1], device 0: bcm2835 HDMI 1 [bcm2835 HDMI 1] Subdevices: 4/4 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 card 1: Headphones [bcm2835 Headphones], device 0: bcm2835 Headphones [bcm2835 Headphones] Subdevices: 4/4 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 card 5: Control [Khadas Tone Control], device 0: USB Audio [USB Audio] Subdevices: 1/1 Subdevice #0: subdevice #0 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 15 19:14 ? 00:00:07 /sbin/init root 2 0 0 19:14 ? 00:00:00 [kthreadd] root 3 2 0 19:14 ? 00:00:00 [rcu_gp] root 4 2 0 19:14 ? 00:00:00 [rcu_par_gp] root 5 2 0 19:14 ? 00:00:00 [kworker/0:0-rcu_gp] root 6 2 0 19:14 ? 00:00:00 [kworker/0:0H-kblockd] root 7 2 0 19:14 ? 00:00:00 [kworker/u8:0-cfg80211] root 8 2 0 19:14 ? 00:00:00 [mm_percpu_wq] root 9 2 0 19:14 ? 00:00:00 [rcu_tasks_rude_] root 10 2 0 19:14 ? 00:00:00 [rcu_tasks_trace] root 11 2 0 19:14 ? 00:00:00 [ksoftirqd/0] root 12 2 0 19:14 ? 00:00:00 [rcu_sched] root 13 2 0 19:14 ? 00:00:00 [migration/0] root 14 2 0 19:14 ? 00:00:00 [cpuhp/0] root 15 2 0 19:14 ? 00:00:00 [cpuhp/1] root 16 2 0 19:14 ? 00:00:00 [migration/1] root 17 2 0 19:14 ? 00:00:00 [ksoftirqd/1] root 18 2 0 19:14 ? 00:00:00 [kworker/1:0-mm_percpu_wq] root 19 2 0 19:14 ? 00:00:00 [kworker/1:0H-kblockd] root 20 2 0 19:14 ? 00:00:00 [cpuhp/2] root 21 2 0 19:14 ? 00:00:00 [migration/2] root 22 2 0 19:14 ? 00:00:00 [ksoftirqd/2] root 23 2 0 19:14 ? 00:00:00 [kworker/2:0-events_long] root 24 2 0 19:14 ? 00:00:00 [kworker/2:0H-kblockd] root 25 2 0 19:14 ? 00:00:00 [cpuhp/3] root 26 2 0 19:14 ? 00:00:00 [migration/3] root 27 2 0 19:14 ? 00:00:00 [ksoftirqd/3] root 28 2 0 19:14 ? 00:00:00 [kworker/3:0-events_long] root 29 2 0 19:14 ? 00:00:00 [kworker/3:0H-events_highpri] root 30 2 0 19:14 ? 00:00:00 [kdevtmpfs] root 31 2 0 19:14 ? 00:00:00 [netns] root 32 2 0 19:14 ? 00:00:00 [kworker/0:1-events] root 33 2 0 19:14 ? 00:00:00 [kworker/1:1-events] root 34 2 0 19:14 ? 00:00:00 [kauditd] root 35 2 0 19:14 ? 00:00:00 [kworker/0:2-events_power_efficient] root 36 2 0 19:14 ? 00:00:00 [khungtaskd] root 37 2 0 19:14 ? 00:00:00 [oom_reaper] root 38 2 0 19:14 ? 00:00:00 [writeback] root 39 2 0 19:14 ? 00:00:00 [kcompactd0] root 59 2 0 19:14 ? 00:00:00 [kblockd] root 60 2 0 19:14 ? 00:00:00 [blkcg_punt_bio] root 61 2 0 19:14 ? 00:00:00 [watchdogd] root 62 2 0 19:14 ? 00:00:00 [kworker/3:1-mm_percpu_wq] root 63 2 0 19:14 ? 00:00:00 [kworker/3:1H-kblockd] root 64 2 0 19:14 ? 00:00:00 [rpciod] root 65 2 0 19:14 ? 00:00:00 [kworker/u9:0-hci0] root 66 2 0 19:14 ? 00:00:00 [xprtiod] root 67 2 0 19:14 ? 00:00:00 [kworker/2:1-rcu_gp] root 68 2 0 19:14 ? 00:00:00 [kswapd0] root 69 2 0 19:14 ? 00:00:00 [nfsiod] root 70 2 0 19:14 ? 00:00:00 [kthrotld] root 71 2 0 19:14 ? 00:00:00 [kworker/2:2-mm_percpu_wq] root 72 2 0 19:14 ? 00:00:00 [iscsi_eh] root 73 2 0 19:14 ? 00:00:00 [iscsi_destroy] root 74 2 0 19:14 ? 00:00:00 [nvme-wq] root 75 2 0 19:14 ? 00:00:00 [nvme-reset-wq] root 76 2 0 19:14 ? 00:00:00 [nvme-delete-wq] root 77 2 0 19:14 ? 00:00:00 [kworker/u8:1-brcmf_wq/mmc1:0001:1] root 79 2 0 19:14 ? 00:00:00 [DWC Notificatio] root 80 2 0 19:14 ? 00:00:00 [uas] root 81 2 0 19:14 ? 00:00:00 [kworker/u8:2-brcmf_wq/mmc1:0001:1] root 82 2 0 19:14 ? 00:00:00 [vchiq-slot/0] root 83 2 0 19:14 ? 00:00:00 [vchiq-recy/0] root 84 2 0 19:14 ? 00:00:00 [vchiq-sync/0] root 85 2 0 19:14 ? 00:00:00 [zswap-shrink] root 86 2 0 19:14 ? 00:00:00 [kworker/2:3-events_power_efficient] root 87 2 0 19:14 ? 00:00:00 [kworker/2:4-events_long] root 88 2 0 19:14 ? 00:00:00 [kworker/2:5-mm_percpu_wq] root 89 2 0 19:14 ? 00:00:00 [sdhci] root 90 2 0 19:14 ? 00:00:00 [irq/48-mmc0] root 91 2 0 19:14 ? 00:00:00 [kworker/3:2-cgroup_destroy] root 92 2 0 19:14 ? 00:00:00 [kworker/3:3-events_freezable] root 93 2 0 19:14 ? 00:00:00 [kworker/3:4-rcu_gp] root 94 2 0 19:14 ? 00:00:00 [kworker/3:5] root 102 2 0 19:14 ? 00:00:00 [mmc_complete] root 103 2 0 19:14 ? 00:00:00 [kworker/0:1H-kblockd] root 121 2 0 19:14 ? 00:00:00 [kworker/1:2-mm_percpu_wq] root 124 2 0 19:14 ? 00:00:00 [kworker/2:1H-kblockd] root 125 2 0 19:14 ? 00:00:00 [kworker/2:2H-kblockd] root 129 2 0 19:14 ? 00:00:00 [jbd2/mmcblk0p2-] root 130 2 0 19:14 ? 00:00:00 [ext4-rsv-conver] root 137 2 3 19:14 ? 00:00:01 [loop0] root 140 2 0 19:14 ? 00:00:00 [kworker/1:1H-kblockd] root 164 2 0 19:14 ? 00:00:00 [kworker/3:2H-kblockd] root 165 2 0 19:14 ? 00:00:00 [jbd2/mmcblk0p3-] root 166 2 0 19:14 ? 00:00:00 [ext4-rsv-conver] root 173 2 0 19:14 ? 00:00:00 [kworker/1:2H-kblockd] root 174 2 0 19:14 ? 00:00:00 [ipv6_addrconf] root 203 1 2 19:14 ? 00:00:00 /lib/systemd/systemd-journald root 220 1 2 19:14 ? 00:00:00 /lib/systemd/systemd-udevd root 246 2 1 19:14 ? 00:00:00 [kworker/0:2H-mmc_complete] root 252 2 0 19:14 ? 00:00:00 [vchiq-keep/0] root 253 2 0 19:14 ? 00:00:00 [SMIO] root 313 2 0 19:14 ? 00:00:00 [mmal-vchiq] root 314 2 0 19:14 ? 00:00:00 [mmal-vchiq] root 316 2 0 19:14 ? 00:00:00 [mmal-vchiq] root 317 2 0 19:14 ? 00:00:00 [mmal-vchiq] root 318 2 0 19:14 ? 00:00:00 [mmal-vchiq] root 319 2 0 19:14 ? 00:00:00 [mmal-vchiq] root 344 2 0 19:15 ? 00:00:00 [cfg80211] root 349 2 0 19:15 ? 00:00:00 [brcmf_wq/mmc1:0] root 350 2 0 19:15 ? 00:00:00 [brcmf_wdog/mmc1] root 418 1 6 19:15 ? 00:00:02 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 _rpc 420 1 0 19:15 ? 00:00:00 /sbin/rpcbind -f -w avahi 454 1 0 19:15 ? 00:00:00 avahi-daemon: running [volumio.local] root 471 1 0 19:15 ? 00:00:00 /lib/systemd/systemd-logind root 480 1 0 19:15 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon volumio 483 1 1 19:15 ? 00:00:00 /usr/local/bin/volumio-remote-updater message+ 489 1 1 19:15 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 491 1 1 19:15 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant nobody 495 1 0 19:15 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* avahi 517 454 0 19:15 ? 00:00:00 avahi-daemon: chroot helper root 537 1 0 19:15 ? 00:00:00 /sbin/dhcpcd -q -b root 634 1 1 19:15 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 644 1 0 19:15 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux root 653 1 0 19:15 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I ntp 656 1 0 19:15 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 root 658 1 0 19:15 ? 00:00:00 /usr/sbin/sshd -D root 665 634 0 19:15 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 700 1 0 19:15 ? 00:00:00 /usr/sbin/hostapd -B -P /run/hostapd.pid -B /etc/hostapd/hostapd.conf dnsmasq 703 1 0 19:15 ? 00:00:00 /usr/sbin/dnsmasq -x /run/dnsmasq/dnsmasq.pid -u dnsmasq -7 /etc/dnsmasq.d,.dpkg-dist,.dpkg-old,.dpkg-new --local-service volumio 712 1 37 19:15 ? 00:00:09 /usr/bin/node /volumio/index.js root 737 2 0 19:15 ? 00:00:00 [kworker/u9:1-hci0] root 738 1 0 19:15 ? 00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 3000000 flow - root 740 2 0 19:15 ? 00:00:00 [kworker/u9:2-hci0] root 754 1 0 19:15 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap root 756 1 0 19:15 ? 00:00:00 /lib/systemd/systemd-hostnamed root 759 1 0 19:15 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 782 759 0 19:15 ? 00:00:00 winbindd: domain child [VOLUMIO] root 784 1 1 19:15 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 786 784 0 19:15 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 787 784 0 19:15 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 788 759 0 19:15 ? 00:00:00 winbindd: idmap child root 789 759 0 19:15 ? 00:00:00 winbindd: domain child [BUILTIN] root 791 784 0 19:15 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 828 2 0 19:15 ? 00:00:00 [cifsiod] root 829 2 0 19:15 ? 00:00:00 [smb3decryptd] root 830 2 0 19:15 ? 00:00:00 [cifsfileinfoput] root 831 2 0 19:15 ? 00:00:00 [cifsoplockd] root 832 2 0 19:15 ? 00:00:00 [cifs-dfscache] root 833 2 0 19:15 ? 00:00:00 [kworker/u8:3-brcmf_wq/mmc1:0001:1] root 835 2 0 19:15 ? 00:00:00 [cifsd] root 836 2 0 19:15 ? 00:00:00 [cifsd] volumio 866 712 1 19:15 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 875 866 5 19:15 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 876 866 5 19:15 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 877 866 5 19:15 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 960 712 0 19:15 ? 00:00:00 /bin/sh -c /usr/bin/python3 /data/plugins/audio_interface/fusiondsp/cgui/main.py volumio 961 960 6 19:15 ? 00:00:01 /usr/bin/python3 /data/plugins/audio_interface/fusiondsp/cgui/main.py mpd 1003 1 7 19:15 ? 00:00:01 /usr/bin/mpd --no-daemon shairpo+ 1004 1 0 19:15 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf volumio 1052 1 0 19:15 ? 00:00:00 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf volumio 1067 1052 99 19:15 ? 00:00:11 openssl genrsa -out /tmp/upmpdclicache/ohcreds/credkey.pem 4096 volumio 1074 1 1 19:15 ? 00:00:00 /bin/streaming-daemon volumio 1102 712 0 19:15 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'frequent freezing of audio' volumio 1103 1102 0 19:15 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js frequent freezing of audio volumio 1123 1103 0 19:15 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 1124 1123 0 19:15 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.5G 842M 1.5G 37% /imgpart /dev/loop0 364M 364M 0 100% /static overlay 27G 1.2G 24G 5% / devtmpfs 919M 0 919M 0% /dev tmpfs 959M 0 959M 0% /dev/shm tmpfs 959M 5.2M 953M 1% /run tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 959M 0 959M 0% /sys/fs/cgroup tmpfs 959M 44K 959M 1% /tmp tmpfs 20M 28K 20M 1% /var/log tmpfs 959M 0 959M 0% /var/spool/cups tmpfs 959M 0 959M 0% /var/spool/cups/tmp /dev/mmcblk0p1 92M 55M 37M 60% /boot //192.168.1.136/Music/Lossless 5.3T 3.2T 2.1T 62% /mnt/NAS/Lossless //192.168.1.136/Music/Lossy 5.3T 3.2T 2.1T 62% /mnt/NAS/Lossy # mount --------------- /dev/mmcblk0p2 on /imgpart type ext4 (rw,relatime) /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) sysfs on /sys type sysfs (rw,nosuid,nodev,noexec,relatime) proc on /proc type proc (rw,relatime) devtmpfs on /dev type devtmpfs (rw,nosuid,size=940824k,nr_inodes=150925,mode=755) securityfs on /sys/kernel/security type securityfs (rw,nosuid,nodev,noexec,relatime) tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev,noexec) devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000) tmpfs on /run type tmpfs (rw,nosuid,nodev,mode=755) tmpfs on /run/lock type tmpfs (rw,nosuid,nodev,noexec,relatime,size=5120k) tmpfs on /sys/fs/cgroup type tmpfs (ro,nosuid,nodev,noexec,mode=755) cgroup2 on /sys/fs/cgroup/unified type cgroup2 (rw,nosuid,nodev,noexec,relatime,nsdelegate) cgroup on /sys/fs/cgroup/systemd type cgroup (rw,nosuid,nodev,noexec,relatime,xattr,name=systemd) none on /sys/fs/bpf type bpf (rw,nosuid,nodev,noexec,relatime,mode=700) cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) cgroup on /sys/fs/cgroup/net_cls,net_prio type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls,net_prio) cgroup on /sys/fs/cgroup/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) sunrpc on /run/rpc_pipefs type rpc_pipefs (rw,relatime) mqueue on /dev/mqueue type mqueue (rw,relatime) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=46,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) tmpfs on /tmp type tmpfs (rw,noatime,mode=755) tmpfs on /var/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4) tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755) tmpfs on /var/spool/cups/tmp type tmpfs (rw,noatime,mode=755) /dev/mmcblk0p1 on /boot type vfat (rw,nosuid,nodev,noexec,relatime,fmask=0111,dmask=0000,allow_utime=0022,codepage=437,iocharset=ascii,shortname=mixed,utf8,errors=remount-ro,user) //192.168.1.136/Music/Lossless on /mnt/NAS/Lossless type cifs (ro,relatime,vers=3.1.1,cache=strict,username=grimaldi,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.1.136,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,echo_interval=60,actimeo=1) //192.168.1.136/Music/Lossy on /mnt/NAS/Lossy type cifs (ro,relatime,vers=3.1.1,cache=strict,username=grimaldi,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.1.136,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,echo_interval=60,actimeo=1) # netstat -natp --------------- (Not all processes could be identified, non-owned process info will not be shown, you would have to be root to see it all.) Active Internet connections (servers and established) Proto Recv-Q Send-Q Local Address Foreign Address State PID/Program name tcp 0 0 0.0.0.0:445 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:49152 0.0.0.0:* LISTEN 1052/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 0.0.0.0:5011 0.0.0.0:* LISTEN 961/python3 tcp 0 0 0.0.0.0:53 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:22 0.0.0.0:* LISTEN - tcp 0 0 192.168.1.121:42424 192.168.1.136:445 ESTABLISHED - tcp 0 0 127.0.0.1:47378 127.0.0.1:3000 ESTABLISHED 483/volumio-remote- tcp 0 0 127.0.0.1:39600 127.0.0.1:6599 ESTABLISHED 1052/upmpdcli tcp 0 0 192.168.1.121:34550 172.67.141.32:443 TIME_WAIT - tcp 0 0 127.0.0.1:51024 127.0.0.1:6600 ESTABLISHED 712/node tcp 0 0 192.168.1.121:34554 172.67.141.32:443 TIME_WAIT - tcp 0 0 192.168.1.121:42422 192.168.1.136:445 ESTABLISHED - tcp 0 0 192.168.1.121:42264 149.210.234.235:80 TIME_WAIT - tcp 0 0 127.0.0.1:39596 127.0.0.1:6599 ESTABLISHED 1052/upmpdcli tcp 0 0 127.0.0.1:47346 127.0.0.1:3000 ESTABLISHED 712/node tcp 0 0 127.0.0.1:51028 127.0.0.1:6600 ESTABLISHED 712/node tcp 0 0 127.0.0.1:47360 127.0.0.1:3000 ESTABLISHED 712/node tcp 0 0 192.168.1.121:55690 35.201.97.85:443 ESTABLISHED 712/node tcp6 0 0 :::3005 :::* LISTEN 712/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::7777 :::* LISTEN 1074/streaming-daem tcp6 0 0 :::6599 :::* LISTEN 712/node tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::53 :::* LISTEN - tcp6 0 0 :::22 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 712/node tcp6 0 0 :::3001 :::* LISTEN 866/node tcp6 0 0 127.0.0.1:7777 127.0.0.1:38818 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38762 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:47350 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38748 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38768 TIME_WAIT - tcp6 0 0 192.168.1.121:3000 192.168.1.63:54516 ESTABLISHED 712/node tcp6 0 0 127.0.0.1:7777 127.0.0.1:38858 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38764 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:47360 ESTABLISHED 712/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:47354 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38798 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38820 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38876 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38794 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38890 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38854 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38888 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38752 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38894 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38776 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38786 TIME_WAIT - tcp6 0 0 192.168.1.121:3000 192.168.1.63:54506 FIN_WAIT2 - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38880 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38856 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:47348 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38860 TIME_WAIT - tcp6 0 0 192.168.1.121:3000 192.168.1.63:54500 FIN_WAIT2 - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38832 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38780 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38838 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:47346 ESTABLISHED 712/node tcp6 0 0 127.0.0.1:7777 127.0.0.1:38760 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38896 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38784 TIME_WAIT - tcp6 0 0 192.168.1.121:3000 192.168.1.63:54505 FIN_WAIT2 - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38788 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38846 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38882 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38844 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38842 TIME_WAIT - tcp6 0 0 192.168.1.121:3000 192.168.1.63:54504 FIN_WAIT2 - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38886 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38822 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38814 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38898 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38802 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38758 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38878 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38892 TIME_WAIT - tcp6 0 0 127.0.0.1:6599 127.0.0.1:39600 ESTABLISHED 712/node tcp6 0 0 127.0.0.1:7777 127.0.0.1:38816 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38884 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38810 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38812 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38862 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:47362 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38772 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38868 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38836 TIME_WAIT - tcp6 0 0 127.0.0.1:6600 127.0.0.1:51024 ESTABLISHED - tcp6 0 0 127.0.0.1:3000 127.0.0.1:47366 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38782 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38804 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38866 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38778 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38806 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38796 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:47344 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38828 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:47378 ESTABLISHED 712/node tcp6 0 0 127.0.0.1:7777 127.0.0.1:38826 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38850 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38872 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38792 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38834 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38766 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38808 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38874 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38848 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38774 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38790 TIME_WAIT - tcp6 0 0 192.168.1.121:3000 192.168.1.63:54502 FIN_WAIT2 - tcp6 0 0 127.0.0.1:6599 127.0.0.1:39596 ESTABLISHED 712/node tcp6 0 0 127.0.0.1:7777 127.0.0.1:38870 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:47364 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38852 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38800 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38770 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38756 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38840 TIME_WAIT - tcp6 0 0 127.0.0.1:6600 127.0.0.1:51028 ESTABLISHED - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38830 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38754 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38864 TIME_WAIT - tcp6 0 0 127.0.0.1:7777 127.0.0.1:38824 TIME_WAIT - # cat /etc/asound.conf --------------- pcm.!default { type empty slave.pcm "volumio" } pcm.volumio { type empty slave.pcm "volumioDsp" } #------ FusionDsp section ------- pcm.volumioDsp { type plug slave.pcm "fusiondsphook" } pcm.fusiondsphook { type volumiohook slave.pcm "fusiondspfifo" hw_params_command "/data/plugins/audio_interface/fusiondsp/camilladsp -p 9876 -l warn -r %r -f $(echo '%f' | sed 's/_//') -n %c /data/configuration/audio_interface/fusiondsp/camilladsp.yml &" hw_free_command "tail --pid `pidof camilladsp` -f /dev/null" debug 1 } pcm.fusiondspfifo { type volumiofifo fifo "/tmp/fusiondspfifo" format_3 "S16_LE" format_4 "S24_LE" format_5 "S32_LE" } #------ End of FusionDsp section ------ pcm.postDsp { 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 "Control" } # cat /var/log/mpd.log --------------- Apr 12 19:15 : zeroconf: No global port, disabling zeroconf Apr 12 19:15 : zeroconf: No global port, disabling zeroconf Apr 12 19:15 : client: [0] opened from local Apr 12 19:15 : exception: No such directory Apr 12 19:15 : client: [1] opened from local Apr 12 19:15 : zeroconf: No global port, disabling zeroconf Apr 12 19:15 : client: [0] opened from local Apr 12 19:15 : client: [1] opened from local Apr 12 19:15 : client: [2] opened from local Apr 12 19:15 : client: [3] opened from 127.0.0.1:51024 Apr 12 19:15 : client: [4] opened from 127.0.0.1:51028 Apr 12 19:15 : exception: Failed to read mixer for 'alsa': no such mixer control: PCM Apr 12 19:15 : client: [5] opened from local # volumio plugin list --------------- This command will list installed plugins on your device ----------------------- Installed Plugins: [ { prettyName: 'FusionDsp', name: 'fusiondsp', category: 'audio_interface', version: '1.0.7', icon: 'fa-sliders fa-rotate-90', isManuallyInstalled: true, enabled: true, active: true } ] ------------------------------------------ # cat /data/configuration/plugins.json --------------- { "music_service": { "airplay_emulation": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "inputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpd": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "webradio": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "example_plugin": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "last_100": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp_browser": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "streaming_services": { "status": { "type": "string", "value": "STARTING" } } }, "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" } }, "fusiondsp": { "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 "1" } audio_output { type "alsa" name "alsa" device "volumio" dop "no" } audio_output { type "fifo" enabled "no" name "multiroom" path "/tmp/snapfifo" format "44100:16:2" } #replaygain "album" #replaygain_preamp "0" volume_normalization "no" ############################################################################### # MPD Internal Buffering ###################################################### audio_buffer_size "2048" ############################################################################### # Resource Limitations ######################################################## #connection_timeout "60" max_connections "20" max_playlist_length "81920" max_command_list_size "81920" max_output_buffer_size "81920" ############################################################################### # Character Encoding ########################################################## filesystem_charset "UTF-8" ############################################################################### # volumio endpointstest --------------- TESTING REMOTE ENDPOINTS https://google.com, 452 ms: OK https://www.googleapis.com, 217 ms: OK https://securetoken.googleapis.com, 353 ms: OK https://myvolumio.firebaseio.com, 591 ms: OK https://functions.volumio.cloud, 955 ms: OK https://oauth-performer.dfs.volumio.org, 398 ms: OK https://browsing-performer.dfs.volumio.org, 429 ms: OK http://cddb.volumio.org, 2746 ms: OK https://functions.volumio.cloud, 1080 ms: OK http://pushupdates.volumio.org, 314 ms: FAILED http://plugins.volumio.org, 2310 ms: OK https://database.volumio.cloud, 990 ms: OK https://radio-directory.firebaseapp.com, 468 ms: OK ---------- WARNING!!! Some remote endpoints cannot be reached! Failing endpoints: http://pushupdates.volumio.org, 468 ms ---------- # sudo journalctl -p 7 --------------- -- Logs begin at Tue 2022-04-12 19:14:59 UTC, end at Tue 2022-04-12 19:15:40 UTC. -- Apr 12 19:14:59 volumio kernel: Booting Linux on physical CPU 0x0 Apr 12 19:14:59 volumio kernel: Linux version 5.10.92-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1514 SMP Mon Jan 17 17:38:03 GMT 2022 Apr 12 19:14:59 volumio kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Apr 12 19:14:59 volumio kernel: CPU: div instructions available: patching division code Apr 12 19:14:59 volumio kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Apr 12 19:14:59 volumio kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.1 Apr 12 19:14:59 volumio kernel: random: fast init done Apr 12 19:14:59 volumio kernel: Memory policy: Data cache writealloc Apr 12 19:14:59 volumio kernel: Reserved memory: created CMA memory pool at 0x000000002a000000, size 64 MiB Apr 12 19:14:59 volumio kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Apr 12 19:14:59 volumio kernel: Zone ranges: Apr 12 19:14:59 volumio kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] Apr 12 19:14:59 volumio kernel: Normal empty Apr 12 19:14:59 volumio kernel: HighMem [mem 0x0000000030000000-0x000000007fffffff] Apr 12 19:14:59 volumio kernel: Movable zone start for each node Apr 12 19:14:59 volumio kernel: Early memory node ranges Apr 12 19:14:59 volumio kernel: node 0: [mem 0x0000000000000000-0x000000003dffffff] Apr 12 19:14:59 volumio kernel: node 0: [mem 0x0000000040000000-0x000000007fffffff] Apr 12 19:14:59 volumio kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000007fffffff] Apr 12 19:14:59 volumio kernel: On node 0 totalpages: 516096 Apr 12 19:14:59 volumio kernel: DMA zone: 1728 pages used for memmap Apr 12 19:14:59 volumio kernel: DMA zone: 0 pages reserved Apr 12 19:14:59 volumio kernel: DMA zone: 196608 pages, LIFO batch:63 Apr 12 19:14:59 volumio kernel: HighMem zone: 319488 pages, LIFO batch:63 Apr 12 19:14:59 volumio kernel: percpu: Embedded 20 pages/cpu s50828 r8192 d22900 u81920 Apr 12 19:14:59 volumio kernel: pcpu-alloc: s50828 r8192 d22900 u81920 alloc=20*4096 Apr 12 19:14:59 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Apr 12 19:14:59 volumio kernel: Built 1 zonelists, mobility grouping on. Total pages: 514368 Apr 12 19:14:59 volumio kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:52:37:2D vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Apr 12 19:14:59 volumio kernel: Kernel parameter elevator= does not have any effect anymore. Please use sysfs to set IO scheduler for individual devices. Apr 12 19:14:59 volumio kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Apr 12 19:14:59 volumio kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Apr 12 19:14:59 volumio kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Apr 12 19:14:59 volumio kernel: software IO TLB: mapped [mem 0x0000000024e00000-0x0000000028e00000] (64MB) Apr 12 19:14:59 volumio kernel: Memory: 1881652K/2064384K available (10240K kernel code, 1366K rwdata, 3196K rodata, 2048K init, 882K bss, 117196K reserved, 65536K cma-reserved, 1277952K highmem) Apr 12 19:14:59 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Apr 12 19:14:59 volumio kernel: ftrace: allocating 34360 entries in 101 pages Apr 12 19:14:59 volumio kernel: ftrace: allocated 101 pages with 4 groups Apr 12 19:14:59 volumio kernel: rcu: Hierarchical RCU implementation. Apr 12 19:14:59 volumio kernel: Rude variant of Tasks RCU enabled. Apr 12 19:14:59 volumio kernel: Tracing variant of Tasks RCU enabled. Apr 12 19:14:59 volumio kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Apr 12 19:14:59 volumio kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Apr 12 19:14:59 volumio kernel: GIC: Using split EOI/Deactivate mode Apr 12 19:14:59 volumio kernel: random: get_random_bytes called from start_kernel+0x3c8/0x59c with crng_init=1 Apr 12 19:14:59 volumio kernel: sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns Apr 12 19:14:59 volumio kernel: clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns Apr 12 19:14:59 volumio kernel: bcm2835: system timer (irq = 25) Apr 12 19:14:59 volumio kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Apr 12 19:14:59 volumio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Apr 12 19:14:59 volumio kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Apr 12 19:14:59 volumio kernel: Switching to timer-based delay loop, resolution 18ns Apr 12 19:14:59 volumio kernel: Console: colour dummy device 80x30 Apr 12 19:14:59 volumio kernel: printk: console [tty1] enabled Apr 12 19:14:59 volumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Apr 12 19:14:59 volumio kernel: pid_max: default: 32768 minimum: 301 Apr 12 19:14:59 volumio kernel: LSM: Security Framework initializing Apr 12 19:14:59 volumio kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Apr 12 19:14:59 volumio kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Apr 12 19:14:59 volumio kernel: cgroup: Disabling memory control group subsystem Apr 12 19:14:59 volumio kernel: CPU: Testing write buffer coherency: ok Apr 12 19:14:59 volumio kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Apr 12 19:14:59 volumio kernel: Setting up static identity map for 0x200000 - 0x20003c Apr 12 19:14:59 volumio kernel: rcu: Hierarchical SRCU implementation. Apr 12 19:14:59 volumio kernel: smp: Bringing up secondary CPUs ... Apr 12 19:14:59 volumio kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Apr 12 19:14:59 volumio kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Apr 12 19:14:59 volumio kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Apr 12 19:14:59 volumio kernel: smp: Brought up 1 node, 4 CPUs Apr 12 19:14:59 volumio kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Apr 12 19:14:59 volumio kernel: CPU: All CPU(s) started in HYP mode. Apr 12 19:14:59 volumio kernel: CPU: Virtualization extensions available. Apr 12 19:14:59 volumio kernel: devtmpfs: initialized Apr 12 19:14:59 volumio kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Apr 12 19:14:59 volumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Apr 12 19:14:59 volumio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Apr 12 19:14:59 volumio kernel: pinctrl core: initialized pinctrl subsystem Apr 12 19:14:59 volumio kernel: NET: Registered protocol family 16 Apr 12 19:14:59 volumio kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Apr 12 19:14:59 volumio kernel: audit: initializing netlink subsys (disabled) Apr 12 19:14:59 volumio kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Apr 12 19:14:59 volumio kernel: thermal_sys: Registered thermal governor 'step_wise' Apr 12 19:14:59 volumio kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Apr 12 19:14:59 volumio kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Apr 12 19:14:59 volumio kernel: Serial: AMBA PL011 UART driver Apr 12 19:14:59 volumio kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Apr 12 19:14:59 volumio kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2022-01-20T13:56:48, variant start Apr 12 19:14:59 volumio kernel: raspberrypi-firmware soc:firmware: Firmware hash is bd88f66f8952d34e4e0613a85c7a6d3da49e13e2 Apr 12 19:14:59 volumio kernel: Kprobes globally optimized Apr 12 19:14:59 volumio kernel: bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1 Apr 12 19:14:59 volumio kernel: vgaarb: loaded Apr 12 19:14:59 volumio kernel: SCSI subsystem initialized Apr 12 19:14:59 volumio kernel: usbcore: registered new interface driver usbfs Apr 12 19:14:59 volumio kernel: usbcore: registered new interface driver hub Apr 12 19:14:59 volumio kernel: usbcore: registered new device driver usb Apr 12 19:14:59 volumio kernel: usb_phy_generic phy: supply vcc not found, using dummy regulator Apr 12 19:14:59 volumio kernel: clocksource: Switched to clocksource arch_sys_counter Apr 12 19:14:59 volumio kernel: VFS: Disk quotas dquot_6.6.0 Apr 12 19:14:59 volumio kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Apr 12 19:14:59 volumio kernel: FS-Cache: Loaded Apr 12 19:14:59 volumio kernel: CacheFiles: Loaded Apr 12 19:14:59 volumio kernel: NET: Registered protocol family 2 Apr 12 19:14:59 volumio kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Apr 12 19:14:59 volumio kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) Apr 12 19:14:59 volumio kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Apr 12 19:14:59 volumio kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 12 19:14:59 volumio kernel: TCP: Hash tables configured (established 8192 bind 8192) Apr 12 19:14:59 volumio kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Apr 12 19:14:59 volumio kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Apr 12 19:14:59 volumio kernel: NET: Registered protocol family 1 Apr 12 19:14:59 volumio kernel: RPC: Registered named UNIX socket transport module. Apr 12 19:14:59 volumio kernel: RPC: Registered udp transport module. Apr 12 19:14:59 volumio kernel: RPC: Registered tcp transport module. Apr 12 19:14:59 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Apr 12 19:14:59 volumio kernel: PCI: CLS 0 bytes, default 64 Apr 12 19:14:59 volumio kernel: Trying to unpack rootfs image as initramfs... Apr 12 19:14:59 volumio kernel: Freeing initrd memory: 13036K Apr 12 19:14:59 volumio kernel: Initialise system trusted keyrings Apr 12 19:14:59 volumio kernel: workingset: timestamp_bits=14 max_order=19 bucket_order=5 Apr 12 19:14:59 volumio kernel: zbud: loaded Apr 12 19:14:59 volumio kernel: FS-Cache: Netfs 'nfs' registered for caching Apr 12 19:14:59 volumio kernel: NFS: Registering the id_resolver key type Apr 12 19:14:59 volumio kernel: Key type id_resolver registered Apr 12 19:14:59 volumio kernel: Key type id_legacy registered Apr 12 19:14:59 volumio kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Apr 12 19:14:59 volumio kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Apr 12 19:14:59 volumio kernel: Key type asymmetric registered Apr 12 19:14:59 volumio kernel: Asymmetric key parser 'x509' registered Apr 12 19:14:59 volumio kernel: bounce: pool size: 64 pages Apr 12 19:14:59 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Apr 12 19:14:59 volumio kernel: io scheduler mq-deadline registered Apr 12 19:14:59 volumio kernel: io scheduler kyber registered Apr 12 19:14:59 volumio kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Apr 12 19:14:59 volumio kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Apr 12 19:14:59 volumio kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Apr 12 19:14:59 volumio kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x007fffffff -> 0x0400000000 Apr 12 19:14:59 volumio kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Apr 12 19:14:59 volumio kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Apr 12 19:14:59 volumio kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Apr 12 19:14:59 volumio kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Apr 12 19:14:59 volumio kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Apr 12 19:14:59 volumio kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Apr 12 19:14:59 volumio kernel: PCI: bus0: Fast back to back transfers disabled Apr 12 19:14:59 volumio kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Apr 12 19:14:59 volumio kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Apr 12 19:14:59 volumio kernel: pci 0000:01:00.0: PME# supported from D0 D3cold Apr 12 19:14:59 volumio kernel: PCI: bus1: Fast back to back transfers disabled Apr 12 19:14:59 volumio kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Apr 12 19:14:59 volumio kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Apr 12 19:14:59 volumio kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Apr 12 19:14:59 volumio kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Apr 12 19:14:59 volumio kernel: pcieport 0000:00:00.0: enabling device (0140 -> 0142) Apr 12 19:14:59 volumio kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 62 Apr 12 19:14:59 volumio kernel: bcm2708_fb soc:fb: FB found 1 display(s) Apr 12 19:14:59 volumio kernel: Console: switching to colour frame buffer device 80x30 Apr 12 19:14:59 volumio kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Apr 12 19:14:59 volumio kernel: iproc-rng200 fe104000.rng: hwrng registered Apr 12 19:14:59 volumio kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Apr 12 19:14:59 volumio kernel: gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000 Apr 12 19:14:59 volumio kernel: brd: module loaded Apr 12 19:14:59 volumio kernel: loop: module loaded Apr 12 19:14:59 volumio kernel: Loading iSCSI transport class v2.0-870. Apr 12 19:14:59 volumio kernel: libphy: Fixed MDIO Bus: probed Apr 12 19:14:59 volumio kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Apr 12 19:14:59 volumio kernel: libphy: bcmgenet MII bus: probed Apr 12 19:14:59 volumio kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Apr 12 19:14:59 volumio kernel: usbcore: registered new interface driver r8152 Apr 12 19:14:59 volumio kernel: usbcore: registered new interface driver lan78xx Apr 12 19:14:59 volumio kernel: usbcore: registered new interface driver smsc95xx Apr 12 19:14:59 volumio kernel: xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142) Apr 12 19:14:59 volumio kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Apr 12 19:14:59 volumio kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Apr 12 19:14:59 volumio kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x00000e0000000890 Apr 12 19:14:59 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 Apr 12 19:14:59 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 12 19:14:59 volumio kernel: usb usb1: Product: xHCI Host Controller Apr 12 19:14:59 volumio kernel: usb usb1: Manufacturer: Linux 5.10.92-v7l+ xhci-hcd Apr 12 19:14:59 volumio kernel: usb usb1: SerialNumber: 0000:01:00.0 Apr 12 19:14:59 volumio kernel: hub 1-0:1.0: USB hub found Apr 12 19:14:59 volumio kernel: hub 1-0:1.0: 1 port detected Apr 12 19:14:59 volumio kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Apr 12 19:14:59 volumio kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Apr 12 19:14:59 volumio kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Apr 12 19:14:59 volumio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 Apr 12 19:14:59 volumio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 12 19:14:59 volumio kernel: usb usb2: Product: xHCI Host Controller Apr 12 19:14:59 volumio kernel: usb usb2: Manufacturer: Linux 5.10.92-v7l+ xhci-hcd Apr 12 19:14:59 volumio kernel: usb usb2: SerialNumber: 0000:01:00.0 Apr 12 19:14:59 volumio kernel: hub 2-0:1.0: USB hub found Apr 12 19:14:59 volumio kernel: hub 2-0:1.0: 4 ports detected Apr 12 19:14:59 volumio kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Apr 12 19:14:59 volumio kernel: dwc_otg: FIQ enabled Apr 12 19:14:59 volumio kernel: dwc_otg: NAK holdoff enabled Apr 12 19:14:59 volumio kernel: dwc_otg: FIQ split-transaction FSM enabled Apr 12 19:14:59 volumio kernel: Module dwc_common_port init Apr 12 19:14:59 volumio kernel: usbcore: registered new interface driver uas Apr 12 19:14:59 volumio kernel: usbcore: registered new interface driver usb-storage Apr 12 19:14:59 volumio kernel: mousedev: PS/2 mouse device common for all mice Apr 12 19:14:59 volumio kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Apr 12 19:14:59 volumio kernel: sdhci: Secure Digital Host Controller Interface driver Apr 12 19:14:59 volumio kernel: sdhci: Copyright(c) Pierre Ossman Apr 12 19:14:59 volumio kernel: mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe Apr 12 19:14:59 volumio kernel: sdhci-pltfm: SDHCI platform and OF driver helper Apr 12 19:14:59 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Apr 12 19:14:59 volumio kernel: hid: raw HID events driver (C) Jiri Kosina Apr 12 19:14:59 volumio kernel: usbcore: registered new interface driver usbhid Apr 12 19:14:59 volumio kernel: usbhid: USB HID core driver Apr 12 19:14:59 volumio kernel: Initializing XFRM netlink socket Apr 12 19:14:59 volumio kernel: NET: Registered protocol family 17 Apr 12 19:14:59 volumio kernel: Key type dns_resolver registered Apr 12 19:14:59 volumio kernel: Registering SWP/SWPB emulation handler Apr 12 19:14:59 volumio kernel: registered taskstats version 1 Apr 12 19:14:59 volumio kernel: Loading compiled-in X.509 certificates Apr 12 19:14:59 volumio kernel: Key type ._fscrypt registered Apr 12 19:14:59 volumio kernel: Key type .fscrypt registered Apr 12 19:14:59 volumio kernel: Key type fscrypt-provisioning registered Apr 12 19:14:59 volumio kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Apr 12 19:14:59 volumio kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Apr 12 19:14:59 volumio kernel: fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2 Apr 12 19:14:59 volumio kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Apr 12 19:14:59 volumio kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Apr 12 19:14:59 volumio kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Apr 12 19:14:59 volumio kernel: of_cfs_init Apr 12 19:14:59 volumio kernel: of_cfs_init: OK Apr 12 19:14:59 volumio kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Apr 12 19:14:59 volumio kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Apr 12 19:14:59 volumio kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Apr 12 19:14:59 volumio kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Apr 12 19:14:59 volumio kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Apr 12 19:14:59 volumio kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Apr 12 19:14:59 volumio kernel: Freeing unused kernel memory: 2048K Apr 12 19:14:59 volumio kernel: Run /init as init process Apr 12 19:14:59 volumio kernel: with arguments: Apr 12 19:14:59 volumio kernel: /init Apr 12 19:14:59 volumio kernel: splash Apr 12 19:14:59 volumio kernel: with environment: Apr 12 19:14:59 volumio kernel: HOME=/ Apr 12 19:14:59 volumio kernel: TERM=linux Apr 12 19:14:59 volumio kernel: imgpart=/dev/mmcblk0p2 Apr 12 19:14:59 volumio kernel: imgfile=/volumio_current.sqsh Apr 12 19:14:59 volumio kernel: bootdelay=5 Apr 12 19:14:59 volumio kernel: mmc1: new high speed SDIO card at address 0001 Apr 12 19:14:59 volumio kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Apr 12 19:14:59 volumio kernel: mmcblk0: mmc0:aaaa SC32G 29.7 GiB Apr 12 19:14:59 volumio kernel: mmcblk0: p1 p2 p3 Apr 12 19:14:59 volumio kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Apr 12 19:14:59 volumio initramfs: Booting Volumio for BCM2711 Apr 12 19:14:59 volumio initramfs: This script mounts rootfs RO with an overlay RW layer. Apr 12 19:14:59 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 12 19:14:59 volumio initramfs: IMGPART=/dev/mmcblk0p2 Apr 12 19:14:59 volumio initramfs: IMGFILE=/volumio_current.sqsh Apr 12 19:14:59 volumio initramfs: Boot delay (except first time) will be 5 seconds Apr 12 19:14:59 volumio initramfs: /dev/mmcblk0p2: Apr 12 19:14:59 volumio kernel: EXT4-fs (mmcblk0p2): recovery complete Apr 12 19:14:59 volumio kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Apr 12 19:14:59 volumio initramfs: Doing a 5 second delay here to give kernel load a headstart Apr 12 19:14:59 volumio kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Apr 12 19:14:59 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Apr 12 19:14:59 volumio kernel: usb 1-1: Product: USB2.0 Hub Apr 12 19:14:59 volumio kernel: hub 1-1:1.0: USB hub found Apr 12 19:14:59 volumio kernel: hub 1-1:1.0: 4 ports detected Apr 12 19:14:59 volumio kernel: usb 1-1.2: new high-speed USB device number 3 using xhci_hcd Apr 12 19:14:59 volumio kernel: usb 1-1.2: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= 1.04 Apr 12 19:14:59 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Apr 12 19:14:59 volumio kernel: usb 1-1.2: Product: Khadas Tone Control Apr 12 19:14:59 volumio kernel: usb 1-1.2: Manufacturer: XMOS Apr 12 19:14:59 volumio initramfs: Checking for USB updates Apr 12 19:14:59 volumio initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Apr 12 19:14:59 volumio kernel: EXT4-fs (mmcblk0p3): recovery complete Apr 12 19:14:59 volumio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Apr 12 19:14:59 volumio initramfs: With Option: Apr 12 19:14:59 volumio initramfs: VOLUMIO_VERSION="3.251" Apr 12 19:14:59 volumio initramfs: Finish initramfs, continue booting Volumio Apr 12 19:14:59 volumio systemd[1]: System time before build time, advancing clock. Apr 12 19:14:59 volumio kernel: NET: Registered protocol family 10 Apr 12 19:14:59 volumio kernel: Segment Routing with IPv6 Apr 12 19:14:59 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) Apr 12 19:14:59 volumio systemd[1]: Detected architecture arm. Apr 12 19:14:59 volumio systemd[1]: Set hostname to . Apr 12 19:14:59 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. Apr 12 19:14:59 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Apr 12 19:14:59 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Apr 12 19:14:59 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Apr 12 19:14:59 volumio systemd[1]: Created slice system-systemd\x2dfsck.slice. Apr 12 19:14:59 volumio systemd[1]: Listening on Journal Socket. Apr 12 19:14:59 volumio systemd[1]: Started dynamicswap service. Apr 12 19:14:59 volumio systemd[1]: Listening on udev Kernel Socket. Apr 12 19:14:59 volumio systemd[1]: Condition check resulted in Huge Pages File System being skipped. Apr 12 19:14:59 volumio systemd[1]: Reached target Swap. Apr 12 19:14:59 volumio kernel: i2c /dev entries driver Apr 12 19:14:59 volumio systemd-journald[203]: Journal started Apr 12 19:14:59 volumio systemd-journald[203]: Runtime journal (/run/log/journal/785f013f15a7766cb54a2cb06217cae8) is 3.7M, max 30.0M, 26.2M free. Apr 12 19:14:59 volumio systemd-modules-load[191]: Inserted module 'i2c_dev' Apr 12 19:14:59 volumio fake-hwclock[199]: Tue Apr 12 19:14:59 UTC 2022 Apr 12 19:14:59 volumio systemd[1]: Started Create Static Device Nodes in /dev. Apr 12 19:14:59 volumio systemd[1]: Starting udev Kernel Device Manager... Apr 12 19:14:59 volumio systemd[1]: Reached target Local File Systems (Pre). Apr 12 19:14:59 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Apr 12 19:14:59 volumio systemd[1]: Mounting /tmp... Apr 12 19:14:59 volumio systemd[1]: Mounting /var/spool/cups... Apr 12 19:14:59 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Apr 12 19:14:59 volumio systemd[1]: Mounting /var/log... Apr 12 19:14:59 volumio systemd[1]: Mounted /tmp. Apr 12 19:14:59 volumio systemd[1]: Mounted /var/spool/cups. Apr 12 19:14:59 volumio systemd[1]: Mounted /var/log. Apr 12 19:14:59 volumio systemd-udevd[220]: Network interface NamePolicy= disabled on kernel command line, ignoring. Apr 12 19:14:59 volumio systemd[1]: Starting Flush Journal to Persistent Storage... Apr 12 19:14:59 volumio systemd[1]: Mounting /var/spool/cups/tmp... Apr 12 19:14:59 volumio systemd[1]: Mounted /var/spool/cups/tmp. Apr 12 19:14:59 volumio systemd[1]: Started udev Kernel Device Manager. Apr 12 19:14:59 volumio systemd-journald[203]: Runtime journal (/run/log/journal/785f013f15a7766cb54a2cb06217cae8) is 3.7M, max 30.0M, 26.2M free. Apr 12 19:14:59 volumio systemd[1]: Started Flush Journal to Persistent Storage. Apr 12 19:14:59 volumio systemd[1]: Started udev Coldplug all Devices. Apr 12 19:14:59 volumio systemd[1]: Starting Helper to synchronize boot up for ifupdown... Apr 12 19:14:59 volumio systemd[1]: Starting Show Plymouth Boot Screen... Apr 12 19:14:59 volumio systemd[1]: Received SIGRTMIN+20 from PID 243 (plymouthd). Apr 12 19:14:59 volumio systemd[1]: Started Show Plymouth Boot Screen. Apr 12 19:14:59 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 12 19:14:59 volumio systemd[1]: Reached target Paths. Apr 12 19:14:59 volumio systemd[1]: Reached target Local Encrypted Volumes. Apr 12 19:14:59 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Apr 12 19:14:59 volumio kernel: rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000 Apr 12 19:14:59 volumio kernel: rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000 Apr 12 19:14:59 volumio kernel: rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000 Apr 12 19:14:59 volumio kernel: rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000 Apr 12 19:14:59 volumio systemd-udevd[227]: Using default interface naming scheme 'v240'. Apr 12 19:14:59 volumio kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Apr 12 19:14:59 volumio kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Apr 12 19:14:59 volumio kernel: mc: Linux media interface: v0.10 Apr 12 19:14:59 volumio kernel: [vc_sm_connected_init]: start Apr 12 19:14:59 volumio kernel: [vc_sm_connected_init]: installed successfully Apr 12 19:15:00 volumio systemd-udevd[227]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 12 19:15:00 volumio kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Apr 12 19:15:00 volumio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Apr 12 19:15:00 volumio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Apr 12 19:15:00 volumio kernel: videodev: Linux video capture interface: v2.00 Apr 12 19:15:00 volumio systemd[1]: Found device /dev/mmcblk0p1. Apr 12 19:15:00 volumio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Apr 12 19:15:00 volumio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Apr 12 19:15:00 volumio kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Apr 12 19:15:00 volumio kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Apr 12 19:15:00 volumio kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Apr 12 19:15:00 volumio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Apr 12 19:15:00 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Apr 12 19:15:00 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Apr 12 19:15:00 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Apr 12 19:15:00 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Apr 12 19:15:00 volumio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Apr 12 19:15:00 volumio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Apr 12 19:15:00 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Apr 12 19:15:00 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Apr 12 19:15:00 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Apr 12 19:15:00 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Apr 12 19:15:00 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Apr 12 19:15:00 volumio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Apr 12 19:15:00 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Apr 12 19:15:00 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Apr 12 19:15:00 volumio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Apr 12 19:15:00 volumio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Apr 12 19:15:00 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Apr 12 19:15:00 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Apr 12 19:15:00 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Apr 12 19:15:00 volumio kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Apr 12 19:15:00 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Apr 12 19:15:00 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Apr 12 19:15:00 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Apr 12 19:15:00 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Apr 12 19:15:00 volumio systemd-udevd[231]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 12 19:15:01 volumio kernel: snd_usb_audio: unknown parameter 'nrpacks' ignored Apr 12 19:15:01 volumio kernel: usb 1-1.2: 1:3 : unsupported format bits 0x100000000 Apr 12 19:15:01 volumio kernel: usbcore: registered new interface driver snd-usb-audio Apr 12 19:15:01 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Apr 12 19:15:01 volumio kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Apr 12 19:15:01 volumio kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Apr 12 19:15:01 volumio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Apr 12 19:15:01 volumio kernel: usbcore: registered new interface driver brcmfmac Apr 12 19:15:02 volumio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Apr 12 19:15:02 volumio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Apr 12 19:15:02 volumio kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan 4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e Apr 12 19:15:03 volumio systemd-udevd[233]: Using default interface naming scheme 'v240'. Apr 12 19:15:03 volumio systemd-udevd[233]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 12 19:15:03 volumio systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Apr 12 19:15:03 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Apr 12 19:15:03 volumio systemd[1]: Condition check resulted in FUSE Control File System being skipped. Apr 12 19:15:03 volumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Apr 12 19:15:03 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 12 19:15:03 volumio systemd[1]: Condition check resulted in Huge Pages File System being skipped. Apr 12 19:15:03 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Apr 12 19:15:03 volumio systemd[1]: Starting File System Check on /dev/mmcblk0p1... Apr 12 19:15:03 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Apr 12 19:15:03 volumio systemd[1]: Started File System Check Daemon to report status. Apr 12 19:15:03 volumio systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Apr 12 19:15:03 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Apr 12 19:15:03 volumio systemd-fsck[358]: fsck.fat 4.1 (2017-01-24) Apr 12 19:15:03 volumio systemd-fsck[358]: /dev/mmcblk0p1: 385 files, 28031/46774 clusters Apr 12 19:15:03 volumio systemd[1]: Started File System Check on /dev/mmcblk0p1. Apr 12 19:15:03 volumio systemd[1]: Mounting /boot... Apr 12 19:15:03 volumio systemd[1]: Mounted /boot. Apr 12 19:15:03 volumio systemd[1]: Reached target Local File Systems. Apr 12 19:15:03 volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Apr 12 19:15:03 volumio systemd-udevd[237]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Apr 12 19:15:03 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Apr 12 19:15:03 volumio systemd[1]: Started ifup for eth0. Apr 12 19:15:03 volumio systemd[1]: Starting Create Volatile Files and Directories... Apr 12 19:15:03 volumio systemd[1]: Starting Preprocess NFS configuration... Apr 12 19:15:03 volumio systemd[1]: Started ifup for wlan0. Apr 12 19:15:03 volumio systemd[1]: Started Helper to synchronize boot up for ifupdown. Apr 12 19:15:03 volumio systemd[1]: plymouth-read-write.service: Succeeded. Apr 12 19:15:03 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Apr 12 19:15:03 volumio systemd[1]: Received SIGRTMIN+20 from PID 243 (plymouthd). Apr 12 19:15:03 volumio systemd[1]: Starting Raise network interfaces... Apr 12 19:15:03 volumio systemd[1]: nfs-config.service: Succeeded. Apr 12 19:15:03 volumio systemd[1]: Started Preprocess NFS configuration. Apr 12 19:15:03 volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Apr 12 19:15:03 volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Apr 12 19:15:03 volumio systemd[1]: Reached target NFS client services. Apr 12 19:15:03 volumio systemd[1]: Started Create Volatile Files and Directories. Apr 12 19:15:03 volumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Apr 12 19:15:03 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Apr 12 19:15:03 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Apr 12 19:15:03 volumio systemd[1]: Starting RPC bind portmap service... Apr 12 19:15:03 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Apr 12 19:15:04 volumio systemd[1]: Reached target System Initialization. Apr 12 19:15:04 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 12 19:15:04 volumio kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Apr 12 19:15:04 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Apr 12 19:15:04 volumio systemd[1]: Listening on mpd.socket. Apr 12 19:15:04 volumio systemd[1]: Started Daily apt download activities. Apr 12 19:15:04 volumio systemd[1]: Started Daily apt upgrade and clean activities. Apr 12 19:15:04 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Apr 12 19:15:04 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Apr 12 19:15:04 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Apr 12 19:15:04 volumio systemd[1]: Reached target Timers. Apr 12 19:15:04 volumio systemd[1]: Listening on triggerhappy.socket. Apr 12 19:15:04 volumio systemd[1]: Reached target Sockets. Apr 12 19:15:04 volumio systemd[1]: Reached target Basic System. Apr 12 19:15:04 volumio systemd[1]: Starting Configure Bluetooth Modems connected by UART... Apr 12 19:15:04 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 12 19:15:04 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Apr 12 19:15:04 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Apr 12 19:15:04 volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Apr 12 19:15:04 volumio systemd[1]: Starting Wireless Services... Apr 12 19:15:04 volumio systemd[1]: Started Volumio Iptables Module. Apr 12 19:15:04 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Apr 12 19:15:04 volumio systemd[1]: Starting Login Service... Apr 12 19:15:04 volumio systemd[1]: Started Manage Sound Card State (restore and store). Apr 12 19:15:04 volumio systemd[1]: Starting Save/Restore Sound Card State... Apr 12 19:15:04 volumio alsactl[480]: alsactl 1.1.8 daemon started Apr 12 19:15:04 volumio systemd[1]: Started volumio-remote-updater.service. Apr 12 19:15:04 volumio systemd[1]: Started D-Bus System Message Bus. Apr 12 19:15:04 volumio systemd[1]: Starting WPA supplicant... Apr 12 19:15:04 volumio systemd[1]: Starting dhcpcd on all interfaces... Apr 12 19:15:04 volumio systemd[1]: Starting triggerhappy global hotkey daemon... Apr 12 19:15:04 volumio systemd[1]: Started RPC bind portmap service. Apr 12 19:15:04 volumio systemd[1]: Started Save/Restore Sound Card State. Apr 12 19:15:04 volumio thd[495]: Unable to parse trigger line: Apr 12 19:15:04 volumio thd[495]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Apr 12 19:15:04 volumio thd[495]: Unable to parse trigger line: Apr 12 19:15:04 volumio thd[495]: Unable to parse trigger line: Apr 12 19:15:04 volumio thd[495]: Found socket passed from systemd Apr 12 19:15:04 volumio systemd[1]: Started triggerhappy global hotkey daemon. Apr 12 19:15:04 volumio ifup[377]: ifup: waiting for lock on /run/network/ifstate.wlan0 Apr 12 19:15:04 volumio systemd[1]: Reached target Sound Card. Apr 12 19:15:04 volumio sh[373]: wlan0=wlan0 Apr 12 19:15:04 volumio systemd[1]: Reached target RPC Port Mapper. Apr 12 19:15:04 volumio systemd[1]: Reached target Remote File Systems (Pre). Apr 12 19:15:04 volumio systemd[1]: Reached target Remote File Systems. Apr 12 19:15:04 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Apr 12 19:15:04 volumio sh[366]: eth0=eth0 Apr 12 19:15:04 volumio avahi-daemon[454]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Apr 12 19:15:04 volumio avahi-daemon[454]: Successfully dropped root privileges. Apr 12 19:15:04 volumio avahi-daemon[454]: avahi-daemon 0.7 starting up. Apr 12 19:15:04 volumio systemd-logind[471]: New seat seat0. Apr 12 19:15:04 volumio kernel: uart-pl011 fe201000.serial: no DMA platform data Apr 12 19:15:04 volumio dhcpcd[494]: dev: loaded udev Apr 12 19:15:05 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Apr 12 19:15:05 volumio dhcpcd[494]: forked to background, child pid 537 Apr 12 19:15:05 volumio systemd[1]: Started Login Service. Apr 12 19:15:05 volumio systemd[1]: Started dhcpcd on all interfaces. Apr 12 19:15:05 volumio avahi-daemon[454]: Successfully called chroot(). Apr 12 19:15:05 volumio avahi-daemon[454]: Successfully dropped remaining capabilities. Apr 12 19:15:05 volumio avahi-daemon[454]: No service file found in /etc/avahi/services. Apr 12 19:15:05 volumio avahi-daemon[454]: Network interface enumeration completed. Apr 12 19:15:05 volumio avahi-daemon[454]: Server startup complete. Host name is volumio.local. Local service cookie is 878228992. Apr 12 19:15:05 volumio systemd[1]: Started Raise network interfaces. Apr 12 19:15:05 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Apr 12 19:15:05 volumio dhcpcd-run-hooks[568]: wlan0: ctrl_interface not defined in /etc/wpa_supplicant/wpa_supplicant.conf Apr 12 19:15:05 volumio dhcpcd-run-hooks[570]: wlan0: not interacting with wpa_supplicant(8) Apr 12 19:15:05 volumio dhcpcd[537]: wlan0: connected to Access Point `' Apr 12 19:15:05 volumio dhcpcd[537]: eth0: waiting for carrier Apr 12 19:15:05 volumio dhcpcd[537]: wlan0: waiting for carrier Apr 12 19:15:06 volumio loadcpufreq[508]: Loading cpufreq kernel modules...done (none). Apr 12 19:15:06 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Apr 12 19:15:06 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Apr 12 19:15:06 volumio systemd[1]: iptables.service: Succeeded. Apr 12 19:15:06 volumio cpufrequtils[602]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Apr 12 19:15:06 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Apr 12 19:15:06 volumio volumio-remote-updater[483]: Error: No active session Apr 12 19:15:06 volumio volumio-remote-updater[483]: [2022-04-12 19:15:06] [info] asio async_connect error: system:111 (Connection refused) Apr 12 19:15:06 volumio volumio-remote-updater[483]: [2022-04-12 19:15:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 12 19:15:06 volumio volumio-remote-updater[483]: [2022-04-12 19:15:06] [error] handle_connect error: Underlying Transport Error Apr 12 19:15:06 volumio systemd[1]: Started WPA supplicant. Apr 12 19:15:06 volumio systemd[1]: Reached target Network. Apr 12 19:15:06 volumio volumio[453]: Could not open config: /tmp/upmpdcli.conf Apr 12 19:15:06 volumio systemd[1]: Starting Music Player Daemon... Apr 12 19:15:06 volumio wpa_supplicant[491]: Successfully initialized wpa_supplicant Apr 12 19:15:06 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Apr 12 19:15:06 volumio systemd[1]: Reached target Network is Online. Apr 12 19:15:06 volumio systemd[1]: Starting Samba NMB Daemon... Apr 12 19:15:06 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Apr 12 19:15:06 volumio systemd[1]: Starting OpenBSD Secure Shell server... Apr 12 19:15:06 volumio systemd[1]: Starting Permit User Sessions... Apr 12 19:15:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 12 19:15:06 volumio systemd[1]: Starting Network Time Service... Apr 12 19:15:06 volumio systemd[1]: Starting /etc/rc.local Compatibility... Apr 12 19:15:06 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 12 19:15:06 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 12 19:15:06 volumio systemd[1]: Started Permit User Sessions. Apr 12 19:15:06 volumio systemd[1]: Started /etc/rc.local Compatibility. Apr 12 19:15:06 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Apr 12 19:15:06 volumio systemd[1]: Starting Hold until boot process finishes up... Apr 12 19:15:06 volumio systemd[1]: Received SIGRTMIN+21 from PID 243 (plymouthd). Apr 12 19:15:06 volumio systemd[1]: Received SIGRTMIN+21 from PID 243 (plymouthd). Apr 12 19:15:06 volumio ifplugd[624]: Network Interface Plugging Daemon...error: unexpectedly disconnected from boot status daemon Apr 12 19:15:06 volumio systemd[1]: plymouth-quit.service: Succeeded. Apr 12 19:15:06 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Apr 12 19:15:06 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Apr 12 19:15:06 volumio systemd[1]: Started Hold until boot process finishes up. Apr 12 19:15:06 volumio systemd[1]: Started Getty on tty1. Apr 12 19:15:06 volumio systemd[1]: Reached target Login Prompts. Apr 12 19:15:06 volumio systemd[1]: plymouth-start.service: Succeeded. Apr 12 19:15:06 volumio ifplugd(eth0)[653]: ifplugd 0.28 initializing. Apr 12 19:15:06 volumio ifplugd(eth0)[653]: Using interface eth0/DC:A6:32:52:37:2D with driver (version: 5.10.92-v7l+) Apr 12 19:15:06 volumio ifplugd(eth0)[653]: Using detection mode: SIOCETHTOOL Apr 12 19:15:06 volumio ifplugd(eth0)[653]: Initialization complete, link beat not detected. Apr 12 19:15:06 volumio ntpd[631]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 12 19:15:06 volumio ntpd[631]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Apr 12 19:15:06 volumio ntpd[656]: proto: precision = 0.796 usec (-20) Apr 12 19:15:06 volumio systemd[1]: Started Network Time Service. Apr 12 19:15:06 volumio ntpd[656]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 12 19:15:06 volumio ntpd[656]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 12 19:15:06 volumio ntpd[656]: Listen and drop on 0 v6wildcard [::]:123 Apr 12 19:15:06 volumio ntpd[656]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 12 19:15:06 volumio ntpd[656]: Listen normally on 2 lo 127.0.0.1:123 Apr 12 19:15:06 volumio ntpd[656]: Listening on routing socket on fd #19 for interface updates Apr 12 19:15:06 volumio ntpd[656]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 12 19:15:06 volumio ntpd[656]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 12 19:15:06 volumio ifplugd[624]: start eth0...done. Apr 12 19:15:06 volumio systemd[1]: Started LSB: Brings up/down network automatically. Apr 12 19:15:07 volumio haveged[418]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Apr 12 19:15:07 volumio haveged[418]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Apr 12 19:15:07 volumio haveged[418]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99937 Apr 12 19:15:07 volumio haveged[418]: haveged: fills: 0, generated: 0 Apr 12 19:15:07 volumio kernel: random: crng init done Apr 12 19:15:07 volumio kernel: random: 7 urandom warning(s) missed due to ratelimiting Apr 12 19:15:07 volumio sshd[658]: Server listening on 0.0.0.0 port 22. Apr 12 19:15:07 volumio sshd[658]: Server listening on :: port 22. Apr 12 19:15:07 volumio systemd[1]: Started OpenBSD Secure Shell server. Apr 12 19:15:07 volumio ntpd[656]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 12 19:15:07 volumio nmbd[634]: [2022/04/12 19:15:07.882448, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Apr 12 19:15:07 volumio nmbd[634]: started asyncdns process 665 Apr 12 19:15:07 volumio nmbd[634]: [2022/04/12 19:15:07.885391, 0] ../lib/util/become_daemon.c:149(daemon_status) Apr 12 19:15:07 volumio nmbd[634]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Apr 12 19:15:07 volumio nmbd[634]: [2022/04/12 19:15:07.885555, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Apr 12 19:15:07 volumio nmbd[634]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Apr 12 19:15:08 volumio dhcpcd[537]: eth0: carrier acquired Apr 12 19:15:08 volumio dhcpcd[537]: DUID 00:01:00:01:29:aa:89:1c:dc:a6:32:52:37:2d Apr 12 19:15:08 volumio dhcpcd[537]: eth0: IAID 32:52:37:2d Apr 12 19:15:08 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Apr 12 19:15:08 volumio dhcpcd[537]: eth0: adding address fe80::dea6:32ff:fe52:372d Apr 12 19:15:08 volumio dhcpcd[537]: ipv6_addaddr1: Permission denied Apr 12 19:15:08 volumio dhcpcd[537]: eth0: probing address 192.168.1.121/24 Apr 12 19:15:08 volumio systemd[1]: systemd-rfkill.service: Succeeded. Apr 12 19:15:08 volumio wireless.js[458]: WIRELESS DAEMON: start Apr 12 19:15:08 volumio wireless.js[458]: WIRELESS: Loaded configuration Apr 12 19:15:08 volumio wireless.js[458]: Cleaning previous... Apr 12 19:15:08 volumio sudo[670]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 12 19:15:08 volumio sudo[670]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:08 volumio sudo[670]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:08 volumio sudo[672]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 12 19:15:08 volumio sudo[672]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:08 volumio ntpd[656]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 12 19:15:08 volumio dhcpcd[537]: eth0: soliciting an IPv6 router Apr 12 19:15:09 volumio mpd[648]: Apr 12 19:15 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 12 19:15:09 volumio ifplugd(eth0)[653]: Link beat detected. Apr 12 19:15:09 volumio sudo[672]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:09 volumio wireless.js[458]: Stopped aP Apr 12 19:15:09 volumio ifplugd(eth0)[653]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 12 19:15:09 volumio ifplugd(eth0)[653]: client: ifup: interface eth0 already configured Apr 12 19:15:09 volumio sudo[686]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 12 19:15:09 volumio sudo[686]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:09 volumio sudo[686]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:09 volumio sudo[688]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 12 19:15:09 volumio sudo[688]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:09 volumio sudo[688]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:09 volumio avahi-daemon[454]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Apr 12 19:15:09 volumio avahi-daemon[454]: New relevant interface wlan0.IPv4 for mDNS. Apr 12 19:15:09 volumio avahi-daemon[454]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Apr 12 19:15:09 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 12 19:15:09 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Apr 12 19:15:09 volumio ifplugd(eth0)[653]: Program executed successfully. Apr 12 19:15:09 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Apr 12 19:15:09 volumio dnsmasq[694]: dnsmasq: syntax check OK. Apr 12 19:15:09 volumio hostapd[695]: Configuration file: /etc/hostapd/hostapd.conf Apr 12 19:15:09 volumio hostapd[695]: wlan0: Could not connect to kernel driver Apr 12 19:15:09 volumio hostapd[695]: Using interface wlan0 with hwaddr dc:a6:32:52:37:2e and ssid "Volumio-C3200" Apr 12 19:15:09 volumio dhcpcd[537]: wlan0: carrier acquired Apr 12 19:15:09 volumio hostapd[695]: wlan0: interface state UNINITIALIZED->ENABLED Apr 12 19:15:09 volumio hostapd[695]: wlan0: AP-ENABLED Apr 12 19:15:09 volumio systemd[1]: hostapd.service: Can't open PID file /run/hostapd.pid (yet?) after start: No such file or directory Apr 12 19:15:09 volumio dnsmasq[703]: started, version 2.80 cachesize 150 Apr 12 19:15:09 volumio dnsmasq[703]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Apr 12 19:15:09 volumio dnsmasq-dhcp[703]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Apr 12 19:15:09 volumio dnsmasq[703]: reading /etc/resolv.conf Apr 12 19:15:09 volumio dnsmasq[703]: using nameserver 208.67.222.222#53 Apr 12 19:15:09 volumio dnsmasq[703]: using nameserver 208.67.220.220#53 Apr 12 19:15:09 volumio dnsmasq[703]: read /etc/hosts - 5 addresses Apr 12 19:15:09 volumio dhcpcd[537]: wlan0: IAID 32:52:37:2e Apr 12 19:15:09 volumio dhcpcd[537]: wlan0: adding address fe80::dea6:32ff:fe52:372e Apr 12 19:15:09 volumio dhcpcd[537]: ipv6_addaddr1: Permission denied Apr 12 19:15:09 volumio mpd[648]: Apr 12 19:15 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 12 19:15:09 volumio mpd[648]: Apr 12 19:15 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 12 19:15:09 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Apr 12 19:15:09 volumio systemd[1]: Started Music Player Daemon. Apr 12 19:15:09 volumio systemd[1]: wireless.service: Succeeded. Apr 12 19:15:09 volumio systemd[1]: Started Wireless Services. Apr 12 19:15:09 volumio systemd[1]: Started Volumio Backend Module. Apr 12 19:15:09 volumio systemd[1]: Started Volumio Streaming Daemon. Apr 12 19:15:09 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Apr 12 19:15:09 volumio systemd[1]: Reached target Host and Network Name Lookups. Apr 12 19:15:09 volumio ntpd[656]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 12 19:15:09 volumio volumio-streaming-daemon[713]: ############################ Apr 12 19:15:09 volumio volumio-streaming-daemon[713]: # Volumio Streaming Daemon # Apr 12 19:15:09 volumio volumio-streaming-daemon[713]: # Running on port 7777 # Apr 12 19:15:09 volumio volumio-streaming-daemon[713]: ############################ Apr 12 19:15:09 volumio volumio-streaming-daemon[713]: Environment: production Apr 12 19:15:09 volumio dhcpcd[537]: wlan0: soliciting an IPv6 router Apr 12 19:15:10 volumio dhcpcd[537]: wlan0: soliciting a DHCP lease Apr 12 19:15:10 volumio ntpd[656]: Listen normally on 3 wlan0 192.168.211.1:123 Apr 12 19:15:10 volumio ntpd[656]: new interface(s) found: waking up resolver Apr 12 19:15:10 volumio ntpd[656]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 12 19:15:11 volumio volumio[712]: info: ------------------------------------------- Apr 12 19:15:11 volumio volumio-remote-updater[483]: [2022-04-12 19:15:11] [connect] Successful connection Apr 12 19:15:11 volumio volumio[712]: info: ----- Volumio3 ---- Apr 12 19:15:11 volumio volumio[712]: info: ------------------------------------------- Apr 12 19:15:11 volumio volumio[712]: info: ----- System startup ---- Apr 12 19:15:11 volumio volumio[712]: info: ------------------------------------------- Apr 12 19:15:11 volumio kernel: Bluetooth: Core ver 2.22 Apr 12 19:15:11 volumio kernel: NET: Registered protocol family 31 Apr 12 19:15:11 volumio kernel: Bluetooth: HCI device and connection manager initialized Apr 12 19:15:11 volumio kernel: Bluetooth: HCI socket layer initialized Apr 12 19:15:11 volumio kernel: Bluetooth: L2CAP socket layer initialized Apr 12 19:15:11 volumio kernel: Bluetooth: SCO socket layer initialized Apr 12 19:15:11 volumio kernel: Bluetooth: HCI UART driver ver 2.3 Apr 12 19:15:11 volumio kernel: Bluetooth: HCI UART protocol H4 registered Apr 12 19:15:11 volumio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Apr 12 19:15:11 volumio kernel: Bluetooth: HCI UART protocol Broadcom registered Apr 12 19:15:11 volumio btuart[449]: bcm43xx_init Apr 12 19:15:11 volumio btuart[449]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd Apr 12 19:15:11 volumio btuart[449]: Set Controller UART speed to 3000000 bit/s Apr 12 19:15:11 volumio btuart[449]: Device setup complete Apr 12 19:15:11 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Apr 12 19:15:11 volumio systemd[1]: Started Configure Bluetooth Modems connected by UART. Apr 12 19:15:11 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Apr 12 19:15:11 volumio systemd[1]: Created slice system-bthelper.slice. Apr 12 19:15:11 volumio systemd[1]: Starting Raspberry Pi bluetooth helper... Apr 12 19:15:12 volumio bthelper[744]: Raspberry Pi BDADDR already set Apr 12 19:15:12 volumio systemd[1]: Started Raspberry Pi bluetooth helper. Apr 12 19:15:12 volumio systemd[1]: Starting Bluetooth service... Apr 12 19:15:12 volumio bluetoothd[754]: Bluetooth daemon 5.50 Apr 12 19:15:12 volumio bluetoothd[754]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Apr 12 19:15:12 volumio systemd[1]: Started Bluetooth service. Apr 12 19:15:12 volumio systemd[1]: Reached target Bluetooth. Apr 12 19:15:12 volumio bluetoothd[754]: Starting SDP server Apr 12 19:15:12 volumio bluetoothd[754]: Excluding (cli) sap Apr 12 19:15:12 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Apr 12 19:15:12 volumio kernel: Bluetooth: BNEP filters: protocol multicast Apr 12 19:15:12 volumio kernel: Bluetooth: BNEP socket layer initialized Apr 12 19:15:12 volumio bluetoothd[754]: Bluetooth management interface 1.18 initialized Apr 12 19:15:12 volumio dbus-daemon[489]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=754 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Apr 12 19:15:12 volumio systemd[1]: Starting Hostname Service... Apr 12 19:15:12 volumio bluetoothd[754]: Failed to set privacy: Rejected (0x0b) Apr 12 19:15:12 volumio dbus-daemon[489]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 12 19:15:12 volumio systemd[1]: Started Hostname Service. Apr 12 19:15:12 volumio volumio[712]: info: MYVOLUMIO Environment detected Apr 12 19:15:12 volumio volumio[712]: info: Plugin folders cleanup Apr 12 19:15:12 volumio volumio[712]: info: Scanning into folder /volumio/app/plugins/ Apr 12 19:15:12 volumio volumio[712]: info: Scanning category audio_interface Apr 12 19:15:12 volumio volumio[712]: info: Scanning category miscellanea Apr 12 19:15:12 volumio volumio[712]: info: Scanning category music_service Apr 12 19:15:12 volumio volumio[712]: info: Scanning category plugins.json Apr 12 19:15:12 volumio volumio[712]: info: Scanning category system_controller Apr 12 19:15:12 volumio volumio[712]: info: Scanning category user_interface Apr 12 19:15:12 volumio volumio[712]: info: Scanning into folder /data/plugins/ Apr 12 19:15:12 volumio volumio[712]: info: Scanning category audio_interface Apr 12 19:15:12 volumio volumio[712]: info: Plugin folders cleanup completed Apr 12 19:15:12 volumio volumio[712]: info: ------------------------------------------- Apr 12 19:15:12 volumio volumio[712]: info: ----- Core plugins startup ---- Apr 12 19:15:12 volumio volumio[712]: info: ------------------------------------------- Apr 12 19:15:12 volumio volumio[712]: info: Loading plugins from folder /volumio/app/plugins/ Apr 12 19:15:12 volumio volumio[712]: info: Adding plugin upnp to MyMusic Plugins Apr 12 19:15:12 volumio volumio[712]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 12 19:15:12 volumio volumio[712]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 12 19:15:12 volumio volumio[712]: info: Loading plugins from folder /data/plugins/ Apr 12 19:15:12 volumio volumio[712]: info: Loading plugin "system"... Apr 12 19:15:12 volumio volumio[712]: info: Loading plugin "appearance"... Apr 12 19:15:12 volumio nmbd[634]: [2022/04/12 19:15:12.910803, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 12 19:15:12 volumio systemd[1]: Started Samba NMB Daemon. Apr 12 19:15:12 volumio nmbd[634]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 12 19:15:12 volumio systemd[1]: Starting Samba Winbind Daemon... Apr 12 19:15:12 volumio dhcpcd[537]: eth0: using static address 192.168.1.121/24 Apr 12 19:15:12 volumio dhcpcd[537]: eth0: adding route to 192.168.1.0/24 Apr 12 19:15:12 volumio dhcpcd[537]: eth0: adding default route via 192.168.1.1 Apr 12 19:15:12 volumio avahi-daemon[454]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.121. Apr 12 19:15:12 volumio avahi-daemon[454]: New relevant interface eth0.IPv4 for mDNS. Apr 12 19:15:12 volumio avahi-daemon[454]: Registering new address record for 192.168.1.121 on eth0.IPv4. Apr 12 19:15:13 volumio dnsmasq[703]: reading /etc/resolv.conf Apr 12 19:15:13 volumio dnsmasq[703]: using nameserver 192.168.1.1#53 Apr 12 19:15:13 volumio dnsmasq[703]: using nameserver 208.67.222.222#53 Apr 12 19:15:13 volumio dnsmasq[703]: using nameserver 208.67.222.222#53 Apr 12 19:15:13 volumio dnsmasq[703]: using nameserver 208.67.220.220#53 Apr 12 19:15:13 volumio winbindd[759]: [2022/04/12 19:15:13.137802, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 12 19:15:13 volumio winbindd[759]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 12 19:15:13 volumio winbindd[759]: [2022/04/12 19:15:13.154326, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 12 19:15:13 volumio winbindd[759]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 12 19:15:13 volumio systemd[1]: Started Samba Winbind Daemon. Apr 12 19:15:13 volumio systemd[1]: Starting Samba SMB Daemon... Apr 12 19:15:13 volumio volumio[712]: info: Loading plugin "network"... Apr 12 19:15:13 volumio smbd[784]: [2022/04/12 19:15:13.572902, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 12 19:15:13 volumio smbd[784]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 12 19:15:13 volumio systemd[1]: Started Samba SMB Daemon. Apr 12 19:15:13 volumio systemd[1]: Reached target Multi-User System. Apr 12 19:15:13 volumio systemd[1]: Reached target Graphical Interface. Apr 12 19:15:13 volumio volumio[712]: info: Refreshing Cached IP Addresses Apr 12 19:15:13 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 12 19:15:13 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 12 19:15:13 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 12 19:15:13 volumio systemd[1]: Startup finished in 9.401s (kernel) + 16.315s (userspace) = 25.716s. Apr 12 19:15:13 volumio volumio[712]: info: Loading plugin "services"... Apr 12 19:15:13 volumio sudo[796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 12 19:15:13 volumio sudo[796]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:13 volumio sudo[794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 12 19:15:13 volumio sudo[794]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:13 volumio volumio[712]: info: Loading plugin "alsa_controller"... Apr 12 19:15:13 volumio sudo[796]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:13 volumio sudo[794]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:13 volumio sudo[798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 12 19:15:13 volumio sudo[798]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:13 volumio volumio[712]: info: Loading plugin "wizard"... Apr 12 19:15:13 volumio volumio[712]: info: Loading plugin "volumio_command_line_client"... Apr 12 19:15:13 volumio volumio[712]: info: Loading plugin "upnp"... Apr 12 19:15:13 volumio volumio[712]: info: [1649790913768] Starting Upmpd Daemon Apr 12 19:15:13 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 12 19:15:13 volumio volumio[712]: info: Loading plugin "my_music"... Apr 12 19:15:13 volumio volumio[712]: info: Loading plugin "mpd"... Apr 12 19:15:14 volumio volumio[712]: info: Creating MPD Configuration file Apr 12 19:15:14 volumio volumio[712]: info: Loading plugin "upnp_browser"... Apr 12 19:15:14 volumio sudo[807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 19:15:14 volumio sudo[807]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:14 volumio sudo[807]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:14 volumio sudo[809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 19:15:14 volumio sudo[809]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:14 volumio systemd[1]: Stopping Music Player Daemon... Apr 12 19:15:14 volumio systemd[1]: mpd.service: Succeeded. Apr 12 19:15:14 volumio systemd[1]: Stopped Music Player Daemon. Apr 12 19:15:14 volumio systemd[1]: Starting Music Player Daemon... Apr 12 19:15:14 volumio volumio[712]: info: Loading plugin "networkfs"... Apr 12 19:15:14 volumio volumio[712]: info: Starting Udev Watcher for removable devices Apr 12 19:15:14 volumio sudo[817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.136/Music/Lossy /mnt/NAS/Lossy Apr 12 19:15:14 volumio sudo[817]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:14 volumio ntpd[656]: Listen normally on 4 eth0 192.168.1.121:123 Apr 12 19:15:14 volumio ntpd[656]: new interface(s) found: waking up resolver Apr 12 19:15:14 volumio sudo[819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.136/Music/Lossless /mnt/NAS/Lossless Apr 12 19:15:14 volumio sudo[819]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:14 volumio kernel: FS-Cache: Netfs 'cifs' registered for caching Apr 12 19:15:14 volumio kernel: Key type cifs.spnego registered Apr 12 19:15:14 volumio kernel: Key type cifs.idmap registered Apr 12 19:15:14 volumio kernel: CIFS: Attempting to mount //192.168.1.136/Music/Lossy Apr 12 19:15:14 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Apr 12 19:15:14 volumio kernel: CIFS: Attempting to mount //192.168.1.136/Music/Lossless Apr 12 19:15:14 volumio kernel: FS-Cache: Duplicate cookie detected Apr 12 19:15:14 volumio kernel: FS-Cache: O-cookie c=996766a3 [p=ad4858dd fl=222 nc=0 na=1] Apr 12 19:15:14 volumio kernel: FS-Cache: O-cookie d=f7b621db n=d652fd5e Apr 12 19:15:14 volumio kernel: FS-Cache: O-key=[8] '020001bdc0a80188' Apr 12 19:15:14 volumio kernel: FS-Cache: N-cookie c=b0b530c9 [p=ad4858dd fl=2 nc=0 na=1] Apr 12 19:15:14 volumio kernel: FS-Cache: N-cookie d=f7b621db n=b524db70 Apr 12 19:15:14 volumio kernel: FS-Cache: N-key=[8] '020001bdc0a80188' Apr 12 19:15:15 volumio mpd[814]: Apr 12 19:15 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 12 19:15:15 volumio dhcpcd[537]: wlan0: probing for an IPv4LL address Apr 12 19:15:15 volumio volumio[712]: info: Ignoring mount for partition: boot Apr 12 19:15:15 volumio volumio[712]: info: Ignoring mount for partition: volumio Apr 12 19:15:15 volumio volumio[712]: info: Ignoring mount for partition: volumio_data Apr 12 19:15:15 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 12 19:15:15 volumio volumio[712]: info: Loading plugin "alarm-clock"... Apr 12 19:15:15 volumio volumio[712]: info: Loading plugin "airplay_emulation"... Apr 12 19:15:15 volumio volumio[712]: info: Starting Shairport Sync Apr 12 19:15:15 volumio volumio[712]: info: Loading plugin "last_100"... Apr 12 19:15:15 volumio volumio[712]: info: Loading plugin "webradio"... Apr 12 19:15:15 volumio volumio[712]: info: Loading plugin "i2s_dacs"... Apr 12 19:15:15 volumio volumio[712]: info: I2S DAC not set, start Auto-detection Apr 12 19:15:15 volumio volumio[712]: info: Loading plugin "volumiodiscovery"... Apr 12 19:15:15 volumio volumio[712]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 12 19:15:15 volumio node[712]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 12 19:15:15 volumio volumio[712]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 12 19:15:15 volumio node[712]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 12 19:15:15 volumio volumio[712]: *** WARNING *** For more information see Apr 12 19:15:15 volumio node[712]: *** WARNING *** For more information see Apr 12 19:15:15 volumio volumio[712]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 12 19:15:15 volumio node[712]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 12 19:15:15 volumio volumio[712]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 12 19:15:15 volumio node[712]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 12 19:15:15 volumio volumio[712]: *** WARNING *** For more information see Apr 12 19:15:15 volumio node[712]: *** WARNING *** For more information see Apr 12 19:15:15 volumio volumio[712]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 12 19:15:15 volumio volumio[712]: info: Discovery: Started advertising with name: Volumio Apr 12 19:15:15 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 12 19:15:15 volumio volumio[712]: info: Loading plugin "outputs"... Apr 12 19:15:15 volumio volumio[712]: info: Loading plugin "albumart"... Apr 12 19:15:15 volumio volumio[712]: info: Plugin example_plugin is not enabled Apr 12 19:15:15 volumio volumio[712]: info: Loading plugin "inputs"... Apr 12 19:15:15 volumio volumio[712]: info: Loading plugin "updater_comm"... Apr 12 19:15:15 volumio volumio[712]: info: Plugin mpdemulation is not enabled Apr 12 19:15:15 volumio mpd[814]: Apr 12 19:15 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 12 19:15:15 volumio mpd[814]: Apr 12 19:15 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 12 19:15:15 volumio volumio[712]: info: Loading plugin "rest_api"... Apr 12 19:15:15 volumio systemd[1]: Started Music Player Daemon. Apr 12 19:15:15 volumio sudo[809]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:15 volumio volumio[712]: info: Loading plugin "websocket"... Apr 12 19:15:15 volumio sudo[817]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:15 volumio sudo[819]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:15 volumio volumio[712]: info: Loading plugin "fusiondsp"... Apr 12 19:15:15 volumio volumio[712]: Forking 3 albumart workers Apr 12 19:15:16 volumio volumio[712]: info: Applying required configuration parameters for plugin fusiondsp Apr 12 19:15:16 volumio volumio[712]: Starting albumart workers Apr 12 19:15:16 volumio volumio[712]: Starting albumart workers Apr 12 19:15:16 volumio volumio[712]: Starting albumart workers Apr 12 19:15:16 volumio volumio[712]: info: Loading i18n strings for locale en Apr 12 19:15:16 volumio volumio[712]: Updating browse sources language Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::initPlayerControls Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:15:16 volumio volumio[712]: Express server listening on port 3000 Apr 12 19:15:16 volumio volumio[712]: [Metrics] WebUI: 5s 663.49ms Apr 12 19:15:16 volumio volumio[712]: info: CoreStateMachine::resetVolumioState Apr 12 19:15:16 volumio volumio[712]: info: CoreStateMachine::getcurrentVolume Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::volumioRetrievevolume Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: MPD Permissions set Apr 12 19:15:16 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 1 Apr 12 19:15:16 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 2 Apr 12 19:15:16 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 3 Apr 12 19:15:16 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 4 Apr 12 19:15:16 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 5 Apr 12 19:15:16 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 6 Apr 12 19:15:16 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 7 Apr 12 19:15:16 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 8 Apr 12 19:15:16 volumio volumio[712]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 12 19:15:16 volumio volumio[712]: info: MPD running with PID814 Apr 12 19:15:16 volumio volumio[712]: ,establishing connection Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:16 volumio volumio[712]: info: Reloading queue from file Apr 12 19:15:16 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 10 Apr 12 19:15:16 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 11 Apr 12 19:15:16 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 12 Apr 12 19:15:16 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 13 Apr 12 19:15:16 volumio volumio[712]: error: updateQueue error: null Apr 12 19:15:16 volumio systemd[1]: systemd-rfkill.service: Succeeded. Apr 12 19:15:16 volumio volumio[712]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 12 19:15:16 volumio volumio[712]: info: VolumeController:: Volume=35 Mute =false Apr 12 19:15:16 volumio volumio[712]: info: CoreStateMachine::pushState Apr 12 19:15:16 volumio volumio[712]: info: CorePlayQueue::getTrack 0 Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::volumioPushState Apr 12 19:15:16 volumio volumio[712]: info: CoreStateMachine::updateTrackBlock Apr 12 19:15:16 volumio volumio[712]: info: CorePlayQueue::getTrackBlock Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::volumioRetrievevolume Apr 12 19:15:16 volumio volumio[712]: info: Setting Device type: Raspberry PI Apr 12 19:15:16 volumio volumio[712]: info: CoreStateMachine::setRepeat null single undefined Apr 12 19:15:16 volumio volumio[712]: info: CoreStateMachine::pushState Apr 12 19:15:16 volumio volumio[712]: info: CorePlayQueue::getTrack 0 Apr 12 19:15:16 volumio volumio[712]: info: CoreCommandRouter::volumioPushState Apr 12 19:15:16 volumio volumio[712]: info: CoreStateMachine::setRandom null Apr 12 19:15:16 volumio volumio[712]: info: CoreStateMachine::pushState Apr 12 19:15:16 volumio volumio[712]: info: CorePlayQueue::getTrack 0 Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::volumioPushState Apr 12 19:15:17 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 14 Apr 12 19:15:17 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 15 Apr 12 19:15:17 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 16 Apr 12 19:15:17 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 17 Apr 12 19:15:17 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 18 Apr 12 19:15:17 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 19 Apr 12 19:15:17 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 20 Apr 12 19:15:17 volumio bthelper[744]: Changing power off succeeded Apr 12 19:15:17 volumio bthelper[744]: [CHG] Controller DC:A6:32:52:37:2F Powered: no Apr 12 19:15:17 volumio bthelper[744]: [CHG] Controller DC:A6:32:52:37:2F Discovering: no Apr 12 19:15:17 volumio bthelper[744]: [CHG] Controller DC:A6:32:52:37:2F Class: 0x00000000 Apr 12 19:15:17 volumio volumio[712]: info: Completed loading Core Plugins Apr 12 19:15:17 volumio volumio[712]: info: Preparing to generate the ALSA configuration file Apr 12 19:15:17 volumio volumio[712]: info: Discovery: adding 1e4dd501-bfe1-4a85-abab-bbfefce50f66 Apr 12 19:15:17 volumio volumio[712]: info: mDNS: Found device Volumio Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::volumioGetState Apr 12 19:15:17 volumio volumio[712]: info: CorePlayQueue::getTrack 0 Apr 12 19:15:17 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 21 Apr 12 19:15:17 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 22 Apr 12 19:15:17 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 23 Apr 12 19:15:17 volumio volumio[712]: info: VolumeController:: Volume=35 Mute =false Apr 12 19:15:17 volumio volumio[712]: info: CoreStateMachine::pushState Apr 12 19:15:17 volumio volumio[712]: info: CorePlayQueue::getTrack 0 Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::volumioPushState Apr 12 19:15:17 volumio volumio[712]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 12 19:15:17 volumio volumio[712]: info: Reading ALSA contributions from plugins. Apr 12 19:15:17 volumio volumio[712]: info: Discovery: this is already registered, 1e4dd501-bfe1-4a85-abab-bbfefce50f66 Apr 12 19:15:17 volumio volumio[712]: info: mDNS: Found device Volumio Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::volumioGetState Apr 12 19:15:17 volumio volumio[712]: info: CorePlayQueue::getTrack 0 Apr 12 19:15:17 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 24 Apr 12 19:15:17 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 25 Apr 12 19:15:17 volumio volumio[712]: info: Asound.conf file unchanged, so no further update is needed Apr 12 19:15:17 volumio volumio[712]: info: Output device has changed, restarting MPD Apr 12 19:15:17 volumio volumio[712]: info: Output device has changed, restarting Shairport Sync Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:15:17 volumio sudo[941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 19:15:17 volumio sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:17 volumio bthelper[744]: [CHG] Controller DC:A6:32:52:37:2F Class: 0x0000041c Apr 12 19:15:17 volumio bthelper[744]: Changing power on succeeded Apr 12 19:15:17 volumio sudo[941]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:17 volumio sudo[943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 19:15:17 volumio sudo[943]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:17 volumio systemd[1]: Stopping Music Player Daemon... Apr 12 19:15:17 volumio volumio[712]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 19:15:17 volumio volumio[712]: info: ___________ START PLUGINS ___________ Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 12 19:15:17 volumio volumio[712]: info: [1649790917258] CoreMusicLibrary::Adding element Media Servers Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 19:15:17 volumio volumio[712]: Cannot find translation for sourceMedia Servers Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:15:17 volumio volumio[712]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:15:17 volumio volumio[712]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 12 19:15:17 volumio volumio[712]: info: [1649790917345] CoreMusicLibrary::Adding element Last_100 Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 19:15:17 volumio volumio[712]: Cannot find translation for sourceMedia Servers Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 12 19:15:17 volumio volumio[712]: info: [1649790917348] CoreMusicLibrary::Adding element Webradio Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 19:15:17 volumio volumio[712]: Cannot find translation for sourceMedia Servers Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 12 19:15:17 volumio volumio[712]: info: Loading i18n strings for locale en Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 12 19:15:17 volumio systemd[1]: mpd.service: Succeeded. Apr 12 19:15:17 volumio systemd[1]: Stopped Music Player Daemon. Apr 12 19:15:17 volumio systemd[1]: Starting Music Player Daemon... Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:17 volumio sudo[798]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:17 volumio volumio[712]: info: AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Apr 12 19:15:17 volumio volumio[712]: info: CamillaGui loaded Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:17 volumio volumio[712]: info: mixtype--------------------- Hardware Apr 12 19:15:17 volumio volumio[712]: info: Volumio Calling Home Apr 12 19:15:17 volumio volumio[712]: info: Preparing to generate the ALSA configuration file Apr 12 19:15:17 volumio volumio[712]: info: Completed starting Core Plugins Apr 12 19:15:17 volumio volumio[712]: info: ------------------------------------------- Apr 12 19:15:17 volumio volumio[712]: info: ----- MyVolumio plugins startup ---- Apr 12 19:15:17 volumio volumio[712]: info: ------------------------------------------- Apr 12 19:15:17 volumio volumio[712]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 12 19:15:17 volumio volumio[712]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 12 19:15:17 volumio volumio[712]: info: Reading ALSA contributions from plugins. Apr 12 19:15:17 volumio volumio[712]: error: MPD error: Error: write EPIPE Apr 12 19:15:17 volumio volumio[712]: info: MPD Permissions set Apr 12 19:15:17 volumio volumio[712]: info: Volumio called home Apr 12 19:15:17 volumio volumio[712]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 26 Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:17 volumio volumio[712]: info: Starting Shairport Sync Apr 12 19:15:17 volumio volumio[712]: info: Starting Shairport Sync Apr 12 19:15:17 volumio volumio[712]: info: Starting Shairport Sync Apr 12 19:15:17 volumio sudo[974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 19:15:17 volumio sudo[974]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:17 volumio sudo[972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 19:15:17 volumio sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 12 19:15:17 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 12 19:15:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 12 19:15:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 12 19:15:17 volumio sudo[978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 19:15:17 volumio sudo[974]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:17 volumio sudo[978]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 12 19:15:17 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 12 19:15:17 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 12 19:15:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 12 19:15:17 volumio volumio[712]: info: Asound.conf file unchanged, so no further update is needed Apr 12 19:15:17 volumio volumio[712]: info: Output device has changed, restarting MPD Apr 12 19:15:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 12 19:15:17 volumio sudo[972]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 12 19:15:17 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 12 19:15:17 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 12 19:15:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 12 19:15:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 12 19:15:17 volumio volumio[712]: info: Output device has changed, restarting Shairport Sync Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:17 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:15:17 volumio sudo[978]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:17 volumio sudo[983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 19:15:17 volumio sudo[983]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:17 volumio sudo[983]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:17 volumio sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 19:15:17 volumio sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:17 volumio volumio[712]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 19:15:18 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 12 19:15:18 volumio systemd[1]: mpd.service: Succeeded. Apr 12 19:15:18 volumio systemd[1]: Stopped Music Player Daemon. Apr 12 19:15:18 volumio systemd[1]: Starting Music Player Daemon... Apr 12 19:15:18 volumio volumio[712]: info: Shairport-Sync Started Apr 12 19:15:18 volumio volumio[712]: Error adding Membership: Error: addMembership EINVAL Apr 12 19:15:18 volumio volumio[712]: info: MPD Permissions set Apr 12 19:15:18 volumio volumio[712]: info: Shairport-Sync Started Apr 12 19:15:18 volumio volumio[712]: info: Shairport-Sync Started Apr 12 19:15:18 volumio volumio[712]: info: CoreCommandRouter::volumioGetState Apr 12 19:15:18 volumio volumio[712]: info: CorePlayQueue::getTrack 0 Apr 12 19:15:18 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:18 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:18 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:18 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:18 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:18 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:18 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:18 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:18 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:15:18 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 19:15:18 volumio volumio[712]: info: Starting Shairport Sync Apr 12 19:15:18 volumio sudo[1001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 19:15:18 volumio sudo[1001]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 12 19:15:18 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 12 19:15:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 12 19:15:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 12 19:15:18 volumio sudo[1001]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:18 volumio volumio[712]: info: Shairport-Sync Started Apr 12 19:15:18 volumio sudo[1008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 12 19:15:18 volumio sudo[1008]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:18 volumio sudo[1008]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:18 volumio sudo[1010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 12 19:15:18 volumio sudo[1010]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:18 volumio sudo[1010]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:18 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 27 Apr 12 19:15:18 volumio mpd[1003]: Apr 12 19:15 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 12 19:15:19 volumio sudo[1014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 12 19:15:19 volumio sudo[1014]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:19 volumio sudo[1014]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:19 volumio sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 12 19:15:19 volumio sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:19 volumio sudo[1016]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:19 volumio volumio[712]: verbose: New Socket.io Connection to 192.168.1.121 from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 28 Apr 12 19:15:19 volumio mpd[1003]: Apr 12 19:15 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 12 19:15:19 volumio mpd[1003]: Apr 12 19:15 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 12 19:15:19 volumio systemd[1]: Started Music Player Daemon. Apr 12 19:15:19 volumio sudo[987]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:19 volumio sudo[943]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:19 volumio volumio[712]: error: MPD error: The expression evaluated to a falsy value: Apr 12 19:15:19 volumio volumio[712]: assert.ok(self.idling) Apr 12 19:15:19 volumio volumio[712]: error: updateQueue error: null Apr 12 19:15:19 volumio volumio[712]: info: --------->filter format None TEXT Apr 12 19:15:19 volumio volumio[712]: info: --------->filter size Apr 12 19:15:19 volumio volumio[712]: error: File size not found in array! Apr 12 19:15:19 volumio volumio[712]: info: Loudness is ON true Apr 12 19:15:19 volumio volumio[712]: info: gainmaxused,-2.95,-2.95,-0.21,-2.12,-2.73,-4.49,-2.43,-4.68,-3.65,-1.34,-3.61,-8.44,0,7.67 Apr 12 19:15:19 volumio volumio[712]: info: crossatt 0 Apr 12 19:15:19 volumio volumio[712]: info: pipelinerr eq1 Apr 12 19:15:19 volumio volumio[712]: - eq2 Apr 12 19:15:19 volumio volumio[712]: - eq3 Apr 12 19:15:19 volumio volumio[712]: - eq4 Apr 12 19:15:19 volumio volumio[712]: - eq5 Apr 12 19:15:19 volumio volumio[712]: - eq6 Apr 12 19:15:19 volumio volumio[712]: - eq7 Apr 12 19:15:19 volumio volumio[712]: - eq8 Apr 12 19:15:19 volumio volumio[712]: - eq9 Apr 12 19:15:19 volumio volumio[712]: - eq10 Apr 12 19:15:19 volumio volumio[712]: - eq11 Apr 12 19:15:19 volumio volumio[712]: - eq12 Apr 12 19:15:19 volumio volumio[712]: - highshelf Apr 12 19:15:19 volumio volumio[712]: - peakloudness Apr 12 19:15:19 volumio volumio[712]: - peakloudness2 Apr 12 19:15:19 volumio volumio[712]: - lowshelf Apr 12 19:15:19 volumio volumio[712]: info: gainresult 7.67 number Apr 12 19:15:19 volumio volumio[712]: info: highshelf: Apr 12 19:15:19 volumio volumio[712]: type: Biquad Apr 12 19:15:19 volumio volumio[712]: parameters: Apr 12 19:15:19 volumio volumio[712]: type: Highshelf Apr 12 19:15:19 volumio volumio[712]: freq: 10620 Apr 12 19:15:19 volumio volumio[712]: q: 1.38 Apr 12 19:15:19 volumio volumio[712]: gain: 2.16 Apr 12 19:15:19 volumio volumio[712]: lowshelf: Apr 12 19:15:19 volumio volumio[712]: type: Biquad Apr 12 19:15:19 volumio volumio[712]: parameters: Apr 12 19:15:19 volumio volumio[712]: type: LowshelfFO Apr 12 19:15:19 volumio volumio[712]: freq: 120 Apr 12 19:15:19 volumio volumio[712]: gain: 7.67 Apr 12 19:15:19 volumio volumio[712]: peakloudness: Apr 12 19:15:19 volumio volumio[712]: type: Biquad Apr 12 19:15:19 volumio volumio[712]: parameters: Apr 12 19:15:19 volumio volumio[712]: type: Peaking Apr 12 19:15:19 volumio volumio[712]: freq: 2000 Apr 12 19:15:19 volumio volumio[712]: q: 0.6 Apr 12 19:15:19 volumio volumio[712]: gain: -0.47 Apr 12 19:15:19 volumio volumio[712]: peakloudness2: Apr 12 19:15:19 volumio volumio[712]: type: Biquad Apr 12 19:15:19 volumio volumio[712]: parameters: Apr 12 19:15:19 volumio volumio[712]: type: Peaking Apr 12 19:15:19 volumio volumio[712]: freq: 4000 Apr 12 19:15:19 volumio volumio[712]: q: 0.8 Apr 12 19:15:19 volumio volumio[712]: gain: -0.21 Apr 12 19:15:19 volumio volumio[712]: peakloudness3: Apr 12 19:15:19 volumio volumio[712]: type: Biquad Apr 12 19:15:19 volumio volumio[712]: parameters: Apr 12 19:15:19 volumio volumio[712]: type: Peaking Apr 12 19:15:19 volumio volumio[712]: freq: 8000 Apr 12 19:15:19 volumio volumio[712]: q: 2.13 Apr 12 19:15:19 volumio volumio[712]: gain: 0.54 Apr 12 19:15:19 volumio volumio[712]: eq1: Apr 12 19:15:19 volumio volumio[712]: type: Biquad Apr 12 19:15:19 volumio volumio[712]: parameters: Apr 12 19:15:19 volumio volumio[712]: type: Peaking Apr 12 19:15:19 volumio volumio[712]: freq: 41 Apr 12 19:15:19 volumio volumio[712]: q: 0.467 Apr 12 19:15:19 volumio volumio[712]: gain: -2.95 Apr 12 19:15:19 volumio volumio[712]: eq2: Apr 12 19:15:19 volumio volumio[712]: type: Biquad Apr 12 19:15:19 volumio volumio[712]: parameters: Apr 12 19:15:19 volumio volumio[712]: type: Lowshelf Apr 12 19:15:19 volumio volumio[712]: freq: 50 Apr 12 19:15:19 volumio volumio[712]: q: 0.651 Apr 12 19:15:19 volumio volumio[712]: gain: -2.95 Apr 12 19:15:19 volumio volumio[712]: eq3: Apr 12 19:15:19 volumio volumio[712]: type: Biquad Apr 12 19:15:19 volumio volumio[712]: parameters: Apr 12 19:15:19 volumio volumio[712]: type: Peaking Apr 12 19:15:19 volumio volumio[712]: freq: 78 Apr 12 19:15:19 volumio volumio[712]: q: 1.751 Apr 12 19:15:19 volumio volumio[712]: gain: -0.21 Apr 12 19:15:19 volumio volumio[712]: eq4: Apr 12 19:15:19 volumio volumio[712]: type: Biquad Apr 12 19:15:19 volumio volumio[712]: parameters: Apr 12 19:15:19 volumio volumio[712]: type: Peaking Apr 12 19:15:19 volumio volumio[712]: freq: 142 Apr 12 19:15:19 volumio volumio[712]: q: 1.482 Apr 12 19:15:19 volumio volumio[712]: gain: -2.12 Apr 12 19:15:19 volumio volumio[712]: eq5: Apr 12 19:15:19 volumio volumio[712]: type: Biquad Apr 12 19:15:19 volumio volumio[712]: parameters: Apr 12 19:15:19 volumio volumio[712]: type: Peaking Apr 12 19:15:19 volumio volumio[712]: freq: 211 Apr 12 19:15:19 volumio volumio[712]: q: 1.834 Apr 12 19:15:19 volumio volumio[712]: gain: -2.73 Apr 12 19:15:19 volumio volumio[712]: eq6: Apr 12 19:15:19 volumio volumio[712]: type: Biquad Apr 12 19:15:19 volumio volumio[712]: parameters: Apr 12 19:15:19 volumio volumio[712]: type: Peaking Apr 12 19:15:19 volumio volumio[712]: freq: 549 Apr 12 19:15:19 volumio volumio[712]: q: 0.366 Apr 12 19:15:19 volumio volumio[712]: gain: -4.49 Apr 12 19:15:19 volumio volumio[712]: eq7: Apr 12 19:15:19 volumio volumio[712]: type: Biquad Apr 12 19:15:19 volumio volumio[712]: parameters: Apr 12 19:15:19 volumio volumio[712]: type: Peaking Apr 12 19:15:19 volumio volumio[712]: freq: 1131 Apr 12 19:15:19 volumio volumio[712]: q: 0.876 Apr 12 19:15:19 volumio volumio[712]: gain: -2.43 Apr 12 19:15:19 volumio volumio[712]: eq8: Apr 12 19:15:19 volumio volumio[712]: type: Biquad Apr 12 19:15:19 volumio volumio[712]: parameters: Apr 12 19:15:19 volumio volumio[712]: type: Peaking Apr 12 19:15:19 volumio volumio[712]: freq: 2246 Apr 12 19:15:19 volumio volumio[712]: q: 1.149 Apr 12 19:15:19 volumio volumio[712]: gain: -4.68 Apr 12 19:15:19 volumio volumio[712]: eq9: Apr 12 19:15:19 volumio volumio[712]: type: Biquad Apr 12 19:15:19 volumio volumio[712]: parameters: Apr 12 19:15:19 volumio volumio[712]: type: Peaking Apr 12 19:15:19 volumio volumio[712]: freq: 2860 Apr 12 19:15:19 volumio volumio[712]: q: 2.015 Apr 12 19:15:19 volumio volumio[712]: gain: -3.65 Apr 12 19:15:19 volumio volumio[712]: eq10: Apr 12 19:15:19 volumio volumio[712]: type: Biquad Apr 12 19:15:19 volumio volumio[712]: parameters: Apr 12 19:15:19 volumio volumio[712]: type: Peaking Apr 12 19:15:19 volumio volumio[712]: freq: 4534 Apr 12 19:15:19 volumio volumio[712]: q: 1.639 Apr 12 19:15:19 volumio volumio[712]: gain: -1.34 Apr 12 19:15:19 volumio volumio[712]: eq11: Apr 12 19:15:19 volumio volumio[712]: type: Biquad Apr 12 19:15:19 volumio volumio[712]: parameters: Apr 12 19:15:19 volumio volumio[712]: type: Peaking Apr 12 19:15:19 volumio volumio[712]: freq: 7280 Apr 12 19:15:19 volumio volumio[712]: q: 1.706 Apr 12 19:15:19 volumio volumio[712]: gain: -3.61 Apr 12 19:15:19 volumio volumio[712]: eq12: Apr 12 19:15:19 volumio volumio[712]: type: Biquad Apr 12 19:15:19 volumio volumio[712]: parameters: Apr 12 19:15:19 volumio volumio[712]: type: Highshelf Apr 12 19:15:19 volumio volumio[712]: freq: 22049 Apr 12 19:15:19 volumio volumio[712]: q: 0.018 Apr 12 19:15:19 volumio volumio[712]: gain: -8.44 Apr 12 19:15:19 volumio volumio[712]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 19:15:19 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 19:15:19 volumio volumio[712]: error: WebSocket error: [object Object] Apr 12 19:15:19 volumio volumio[712]: info: CoreCommandRouter::volumioGetState Apr 12 19:15:19 volumio volumio[712]: info: CorePlayQueue::getTrack 0 Apr 12 19:15:19 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 12 19:15:19 volumio volumio[712]: info: Listing playlists Apr 12 19:15:19 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 12 19:15:19 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 12 19:15:19 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 12 19:15:19 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 12 19:15:20 volumio dhcpcd[537]: wlan0: using IPv4LL address 169.254.47.225 Apr 12 19:15:20 volumio avahi-daemon[454]: Registering new address record for 169.254.47.225 on wlan0.IPv4. Apr 12 19:15:20 volumio dhcpcd[537]: wlan0: adding route to 169.254.0.0/16 Apr 12 19:15:21 volumio kernel: cam-dummy-reg: disabling Apr 12 19:15:21 volumio kernel: cam1-reg: disabling Apr 12 19:15:21 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 12 19:15:21 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 12 19:15:22 volumio volumio[712]: verbose: New Socket.io Connection to volumio.local from 192.168.1.63 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/100.0.4896.79 Safari/537.36 Total Clients: 28 Apr 12 19:15:22 volumio volumio[712]: info: CoreCommandRouter::volumioGetState Apr 12 19:15:22 volumio volumio[712]: info: CorePlayQueue::getTrack 0 Apr 12 19:15:22 volumio volumio[712]: info: CoreCommandRouter::volumioGetQueue Apr 12 19:15:22 volumio volumio[712]: info: CoreStateMachine::getQueue Apr 12 19:15:22 volumio volumio[712]: info: CorePlayQueue::getQueue Apr 12 19:15:22 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Apr 12 19:15:22 volumio volumio[712]: error: updateQueue error: null Apr 12 19:15:22 volumio ntpd[656]: Listen normally on 5 wlan0 169.254.47.225:123 Apr 12 19:15:22 volumio ntpd[656]: new interface(s) found: waking up resolver Apr 12 19:15:23 volumio sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 12 19:15:23 volumio sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:23 volumio sudo[1044]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:23 volumio sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 12 19:15:23 volumio sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:23 volumio sudo[1046]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:23 volumio sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 12 19:15:23 volumio sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:23 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 12 19:15:23 volumio sudo[1050]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:23 volumio volumio[712]: info: Upmpdcli Daemon Started Apr 12 19:15:23 volumio volumio-remote-updater[483]: [2022-04-12 19:15:23] [connect] Successful connection Apr 12 19:15:23 volumio volumio-remote-updater[483]: [2022-04-12 19:15:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1649790923 101 Apr 12 19:15:23 volumio volumio[1052]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 12 19:15:23 volumio volumio[712]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 29 Apr 12 19:15:24 volumio volumio[712]: error: updateQueue error: null Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 12 19:15:26 volumio volumio[712]: info: Adding plugin bluetooth to MyMusic Plugins Apr 12 19:15:26 volumio volumio[712]: info: Adding plugin multiroom to MyMusic Plugins Apr 12 19:15:26 volumio volumio[712]: info: Adding plugin metavolumio to MyMusic Plugins Apr 12 19:15:26 volumio volumio[712]: info: Adding plugin cd_controller to MyMusic Plugins Apr 12 19:15:26 volumio volumio[712]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 12 19:15:26 volumio volumio[712]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 12 19:15:26 volumio volumio[712]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 12 19:15:27 volumio volumio[712]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 12 19:15:27 volumio volumio[712]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 12 19:15:27 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:15:27 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:15:27 volumio volumio[712]: info: Starting MyVolumio Remote Streaming Endpoints Apr 12 19:15:27 volumio volumio[712]: info: MyVolumio login type: Token Apr 12 19:15:27 volumio volumio[712]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 12 19:15:27 volumio volumio[712]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 12 19:15:27 volumio volumio[712]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 12 19:15:27 volumio volumio[712]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 12 19:15:27 volumio volumio[712]: info: Streaming services startup Apr 12 19:15:27 volumio volumio[712]: info: Starting Streaming Daemon Apr 12 19:15:27 volumio sudo[1069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 12 19:15:27 volumio sudo[1069]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:27 volumio systemd[1]: Stopping Volumio Streaming Daemon... Apr 12 19:15:27 volumio systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Apr 12 19:15:27 volumio systemd[1]: volumio-streaming-daemon.service: Succeeded. Apr 12 19:15:27 volumio systemd[1]: Stopped Volumio Streaming Daemon. Apr 12 19:15:27 volumio systemd[1]: Started Volumio Streaming Daemon. Apr 12 19:15:27 volumio sudo[1069]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:27 volumio volumio-streaming-daemon[1074]: ############################ Apr 12 19:15:27 volumio volumio-streaming-daemon[1074]: # Volumio Streaming Daemon # Apr 12 19:15:27 volumio volumio-streaming-daemon[1074]: # Running on port 7777 # Apr 12 19:15:27 volumio volumio-streaming-daemon[1074]: ############################ Apr 12 19:15:27 volumio volumio-streaming-daemon[1074]: Environment: production Apr 12 19:15:28 volumio volumio[712]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 12 19:15:28 volumio volumio[712]: info: MYVOLUMIO: Adding device Apr 12 19:15:28 volumio volumio[712]: info: MYVOLUMIO: Evaluating Server Apr 12 19:15:29 volumio volumio[712]: info: MyVolumio status changed Apr 12 19:15:29 volumio volumio[712]: info: Streaming services startup Apr 12 19:15:29 volumio volumio[712]: info: Re-Starting Streaming Daemon Apr 12 19:15:29 volumio volumio[712]: info: Removing browser output: myVolumio user plan is not superstar Apr 12 19:15:29 volumio volumio[712]: info: Removing audio output: Apr 12 19:15:29 volumio volumio[712]: info: Stoppping Tunnel 1 Apr 12 19:15:29 volumio sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 12 19:15:29 volumio sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:29 volumio volumio-streaming-daemon[1074]: Restarting daemon Apr 12 19:15:29 volumio volumio-streaming-daemon[1074]: Environment: production Apr 12 19:15:29 volumio sudo[1099]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:29 volumio volumio[712]: info: Remote SSH Stopped Apr 12 19:15:29 volumio volumio[712]: info: Setting Geolocation for MyVolumio to eu1 Apr 12 19:15:29 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:15:29 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:15:29 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:15:29 volumio volumio-streaming-daemon[1074]: Environment: production Apr 12 19:15:29 volumio volumio[712]: info: Fetching Streaming Services browse cache Apr 12 19:15:29 volumio volumio-streaming-daemon[1074]: Environment: production Apr 12 19:15:29 volumio volumio[712]: info: Fetching Streaming Services browse cache Apr 12 19:15:30 volumio volumio[712]: info: Updating MyVolumio device info Apr 12 19:15:30 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 19:15:33 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 12 19:15:35 volumio volumio[712]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Apr 12 19:15:35 volumio volumio[712]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Apr 12 19:15:35 volumio sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Apr 12 19:15:35 volumio sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 12 19:15:35 volumio sudo[1119]: pam_unix(sudo:session): session closed for user root Apr 12 19:15:40 volumio sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Apr 12 19:15:40 volumio sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0)