Mon Aug 14 11:39:01 NZST 2023 Description="'WebUI Displying incorrect track info.'" # 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="93839ee567449b82f3a842e1e9ea0a6eafe28f63" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="29a1dec49d394b2f18a0d2e74c66e06f741f0ecf" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 01 Aug 2023 05:10:34 PM CEST" VOLUMIO_VERSION="3.538" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8c2b1ae973edf81bc6667a61b6160340" # ifconfig --------------- eth0: flags=4163 mtu 1500 inet 192.168.1.82 netmask 255.255.255.0 broadcast 192.168.1.255 ether dc:a6:32:0e:46:39 txqueuelen 1000 (Ethernet) RX packets 88583 bytes 111039165 (105.8 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 36920 bytes 8410743 (8.0 MiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 lo: flags=73 mtu 65536 inet 127.0.0.1 netmask 255.0.0.0 loop txqueuelen 1000 (Local Loopback) RX packets 1826 bytes 269402 (263.0 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 1826 bytes 269402 (263.0 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 # sudo iwconfig --------------- lo no wireless extensions. eth0 no wireless extensions. wlan0 IEEE 802.11 ESSID:off/any Mode:Managed Access Point: Not-Associated Retry short limit:7 RTS thr:off Fragment thr:off Encryption key:off Power Management:off # aplay -l --------------- **** List of PLAYBACK Hardware Devices **** card 5: E50 [E50], 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 0 11:13 ? 00:00:06 /sbin/init root 2 0 0 11:13 ? 00:00:00 [kthreadd] root 3 2 0 11:13 ? 00:00:00 [rcu_gp] root 4 2 0 11:13 ? 00:00:00 [rcu_par_gp] root 8 2 0 11:13 ? 00:00:00 [mm_percpu_wq] root 9 2 0 11:13 ? 00:00:00 [rcu_tasks_rude_] root 10 2 0 11:13 ? 00:00:00 [rcu_tasks_trace] root 11 2 0 11:13 ? 00:00:00 [ksoftirqd/0] root 12 2 0 11:13 ? 00:00:00 [rcu_sched] root 13 2 0 11:13 ? 00:00:00 [migration/0] root 14 2 0 11:13 ? 00:00:00 [cpuhp/0] root 15 2 0 11:13 ? 00:00:00 [cpuhp/1] root 16 2 0 11:13 ? 00:00:00 [migration/1] root 17 2 0 11:13 ? 00:00:00 [ksoftirqd/1] root 20 2 0 11:13 ? 00:00:00 [cpuhp/2] root 21 2 0 11:13 ? 00:00:00 [migration/2] root 22 2 0 11:13 ? 00:00:00 [ksoftirqd/2] root 25 2 0 11:13 ? 00:00:00 [cpuhp/3] root 26 2 0 11:13 ? 00:00:00 [migration/3] root 27 2 0 11:13 ? 00:00:00 [ksoftirqd/3] root 30 2 0 11:13 ? 00:00:00 [kdevtmpfs] root 31 2 0 11:13 ? 00:00:00 [netns] root 34 2 0 11:13 ? 00:00:00 [kauditd] root 36 2 0 11:13 ? 00:00:00 [khungtaskd] root 37 2 0 11:13 ? 00:00:00 [oom_reaper] root 38 2 0 11:13 ? 00:00:00 [writeback] root 39 2 0 11:13 ? 00:00:00 [kcompactd0] root 59 2 0 11:13 ? 00:00:00 [kblockd] root 60 2 0 11:13 ? 00:00:00 [blkcg_punt_bio] root 61 2 0 11:13 ? 00:00:00 [watchdogd] root 62 2 0 11:13 ? 00:00:00 [kworker/3:1-events_power_efficient] root 63 2 0 11:13 ? 00:00:00 [kworker/3:1H-kblockd] root 64 2 0 11:13 ? 00:00:00 [rpciod] root 65 2 0 11:13 ? 00:00:00 [kworker/u9:0-hci0] root 66 2 0 11:13 ? 00:00:00 [xprtiod] root 68 2 0 11:13 ? 00:00:00 [kswapd0] root 69 2 0 11:13 ? 00:00:00 [nfsiod] root 70 2 0 11:13 ? 00:00:00 [kthrotld] root 71 2 0 11:13 ? 00:00:00 [kworker/1:2-events] root 72 2 0 11:13 ? 00:00:00 [iscsi_eh] root 73 2 0 11:13 ? 00:00:00 [iscsi_destroy] root 74 2 0 11:13 ? 00:00:00 [nvme-wq] root 75 2 0 11:13 ? 00:00:00 [nvme-reset-wq] root 76 2 0 11:13 ? 00:00:00 [nvme-delete-wq] root 77 2 0 11:13 ? 00:00:00 [kworker/u8:1-events_unbound] root 79 2 0 11:13 ? 00:00:00 [DWC Notificatio] root 80 2 0 11:13 ? 00:00:00 [uas] root 81 2 0 11:13 ? 00:00:00 [kworker/u8:2-flush-179:0] root 82 2 0 11:13 ? 00:00:00 [vchiq-slot/0] root 83 2 0 11:13 ? 00:00:00 [vchiq-recy/0] root 84 2 0 11:13 ? 00:00:00 [vchiq-sync/0] root 85 2 0 11:13 ? 00:00:00 [zswap-shrink] root 88 2 0 11:13 ? 00:00:00 [kworker/1:5-mm_percpu_wq] root 89 2 0 11:13 ? 00:00:00 [sdhci] root 90 2 0 11:13 ? 00:00:00 [irq/48-mmc0] root 91 2 0 11:13 ? 00:00:00 [kworker/0:3-events] root 99 2 0 11:13 ? 00:00:00 [mmc_complete] root 100 2 0 11:13 ? 00:00:00 [kworker/0:1H-kblockd] root 121 2 0 11:13 ? 00:00:00 [kworker/2:1H-kblockd] root 126 2 0 11:13 ? 00:00:00 [jbd2/mmcblk0p2-] root 127 2 0 11:13 ? 00:00:00 [ext4-rsv-conver] root 134 2 0 11:13 ? 00:00:01 [loop0] root 142 2 0 11:13 ? 00:00:00 [kworker/1:1H-kblockd] root 162 2 0 11:13 ? 00:00:00 [jbd2/mmcblk0p3-] root 163 2 0 11:13 ? 00:00:00 [ext4-rsv-conver] root 171 2 0 11:13 ? 00:00:00 [ipv6_addrconf] root 172 2 0 11:13 ? 00:00:00 [kworker/3:2-events] root 194 1 0 11:13 ? 00:00:01 /lib/systemd/systemd-journald root 223 1 0 11:13 ? 00:00:00 /lib/systemd/systemd-udevd root 257 2 0 11:13 ? 00:00:00 [vchiq-keep/0] root 259 2 0 11:13 ? 00:00:00 [SMIO] root 294 2 0 11:13 ? 00:00:00 [mmal-vchiq] root 298 2 0 11:13 ? 00:00:00 [mmal-vchiq] root 299 2 0 11:13 ? 00:00:00 [mmal-vchiq] root 300 2 0 11:13 ? 00:00:00 [mmal-vchiq] root 301 2 0 11:13 ? 00:00:00 [mmal-vchiq] root 302 2 0 11:13 ? 00:00:00 [mmal-vchiq] root 330 2 0 11:13 ? 00:00:00 [cfg80211] root 371 2 0 11:13 ? 00:00:00 [brcmf_wq/mmc1:0] root 383 2 0 11:13 ? 00:00:00 [brcmf_wdog/mmc1] _rpc 452 1 0 11:13 ? 00:00:00 /sbin/rpcbind -f -w root 457 1 0 11:13 ? 00:00:01 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 root 461 1 0 11:13 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon message+ 465 1 0 11:13 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only volumio 470 1 0 11:13 ? 00:00:00 /usr/local/bin/volumio-remote-updater avahi 473 1 0 11:13 ? 00:00:00 avahi-daemon: running [dens-pi4.local] root 475 1 0 11:13 ? 00:00:00 /lib/systemd/systemd-logind root 476 1 0 11:13 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant nobody 486 1 0 11:13 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* avahi 493 473 0 11:13 ? 00:00:00 avahi-daemon: chroot helper root 523 1 0 11:13 ? 00:00:00 /usr/sbin/cron -f volumio 602 1 1 11:13 ? 00:00:24 /usr/bin/node /volumio/index.js root 630 1 0 11:13 ? 00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 3000000 flow - root 632 2 0 11:13 ? 00:00:00 [kworker/u9:2-hci0] root 643 1 0 11:13 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap root 682 2 0 11:13 ? 00:00:00 [cifsiod] root 683 2 0 11:13 ? 00:00:00 [smb3decryptd] root 684 2 0 11:13 ? 00:00:00 [cifsfileinfoput] root 685 2 0 11:13 ? 00:00:00 [cifsoplockd] root 686 2 0 11:13 ? 00:00:00 [cifs-dfscache] volumio 693 602 0 11:13 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 700 693 0 11:13 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 702 693 0 11:13 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 708 693 0 11:13 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart root 765 1 0 11:13 ? 00:00:00 /sbin/dhcpcd eth0 volumio 821 1 0 11:14 ? 00:00:00 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf root 830 2 0 11:14 ? 00:00:01 [cifsd] volumio 896 602 0 11:14 ? 00:00:00 /usr/bin/node /myvolumio/plugins/music_service/streaming_services/streamingProxy.js root 973 1 0 11:15 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 980 1 0 11:15 ? 00:00:00 /usr/sbin/sshd -D root 982 1 0 11:15 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux ntp 991 1 0 11:15 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 root 993 1 0 11:15 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I root 1246 973 0 11:15 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 1247 1 0 11:15 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 1250 1247 0 11:15 ? 00:00:00 winbindd: domain child [DENS-PI4] root 1252 1 0 11:15 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 1254 1252 0 11:15 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 1255 1252 0 11:15 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 1256 1247 0 11:15 ? 00:00:00 winbindd: idmap child root 1257 1247 0 11:15 ? 00:00:00 winbindd: domain child [BUILTIN] root 1258 1252 0 11:15 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 1262 1 0 11:15 ? 00:00:00 /usr/bin/python3 /usr/bin/argononed.py root 1264 1 0 11:15 ? 00:00:02 /usr/bin/python3 /home/volumio/fanshim-python/examples/automatic.py --on-threshold 55 --off-threshold 50 --low-temp 50 --high-temp 55 --delay 2 --brightness 64 root 1339 2 0 11:22 ? 00:00:00 [kworker/3:0H] root 1355 2 0 11:26 ? 00:00:00 [kworker/2:1-mm_percpu_wq] mpd 1415 1 0 11:28 ? 00:00:02 /usr/bin/mpd --systemd shairpo+ 1432 1 0 11:28 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf root 1440 2 0 11:29 ? 00:00:00 [kworker/0:0H] root 1443 2 0 11:30 ? 00:00:00 [kworker/1:0H] root 1446 2 0 11:30 ? 00:00:00 [kworker/2:0H] root 1460 2 0 11:33 ? 00:00:00 [kworker/0:1-events] root 1461 2 0 11:33 ? 00:00:00 [kworker/2:2-events_power_efficient] root 1479 2 0 11:39 ? 00:00:00 [kworker/0:0] volumio 1480 602 1 11:39 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'WebUI Displying incorrect track info.' volumio 1481 1480 21 11:39 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js WebUI Displying incorrect track info. volumio 1501 1481 0 11:39 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 1502 1501 0 11:39 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.5G 1001M 1.3G 43% /imgpart /dev/loop0 443M 443M 0 100% /static overlay 12G 795M 11G 7% / devtmpfs 1.9G 0 1.9G 0% /dev tmpfs 1.9G 0 1.9G 0% /dev/shm tmpfs 1.9G 9.0M 1.9G 1% /run tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 1.9G 0 1.9G 0% /sys/fs/cgroup tmpfs 1.9G 48K 1.9G 1% /tmp tmpfs 20M 44K 20M 1% /var/log tmpfs 1.9G 0 1.9G 0% /var/spool/cups tmpfs 1.9G 0 1.9G 0% /var/spool/cups/tmp /dev/mmcblk0p1 92M 54M 38M 59% /boot //192.168.1.20/Own 931G 719G 212G 78% /mnt/NAS/Own //192.168.1.20/audio/hi-definition/stereo 931G 685G 247G 74% /mnt/NAS/Stereo //192.168.1.20/Soundtrack 931G 685G 247G 74% /mnt/NAS/Soundtrack //192.168.1.20/Review 931G 719G 212G 78% /mnt/NAS/Review //192.168.1.20/audio/hi-definition/Classical 931G 685G 247G 74% /mnt/NAS/Classical # 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=1947708k,nr_inodes=146463,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/net_cls,net_prio type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls,net_prio) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) cgroup on /sys/fs/cgroup/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=30,pgrp=1,timeout=0,minproto=5,maxproto=5,direct) debugfs on /sys/kernel/debug type debugfs (rw,relatime) sunrpc on /run/rpc_pipefs type rpc_pipefs (rw,relatime) mqueue on /dev/mqueue type mqueue (rw,relatime) configfs on /sys/kernel/config type configfs (rw,relatime) tmpfs on /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.20/Own on /mnt/NAS/Own type cifs (ro,relatime,vers=3.1.1,cache=strict,username=den,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.1.20,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,echo_interval=60,actimeo=1) //192.168.1.20/audio/hi-definition/stereo on /mnt/NAS/Stereo type cifs (ro,relatime,vers=3.1.1,cache=strict,username=den,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.1.20,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,echo_interval=60,actimeo=1) //192.168.1.20/Soundtrack on /mnt/NAS/Soundtrack type cifs (ro,relatime,vers=3.1.1,cache=strict,username=den,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.1.20,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,echo_interval=60,actimeo=1) //192.168.1.20/Review on /mnt/NAS/Review type cifs (ro,relatime,vers=3.1.1,cache=strict,username=den,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.1.20,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,echo_interval=60,actimeo=1) //192.168.1.20/audio/hi-definition/Classical on /mnt/NAS/Classical type cifs (ro,relatime,vers=3.1.1,cache=strict,username=den,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.1.20,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:49149 0.0.0.0:* LISTEN 821/upmpdcli tcp 0 0 0.0.0.0:445 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:49152 0.0.0.0:* LISTEN 821/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:22 0.0.0.0:* LISTEN - tcp 0 0 127.0.0.1:35782 127.0.0.1:3000 ESTABLISHED 602/node tcp 0 0 127.0.0.1:38028 127.0.0.1:6599 ESTABLISHED 821/upmpdcli tcp 0 0 127.0.0.1:35786 127.0.0.1:3000 ESTABLISHED 602/node tcp 0 0 127.0.0.1:35744 127.0.0.1:3000 ESTABLISHED 602/node tcp 0 0 192.168.1.82:34944 34.120.160.131:443 ESTABLISHED 602/node tcp 0 0 127.0.0.1:35754 127.0.0.1:3000 ESTABLISHED 470/volumio-remote- tcp 0 0 192.168.1.82:39992 192.168.1.20:445 ESTABLISHED - tcp 0 0 127.0.0.1:35830 127.0.0.1:3000 ESTABLISHED 602/node tcp 0 0 127.0.0.1:38024 127.0.0.1:6599 ESTABLISHED 821/upmpdcli tcp6 0 0 :::3005 :::* LISTEN 602/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::4004 :::* LISTEN 602/node tcp6 0 0 :::6599 :::* LISTEN 602/node tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::3245 :::* LISTEN 896/node tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::22 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 602/node tcp6 0 0 :::3001 :::* LISTEN 693/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:35786 ESTABLISHED 602/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:35754 ESTABLISHED 602/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:38028 ESTABLISHED 602/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:35830 ESTABLISHED 602/node tcp6 0 0 192.168.1.82:3000 192.168.1.140:51178 TIME_WAIT - tcp6 0 0 192.168.1.82:3000 192.168.1.140:51041 TIME_WAIT - tcp6 0 0 192.168.1.82:3000 192.168.1.140:51198 ESTABLISHED 602/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:38024 ESTABLISHED 602/node tcp6 0 0 192.168.1.82:3000 192.168.1.140:51181 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:35744 ESTABLISHED 602/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:35782 ESTABLISHED 602/node # cat /etc/asound.conf --------------- pcm.!default { type empty slave.pcm "volumio" } pcm.volumio { type empty slave.pcm "volumioOutput" } # There is always a plug before the hardware to be safe pcm.volumioOutput { type plug slave.pcm "volumioHw" } pcm.volumioHw { type hw card "E50" } # cat /var/log/mpd.log --------------- Aug 14 11:02 : zeroconf: No global port, disabling zeroconf Aug 14 11:02 : exception: No such directory Aug 14 11:16 : mixer: Failed to read mixer for 'alsa': failed to attach to default: No such file or directory Aug 14 11:28 : zeroconf: No global port, disabling zeroconf Aug 14 11:31 : output: Failed to open mixer for 'alsa': failed to attach to default: No such file or directory Aug 14 11:36 : player: played "NAS/Soundtrack/Hans Zimmer/Dunkirk/01 Hans Zimmer - The Mole.flac" Aug 14 11:36 : output: Failed to open mixer for 'alsa': failed to attach to default: No such file or directory # volumio plugin list --------------- This command will list installed plugins on your device ----------------------- Installed Plugins: [ { prettyName: 'Plex and PlexAmp', name: 'plexamp', category: 'music_service', version: '1.0.3', icon: 'fa-server', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'Radio Paradise', name: 'radio_paradise', category: 'music_service', version: '1.0.3', icon: 'fa-headphones', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'Backup & Restore Data', name: 'backup_restore', category: 'system_controller', version: '0.7.4', icon: 'fa-floppy-o', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'crossfaderswitch', name: 'crossfader_switch', category: 'system_controller', version: '1.0.2', icon: 'fa-lightbulb-o', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'GPIO Control', name: 'gpio_control', category: 'system_hardware', version: '1.0.1', icon: 'fa-microchip', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'System information', name: 'Systeminfo', category: 'user_interface', version: '3.0.0', icon: 'fa-info-circle', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'Now Playing', name: 'now_playing', category: 'user_interface', version: '0.3.8', icon: 'fa-play-circle', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'randomizer', name: 'randomizer', category: 'user_interface', version: '3.0.0', icon: 'fa-lightbulb-o', isManuallyInstalled: false, enabled: true, active: true } ] ------------------------------------------ # cat /data/configuration/plugins.json --------------- { "music_service": { "airplay_emulation": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "inputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpd": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "webradio": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "example_plugin": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "last_100": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp_browser": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "streaming_services": { "status": { "type": "string", "value": "STARTED" } }, "radio_paradise": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "plexamp": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "audio_interface": { "outputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "alsa_controller": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "miscellanea": { "alarm-clock": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "albumart": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "appearance": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "wizard": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "my_music": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "user_interface": { "websocket": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpdemulation": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STARTED" } }, "rest_api": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "Systeminfo": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "now_playing": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "randomizer": { "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" } }, "backup_restore": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "crossfader_switch": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "system_hardware": { "gpio_control": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } } } # cat /etc/mpd.conf --------------- # Volumio MPD Configuration File # Files and directories ####################################################### music_directory "/var/lib/mpd/music" playlist_directory "/var/lib/mpd/playlists" db_file "/var/lib/mpd/tag_cache" log_file "/var/log/mpd.log" #pid_file "/var/run/mpd/pid" #state_file "/var/lib/mpd/state" #sticker_file "/var/lib/mpd/sticker.sql" ############################################################################### # General music daemon options ################################################ user "mpd" group "audio" bind_to_address "any" #port "6600" #log_level "default" #save_absolute_paths_in_playlists "no" #metadata_to_use "artist,album,title,track,name,genre,date,composer,performer,disc" auto_update "no" #auto_update_depth "3" ############################################################################### # Symbolic link behavior ###################################################### follow_outside_symlinks "yes" follow_inside_symlinks "yes" ############################################################################### # Input ####################################################################### # input { plugin "curl" # proxy "proxy.isp.com:8080" # proxy_user "user" # proxy_password "password" } ############################################################################### # Decoder ################################################################ decoder { plugin "faad" enabled "no" } ############################################################################### # Audio Output ################################################################ resampler { plugin "soxr" quality "high" threads "1" } audio_output { type "alsa" name "alsa" device "volumio" dop "no" } audio_output { type "fifo" enabled "no" name "multiroom" path "/tmp/snapfifo" format "44100:16:2" } #replaygain "album" #replaygain_preamp "0" volume_normalization "no" ############################################################################### # MPD Internal Buffering ###################################################### audio_buffer_size "12288" ############################################################################### # 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, 698 ms: OK https://www.googleapis.com, 386 ms: OK https://securetoken.googleapis.com, 592 ms: OK https://myvolumio.firebaseio.com, 590 ms: OK https://functions.volumio.cloud, 547 ms: OK https://oauth-performer.dfs.volumio.org, 1159 ms: OK https://browsing-performer.dfs.volumio.org, 1180 ms: OK http://cddb.volumio.org, 4907 ms: OK https://functions.volumio.cloud, 655 ms: OK http://pushupdates.volumio.org, 828 ms: OK http://plugins.volumio.org, 1045 ms: OK https://database.volumio.cloud, 760 ms: OK https://radio-directory.firebaseapp.com, 361 ms: OK ---------- REMOTE ENDPOINTS TEST OK, all Endpoints are reachable ---------- # cat /data/updatesHistoryFile --------------- --- Thu Mar 30 2023 15:51:02 GMT+0000 (Coordinated Universal Time) {"systemversion":"3.449","builddate":"Thu 30 Mar 2023 05:45:03 PM CEST","variant":"volumio","hardware":"pi"} ------ Fri Jul 07 2023 21:08:12 GMT+1200 (New Zealand Standard Time) {"systemversion":"3.512","builddate":"Sun 25 Jun 2023 07:20:58 PM CEST","variant":"volumio","hardware":"pi"} ------ Sun Jul 23 2023 03:07:25 GMT+1200 (New Zealand Standard Time) {"systemversion":"3.532","builddate":"Fri 21 Jul 2023 06:49:58 PM CEST","variant":"volumio","hardware":"pi"} ------ Wed Jul 26 2023 05:00:55 GMT+1200 (New Zealand Standard Time) {"systemversion":"3.534","builddate":"Mon 24 Jul 2023 06:15:42 PM CEST","variant":"volumio","hardware":"pi"} ------ Thu Jul 27 2023 17:31:47 GMT+1200 (New Zealand Standard Time) {"systemversion":"3.535","builddate":"Wed 26 Jul 2023 05:46:04 PM CEST","variant":"volumio","hardware":"pi"} ------ Sat Jul 29 2023 03:47:31 GMT+1200 (New Zealand Standard Time) {"systemversion":"3.536","builddate":"Thu 27 Jul 2023 09:32:29 AM CEST","variant":"volumio","hardware":"pi"} ------ Tue Aug 08 2023 17:38:55 GMT+1200 (New Zealand Standard Time) {"systemversion":"3.538","builddate":"Tue 01 Aug 2023 05:10:34 PM CEST","variant":"volumio","hardware":"pi"} --- # sudo journalctl -p 7 --------------- -- Logs begin at Thu 2019-02-14 23:11:59 NZDT, end at Mon 2023-08-14 11:39:09 NZST. -- Feb 14 23:11:59 dens-pi4 kernel: Booting Linux on physical CPU 0x0 Feb 14 23:11:59 dens-pi4 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 Feb 14 23:11:59 dens-pi4 kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Feb 14 23:11:59 dens-pi4 kernel: CPU: div instructions available: patching division code Feb 14 23:11:59 dens-pi4 kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Feb 14 23:11:59 dens-pi4 kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.1 Feb 14 23:11:59 dens-pi4 kernel: random: fast init done Feb 14 23:11:59 dens-pi4 kernel: Memory policy: Data cache writealloc Feb 14 23:11:59 dens-pi4 kernel: Reserved memory: created CMA memory pool at 0x000000002a000000, size 64 MiB Feb 14 23:11:59 dens-pi4 kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Feb 14 23:11:59 dens-pi4 kernel: Zone ranges: Feb 14 23:11:59 dens-pi4 kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] Feb 14 23:11:59 dens-pi4 kernel: Normal empty Feb 14 23:11:59 dens-pi4 kernel: HighMem [mem 0x0000000030000000-0x00000000fbffffff] Feb 14 23:11:59 dens-pi4 kernel: Movable zone start for each node Feb 14 23:11:59 dens-pi4 kernel: Early memory node ranges Feb 14 23:11:59 dens-pi4 kernel: node 0: [mem 0x0000000000000000-0x000000003dffffff] Feb 14 23:11:59 dens-pi4 kernel: node 0: [mem 0x0000000040000000-0x00000000fbffffff] Feb 14 23:11:59 dens-pi4 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff] Feb 14 23:11:59 dens-pi4 kernel: On node 0 totalpages: 1024000 Feb 14 23:11:59 dens-pi4 kernel: DMA zone: 1728 pages used for memmap Feb 14 23:11:59 dens-pi4 kernel: DMA zone: 0 pages reserved Feb 14 23:11:59 dens-pi4 kernel: DMA zone: 196608 pages, LIFO batch:63 Feb 14 23:11:59 dens-pi4 kernel: HighMem zone: 827392 pages, LIFO batch:63 Feb 14 23:11:59 dens-pi4 kernel: percpu: Embedded 20 pages/cpu s50828 r8192 d22900 u81920 Feb 14 23:11:59 dens-pi4 kernel: pcpu-alloc: s50828 r8192 d22900 u81920 alloc=20*4096 Feb 14 23:11:59 dens-pi4 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 14 23:11:59 dens-pi4 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1022272 Feb 14 23:11:59 dens-pi4 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:0E:46:39 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 Feb 14 23:11:59 dens-pi4 kernel: Kernel parameter elevator= does not have any effect anymore. Please use sysfs to set IO scheduler for individual devices. Feb 14 23:11:59 dens-pi4 kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Feb 14 23:11:59 dens-pi4 kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 14 23:11:59 dens-pi4 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 14 23:11:59 dens-pi4 kernel: software IO TLB: mapped [mem 0x0000000023c90000-0x0000000027c90000] (64MB) Feb 14 23:11:59 dens-pi4 kernel: Memory: 3895420K/4096000K available (10240K kernel code, 1366K rwdata, 3196K rodata, 2048K init, 882K bss, 135044K reserved, 65536K cma-reserved, 3309568K highmem) Feb 14 23:11:59 dens-pi4 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 14 23:11:59 dens-pi4 kernel: ftrace: allocating 34360 entries in 101 pages Feb 14 23:11:59 dens-pi4 kernel: ftrace: allocated 101 pages with 4 groups Feb 14 23:11:59 dens-pi4 kernel: rcu: Hierarchical RCU implementation. Feb 14 23:11:59 dens-pi4 kernel: Rude variant of Tasks RCU enabled. Feb 14 23:11:59 dens-pi4 kernel: Tracing variant of Tasks RCU enabled. Feb 14 23:11:59 dens-pi4 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Feb 14 23:11:59 dens-pi4 kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Feb 14 23:11:59 dens-pi4 kernel: GIC: Using split EOI/Deactivate mode Feb 14 23:11:59 dens-pi4 kernel: random: get_random_bytes called from start_kernel+0x3c8/0x59c with crng_init=1 Feb 14 23:11:59 dens-pi4 kernel: sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns Feb 14 23:11:59 dens-pi4 kernel: clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns Feb 14 23:11:59 dens-pi4 kernel: bcm2835: system timer (irq = 25) Feb 14 23:11:59 dens-pi4 kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Feb 14 23:11:59 dens-pi4 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Feb 14 23:11:59 dens-pi4 kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Feb 14 23:11:59 dens-pi4 kernel: Switching to timer-based delay loop, resolution 18ns Feb 14 23:11:59 dens-pi4 kernel: Console: colour dummy device 80x30 Feb 14 23:11:59 dens-pi4 kernel: printk: console [tty1] enabled Feb 14 23:11:59 dens-pi4 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Feb 14 23:11:59 dens-pi4 kernel: pid_max: default: 32768 minimum: 301 Feb 14 23:11:59 dens-pi4 kernel: LSM: Security Framework initializing Feb 14 23:11:59 dens-pi4 kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 23:11:59 dens-pi4 kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 23:11:59 dens-pi4 kernel: cgroup: Disabling memory control group subsystem Feb 14 23:11:59 dens-pi4 kernel: CPU: Testing write buffer coherency: ok Feb 14 23:11:59 dens-pi4 kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Feb 14 23:11:59 dens-pi4 kernel: Setting up static identity map for 0x200000 - 0x20003c Feb 14 23:11:59 dens-pi4 kernel: rcu: Hierarchical SRCU implementation. Feb 14 23:11:59 dens-pi4 kernel: smp: Bringing up secondary CPUs ... Feb 14 23:11:59 dens-pi4 kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Feb 14 23:11:59 dens-pi4 kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Feb 14 23:11:59 dens-pi4 kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Feb 14 23:11:59 dens-pi4 kernel: smp: Brought up 1 node, 4 CPUs Feb 14 23:11:59 dens-pi4 kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Feb 14 23:11:59 dens-pi4 kernel: CPU: All CPU(s) started in HYP mode. Feb 14 23:11:59 dens-pi4 kernel: CPU: Virtualization extensions available. Feb 14 23:11:59 dens-pi4 kernel: devtmpfs: initialized Feb 14 23:11:59 dens-pi4 kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Feb 14 23:11:59 dens-pi4 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Feb 14 23:11:59 dens-pi4 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Feb 14 23:11:59 dens-pi4 kernel: pinctrl core: initialized pinctrl subsystem Feb 14 23:11:59 dens-pi4 kernel: NET: Registered protocol family 16 Feb 14 23:11:59 dens-pi4 kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Feb 14 23:11:59 dens-pi4 kernel: audit: initializing netlink subsys (disabled) Feb 14 23:11:59 dens-pi4 kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Feb 14 23:11:59 dens-pi4 kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 14 23:11:59 dens-pi4 kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Feb 14 23:11:59 dens-pi4 kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Feb 14 23:11:59 dens-pi4 kernel: Serial: AMBA PL011 UART driver Feb 14 23:11:59 dens-pi4 kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Feb 14 23:11:59 dens-pi4 kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2022-01-20T13:56:48, variant start Feb 14 23:11:59 dens-pi4 kernel: raspberrypi-firmware soc:firmware: Firmware hash is bd88f66f8952d34e4e0613a85c7a6d3da49e13e2 Feb 14 23:11:59 dens-pi4 kernel: Kprobes globally optimized Feb 14 23:11:59 dens-pi4 kernel: bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1 Feb 14 23:11:59 dens-pi4 kernel: vgaarb: loaded Feb 14 23:11:59 dens-pi4 kernel: SCSI subsystem initialized Feb 14 23:11:59 dens-pi4 kernel: usbcore: registered new interface driver usbfs Feb 14 23:11:59 dens-pi4 kernel: usbcore: registered new interface driver hub Feb 14 23:11:59 dens-pi4 kernel: usbcore: registered new device driver usb Feb 14 23:11:59 dens-pi4 kernel: usb_phy_generic phy: supply vcc not found, using dummy regulator Feb 14 23:11:59 dens-pi4 kernel: clocksource: Switched to clocksource arch_sys_counter Feb 14 23:11:59 dens-pi4 kernel: VFS: Disk quotas dquot_6.6.0 Feb 14 23:11:59 dens-pi4 kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Feb 14 23:11:59 dens-pi4 kernel: FS-Cache: Loaded Feb 14 23:11:59 dens-pi4 kernel: CacheFiles: Loaded Feb 14 23:11:59 dens-pi4 kernel: NET: Registered protocol family 2 Feb 14 23:11:59 dens-pi4 kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Feb 14 23:11:59 dens-pi4 kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) Feb 14 23:11:59 dens-pi4 kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Feb 14 23:11:59 dens-pi4 kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) Feb 14 23:11:59 dens-pi4 kernel: TCP: Hash tables configured (established 8192 bind 8192) Feb 14 23:11:59 dens-pi4 kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 23:11:59 dens-pi4 kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 23:11:59 dens-pi4 kernel: NET: Registered protocol family 1 Feb 14 23:11:59 dens-pi4 kernel: RPC: Registered named UNIX socket transport module. Feb 14 23:11:59 dens-pi4 kernel: RPC: Registered udp transport module. Feb 14 23:11:59 dens-pi4 kernel: RPC: Registered tcp transport module. Feb 14 23:11:59 dens-pi4 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 14 23:11:59 dens-pi4 kernel: PCI: CLS 0 bytes, default 64 Feb 14 23:11:59 dens-pi4 kernel: Trying to unpack rootfs image as initramfs... Feb 14 23:11:59 dens-pi4 kernel: Freeing initrd memory: 13028K Feb 14 23:11:59 dens-pi4 kernel: Initialise system trusted keyrings Feb 14 23:11:59 dens-pi4 kernel: workingset: timestamp_bits=14 max_order=20 bucket_order=6 Feb 14 23:11:59 dens-pi4 kernel: zbud: loaded Feb 14 23:11:59 dens-pi4 kernel: FS-Cache: Netfs 'nfs' registered for caching Feb 14 23:11:59 dens-pi4 kernel: NFS: Registering the id_resolver key type Feb 14 23:11:59 dens-pi4 kernel: Key type id_resolver registered Feb 14 23:11:59 dens-pi4 kernel: Key type id_legacy registered Feb 14 23:11:59 dens-pi4 kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Feb 14 23:11:59 dens-pi4 kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Feb 14 23:11:59 dens-pi4 kernel: Key type asymmetric registered Feb 14 23:11:59 dens-pi4 kernel: Asymmetric key parser 'x509' registered Feb 14 23:11:59 dens-pi4 kernel: bounce: pool size: 64 pages Feb 14 23:11:59 dens-pi4 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Feb 14 23:11:59 dens-pi4 kernel: io scheduler mq-deadline registered Feb 14 23:11:59 dens-pi4 kernel: io scheduler kyber registered Feb 14 23:11:59 dens-pi4 kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Feb 14 23:11:59 dens-pi4 kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Feb 14 23:11:59 dens-pi4 kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Feb 14 23:11:59 dens-pi4 kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000 Feb 14 23:11:59 dens-pi4 kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Feb 14 23:11:59 dens-pi4 kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Feb 14 23:11:59 dens-pi4 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Feb 14 23:11:59 dens-pi4 kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Feb 14 23:11:59 dens-pi4 kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Feb 14 23:11:59 dens-pi4 kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Feb 14 23:11:59 dens-pi4 kernel: PCI: bus0: Fast back to back transfers disabled Feb 14 23:11:59 dens-pi4 kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Aug 14 11:00:18 dens-pi4 kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Aug 14 11:00:18 dens-pi4 kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Aug 14 11:00:18 dens-pi4 kernel: pci 0000:01:00.0: PME# supported from D0 D3cold Aug 14 11:00:18 dens-pi4 kernel: PCI: bus1: Fast back to back transfers disabled Aug 14 11:00:18 dens-pi4 kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Aug 14 11:00:18 dens-pi4 kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Aug 14 11:00:18 dens-pi4 kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Aug 14 11:00:18 dens-pi4 kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Aug 14 11:00:18 dens-pi4 kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Aug 14 11:00:18 dens-pi4 kernel: pcieport 0000:00:00.0: enabling device (0140 -> 0142) Aug 14 11:00:18 dens-pi4 kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 62 Aug 14 11:00:18 dens-pi4 kernel: bcm2708_fb soc:fb: FB found 1 display(s) Aug 14 11:00:18 dens-pi4 kernel: Console: switching to colour frame buffer device 80x30 Aug 14 11:00:18 dens-pi4 kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Aug 14 11:00:18 dens-pi4 kernel: iproc-rng200 fe104000.rng: hwrng registered Aug 14 11:00:18 dens-pi4 kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Aug 14 11:00:18 dens-pi4 kernel: gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000 Aug 14 11:00:18 dens-pi4 kernel: brd: module loaded Aug 14 11:00:18 dens-pi4 kernel: loop: module loaded Aug 14 11:00:18 dens-pi4 kernel: Loading iSCSI transport class v2.0-870. Aug 14 11:00:18 dens-pi4 kernel: libphy: Fixed MDIO Bus: probed Aug 14 11:00:18 dens-pi4 kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Aug 14 11:00:18 dens-pi4 kernel: libphy: bcmgenet MII bus: probed Aug 14 11:00:18 dens-pi4 kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Aug 14 11:00:18 dens-pi4 kernel: usbcore: registered new interface driver r8152 Aug 14 11:00:18 dens-pi4 kernel: usbcore: registered new interface driver lan78xx Aug 14 11:00:18 dens-pi4 kernel: usbcore: registered new interface driver smsc95xx Aug 14 11:00:18 dens-pi4 kernel: xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142) Aug 14 11:00:18 dens-pi4 kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Aug 14 11:00:18 dens-pi4 kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Aug 14 11:00:18 dens-pi4 kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x00000e0000000890 Aug 14 11:00:18 dens-pi4 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 Aug 14 11:00:18 dens-pi4 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Aug 14 11:00:18 dens-pi4 kernel: usb usb1: Product: xHCI Host Controller Aug 14 11:00:18 dens-pi4 kernel: usb usb1: Manufacturer: Linux 5.10.92-v7l+ xhci-hcd Aug 14 11:00:18 dens-pi4 kernel: usb usb1: SerialNumber: 0000:01:00.0 Aug 14 11:00:18 dens-pi4 kernel: hub 1-0:1.0: USB hub found Aug 14 11:00:18 dens-pi4 kernel: hub 1-0:1.0: 1 port detected Aug 14 11:00:18 dens-pi4 kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Aug 14 11:00:18 dens-pi4 kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Aug 14 11:00:18 dens-pi4 kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Aug 14 11:00:18 dens-pi4 kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 Aug 14 11:00:18 dens-pi4 kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Aug 14 11:00:18 dens-pi4 kernel: usb usb2: Product: xHCI Host Controller Aug 14 11:00:18 dens-pi4 kernel: usb usb2: Manufacturer: Linux 5.10.92-v7l+ xhci-hcd Aug 14 11:00:18 dens-pi4 kernel: usb usb2: SerialNumber: 0000:01:00.0 Aug 14 11:00:18 dens-pi4 kernel: hub 2-0:1.0: USB hub found Aug 14 11:00:18 dens-pi4 kernel: hub 2-0:1.0: 4 ports detected Aug 14 11:00:18 dens-pi4 kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Aug 14 11:00:18 dens-pi4 kernel: dwc_otg: FIQ enabled Aug 14 11:00:18 dens-pi4 kernel: dwc_otg: NAK holdoff enabled Aug 14 11:00:18 dens-pi4 kernel: dwc_otg: FIQ split-transaction FSM enabled Aug 14 11:00:18 dens-pi4 kernel: Module dwc_common_port init Aug 14 11:00:18 dens-pi4 kernel: usbcore: registered new interface driver uas Aug 14 11:00:18 dens-pi4 kernel: usbcore: registered new interface driver usb-storage Aug 14 11:00:18 dens-pi4 kernel: mousedev: PS/2 mouse device common for all mice Aug 14 11:00:18 dens-pi4 kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Aug 14 11:00:18 dens-pi4 kernel: sdhci: Secure Digital Host Controller Interface driver Aug 14 11:00:18 dens-pi4 kernel: sdhci: Copyright(c) Pierre Ossman Aug 14 11:00:18 dens-pi4 kernel: mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe Aug 14 11:00:18 dens-pi4 kernel: sdhci-pltfm: SDHCI platform and OF driver helper Aug 14 11:00:18 dens-pi4 kernel: ledtrig-cpu: registered to indicate activity on CPUs Aug 14 11:00:18 dens-pi4 kernel: hid: raw HID events driver (C) Jiri Kosina Aug 14 11:00:18 dens-pi4 kernel: usbcore: registered new interface driver usbhid Aug 14 11:00:18 dens-pi4 kernel: usbhid: USB HID core driver Aug 14 11:00:18 dens-pi4 kernel: Initializing XFRM netlink socket Aug 14 11:00:18 dens-pi4 kernel: NET: Registered protocol family 17 Aug 14 11:00:18 dens-pi4 kernel: Key type dns_resolver registered Aug 14 11:00:18 dens-pi4 kernel: Registering SWP/SWPB emulation handler Aug 14 11:00:18 dens-pi4 kernel: registered taskstats version 1 Aug 14 11:00:18 dens-pi4 kernel: Loading compiled-in X.509 certificates Aug 14 11:00:18 dens-pi4 kernel: Key type ._fscrypt registered Aug 14 11:00:18 dens-pi4 kernel: Key type .fscrypt registered Aug 14 11:00:18 dens-pi4 kernel: Key type fscrypt-provisioning registered Aug 14 11:00:18 dens-pi4 kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Aug 14 11:00:18 dens-pi4 kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Aug 14 11:00:18 dens-pi4 kernel: fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2 Aug 14 11:00:18 dens-pi4 kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Aug 14 11:00:18 dens-pi4 kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Aug 14 11:00:18 dens-pi4 kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Aug 14 11:00:18 dens-pi4 kernel: of_cfs_init Aug 14 11:00:18 dens-pi4 kernel: of_cfs_init: OK Aug 14 11:00:18 dens-pi4 kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Aug 14 11:00:18 dens-pi4 kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Aug 14 11:00:18 dens-pi4 kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Aug 14 11:00:18 dens-pi4 kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Aug 14 11:00:18 dens-pi4 kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Aug 14 11:00:18 dens-pi4 kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Aug 14 11:00:18 dens-pi4 kernel: Freeing unused kernel memory: 2048K Aug 14 11:00:18 dens-pi4 kernel: Run /init as init process Aug 14 11:00:18 dens-pi4 kernel: with arguments: Aug 14 11:00:18 dens-pi4 kernel: /init Aug 14 11:00:18 dens-pi4 kernel: splash Aug 14 11:00:18 dens-pi4 kernel: with environment: Aug 14 11:00:18 dens-pi4 kernel: HOME=/ Aug 14 11:00:18 dens-pi4 kernel: TERM=linux Aug 14 11:00:18 dens-pi4 kernel: imgpart=/dev/mmcblk0p2 Aug 14 11:00:18 dens-pi4 kernel: imgfile=/volumio_current.sqsh Aug 14 11:00:18 dens-pi4 kernel: bootdelay=5 Aug 14 11:00:18 dens-pi4 kernel: mmc1: new high speed SDIO card at address 0001 Aug 14 11:00:18 dens-pi4 kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Aug 14 11:00:18 dens-pi4 kernel: mmcblk0: mmc0:aaaa SC16G 14.8 GiB Aug 14 11:00:18 dens-pi4 kernel: mmcblk0: p1 p2 p3 Aug 14 11:00:18 dens-pi4 kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Aug 14 11:00:18 dens-pi4 initramfs: Booting Volumio for BCM2711 Aug 14 11:00:18 dens-pi4 initramfs: This script mounts rootfs RO with an overlay RW layer. Aug 14 11:00:18 dens-pi4 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 14 11:00:18 dens-pi4 initramfs: IMGPART=/dev/mmcblk0p2 Aug 14 11:00:18 dens-pi4 initramfs: IMGFILE=/volumio_current.sqsh Aug 14 11:00:18 dens-pi4 initramfs: Boot delay (except first time) will be 5 seconds Aug 14 11:00:18 dens-pi4 initramfs: /dev/mmcblk0p2: Aug 14 11:00:18 dens-pi4 kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Aug 14 11:00:18 dens-pi4 kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Aug 14 11:00:18 dens-pi4 kernel: usb 1-1: Product: USB2.0 Hub Aug 14 11:00:18 dens-pi4 kernel: hub 1-1:1.0: USB hub found Aug 14 11:00:18 dens-pi4 kernel: hub 1-1:1.0: 4 ports detected Aug 14 11:00:18 dens-pi4 kernel: EXT4-fs (mmcblk0p2): recovery complete Aug 14 11:00:18 dens-pi4 kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Aug 14 11:00:18 dens-pi4 initramfs: Doing a 5 second delay here to give kernel load a headstart Aug 14 11:00:18 dens-pi4 kernel: usb 1-1.1: new high-speed USB device number 3 using xhci_hcd Aug 14 11:00:18 dens-pi4 kernel: usb 1-1.1: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.07 Aug 14 11:00:18 dens-pi4 kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Aug 14 11:00:18 dens-pi4 kernel: usb 1-1.1: Product: E50 Aug 14 11:00:18 dens-pi4 kernel: usb 1-1.1: Manufacturer: Topping Aug 14 11:00:18 dens-pi4 kernel: hid-generic 0003:152A:8750.0001: hiddev96,hidraw0: USB HID v1.10 Device [Topping E50] on usb-0000:01:00.0-1.1/input3 Aug 14 11:00:18 dens-pi4 kernel: usb 1-1.4: new full-speed USB device number 4 using xhci_hcd Aug 14 11:00:18 dens-pi4 kernel: usb 1-1.4: New USB device found, idVendor=20a0, idProduct=0006, bcdDevice= 1.00 Aug 14 11:00:18 dens-pi4 kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Aug 14 11:00:18 dens-pi4 kernel: usb 1-1.4: Product: flirc Aug 14 11:00:18 dens-pi4 kernel: usb 1-1.4: Manufacturer: flirc.tv Aug 14 11:00:18 dens-pi4 kernel: hid-generic 0003:20A0:0006.0002: hiddev97,hidraw1: USB HID v1.11 Device [flirc.tv flirc] on usb-0000:01:00.0-1.4/input0 Aug 14 11:00:18 dens-pi4 kernel: input: flirc.tv flirc Keyboard as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.1/0003:20A0:0006.0003/input/input0 Aug 14 11:00:18 dens-pi4 kernel: input: flirc.tv flirc Consumer Control as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.1/0003:20A0:0006.0003/input/input1 Aug 14 11:00:18 dens-pi4 kernel: input: flirc.tv flirc System Control as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.1/0003:20A0:0006.0003/input/input2 Aug 14 11:00:18 dens-pi4 kernel: hid-generic 0003:20A0:0006.0003: input,hidraw2: USB HID v1.01 Keyboard [flirc.tv flirc] on usb-0000:01:00.0-1.4/input1 Aug 14 11:00:18 dens-pi4 initramfs: Checking for USB updates Aug 14 11:00:18 dens-pi4 initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Aug 14 11:00:18 dens-pi4 kernel: EXT4-fs (mmcblk0p3): recovery complete Aug 14 11:00:18 dens-pi4 kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Aug 14 11:00:18 dens-pi4 initramfs: With Option: Aug 14 11:00:18 dens-pi4 initramfs: VOLUMIO_VERSION="3.538" Aug 14 11:00:18 dens-pi4 initramfs: Finish initramfs, continue booting Volumio Aug 14 11:00:18 dens-pi4 systemd[1]: System time before build time, advancing clock. Aug 14 11:00:18 dens-pi4 kernel: NET: Registered protocol family 10 Aug 14 11:00:18 dens-pi4 kernel: Segment Routing with IPv6 Aug 14 11:00:18 dens-pi4 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) Aug 14 11:00:18 dens-pi4 systemd[1]: Detected architecture arm. Aug 14 11:00:18 dens-pi4 systemd[1]: Set hostname to . Aug 14 11:00:18 dens-pi4 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. Aug 14 11:00:18 dens-pi4 kernel: random: systemd: uninitialized urandom read (16 bytes read) Aug 14 11:00:18 dens-pi4 kernel: random: systemd: uninitialized urandom read (16 bytes read) Aug 14 11:00:18 dens-pi4 kernel: random: systemd: uninitialized urandom read (16 bytes read) Aug 14 11:00:18 dens-pi4 systemd[1]: Reached target System Time Synchronized. Aug 14 11:00:18 dens-pi4 systemd[1]: Created slice User and Session Slice. Aug 14 11:00:18 dens-pi4 systemd[1]: Created slice system-getty.slice. Aug 14 11:00:18 dens-pi4 systemd[1]: Listening on udev Control Socket. Aug 14 11:00:18 dens-pi4 systemd[1]: Created slice system-systemd\x2dfsck.slice. Aug 14 11:00:18 dens-pi4 systemd[1]: Reached target Slices. Aug 14 11:00:18 dens-pi4 kernel: i2c /dev entries driver Aug 14 11:00:18 dens-pi4 systemd-journald[194]: Journal started Aug 14 11:00:18 dens-pi4 systemd-journald[194]: Runtime journal (/run/log/journal/4f62df4b7e374c47e98bbc1f6425ae06) is 7.5M, max 30.0M, 22.5M free. Aug 14 11:00:18 dens-pi4 systemd-modules-load[189]: Inserted module 'i2c_dev' Aug 14 11:00:18 dens-pi4 fake-hwclock[196]: Sun Aug 13 23:00:18 UTC 2023 Aug 14 11:00:18 dens-pi4 systemd[1]: dynamicswap.service: Succeeded. Aug 14 11:00:18 dens-pi4 systemd[1]: Started Create Static Device Nodes in /dev. Aug 14 11:00:18 dens-pi4 systemd[1]: Reached target Local File Systems (Pre). Aug 14 11:00:18 dens-pi4 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Aug 14 11:00:18 dens-pi4 systemd[1]: Mounting /tmp... Aug 14 11:00:18 dens-pi4 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Aug 14 11:00:18 dens-pi4 systemd[1]: Mounting /var/log... Aug 14 11:00:18 dens-pi4 systemd[1]: Mounting /var/spool/cups... Aug 14 11:00:18 dens-pi4 systemd[1]: Starting udev Kernel Device Manager... Aug 14 11:00:18 dens-pi4 systemd[1]: Mounted /tmp. Aug 14 11:00:18 dens-pi4 systemd[1]: Mounted /var/log. Aug 14 11:00:18 dens-pi4 systemd[1]: Starting Flush Journal to Persistent Storage... Aug 14 11:00:18 dens-pi4 systemd[1]: Mounted /var/spool/cups. Aug 14 11:00:18 dens-pi4 systemd[1]: Mounting /var/spool/cups/tmp... Aug 14 11:00:18 dens-pi4 systemd[1]: Mounted /var/spool/cups/tmp. Aug 14 11:00:18 dens-pi4 systemd-udevd[223]: Network interface NamePolicy= disabled on kernel command line, ignoring. Aug 14 11:00:18 dens-pi4 systemd-journald[194]: Runtime journal (/run/log/journal/4f62df4b7e374c47e98bbc1f6425ae06) is 7.5M, max 30.0M, 22.5M free. Aug 14 11:00:18 dens-pi4 systemd[1]: Started Flush Journal to Persistent Storage. Aug 14 11:00:18 dens-pi4 systemd[1]: Started udev Coldplug all Devices. Aug 14 11:00:18 dens-pi4 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Aug 14 11:00:18 dens-pi4 systemd[1]: Started udev Kernel Device Manager. Aug 14 11:00:18 dens-pi4 systemd[1]: Starting Show Plymouth Boot Screen... Aug 14 11:00:18 dens-pi4 systemd[1]: Received SIGRTMIN+20 from PID 239 (plymouthd). Aug 14 11:00:18 dens-pi4 systemd[1]: Started Show Plymouth Boot Screen. Aug 14 11:00:18 dens-pi4 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Aug 14 11:00:18 dens-pi4 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Aug 14 11:00:18 dens-pi4 systemd[1]: Reached target Paths. Aug 14 11:00:18 dens-pi4 systemd[1]: Reached target Local Encrypted Volumes. Aug 14 11:00:18 dens-pi4 systemd-udevd[246]: Using default interface naming scheme 'v240'. Aug 14 11:00:18 dens-pi4 systemd-udevd[246]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 14 11:00:18 dens-pi4 kernel: rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000 Aug 14 11:00:18 dens-pi4 kernel: rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000 Aug 14 11:00:18 dens-pi4 kernel: rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000 Aug 14 11:00:18 dens-pi4 kernel: rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000 Aug 14 11:00:18 dens-pi4 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Aug 14 11:00:18 dens-pi4 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Aug 14 11:00:18 dens-pi4 kernel: [vc_sm_connected_init]: start Aug 14 11:00:18 dens-pi4 kernel: mc: Linux media interface: v0.10 Aug 14 11:00:18 dens-pi4 kernel: [vc_sm_connected_init]: installed successfully Aug 14 11:00:19 dens-pi4 kernel: videodev: Linux video capture interface: v2.00 Aug 14 11:00:19 dens-pi4 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Aug 14 11:00:19 dens-pi4 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Aug 14 11:00:19 dens-pi4 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Aug 14 11:00:19 dens-pi4 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Aug 14 11:00:19 dens-pi4 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Aug 14 11:00:19 dens-pi4 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Aug 14 11:00:19 dens-pi4 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Aug 14 11:00:19 dens-pi4 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Aug 14 11:00:19 dens-pi4 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Aug 14 11:00:19 dens-pi4 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Aug 14 11:00:19 dens-pi4 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Aug 14 11:00:19 dens-pi4 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Aug 14 11:00:19 dens-pi4 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Aug 14 11:00:19 dens-pi4 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Aug 14 11:00:19 dens-pi4 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Aug 14 11:00:19 dens-pi4 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Aug 14 11:00:19 dens-pi4 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Aug 14 11:00:19 dens-pi4 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Aug 14 11:00:19 dens-pi4 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Aug 14 11:00:19 dens-pi4 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Aug 14 11:00:19 dens-pi4 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Aug 14 11:00:19 dens-pi4 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Aug 14 11:00:19 dens-pi4 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Aug 14 11:00:19 dens-pi4 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Aug 14 11:00:19 dens-pi4 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Aug 14 11:00:19 dens-pi4 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Aug 14 11:00:19 dens-pi4 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Aug 14 11:00:19 dens-pi4 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Aug 14 11:00:19 dens-pi4 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Aug 14 11:00:19 dens-pi4 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Aug 14 11:00:19 dens-pi4 systemd[1]: Found device /dev/mmcblk0p1. Aug 14 11:00:19 dens-pi4 systemd-udevd[245]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 14 11:00:19 dens-pi4 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Aug 14 11:00:19 dens-pi4 kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Aug 14 11:00:19 dens-pi4 kernel: cfg80211: loaded regulatory.db is malformed or signature is missing/invalid Aug 14 11:00:19 dens-pi4 systemd-udevd[241]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 14 11:00:19 dens-pi4 systemd-udevd[249]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 14 11:00:19 dens-pi4 systemd-udevd[264]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 14 11:00:19 dens-pi4 kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Aug 14 11:00:20 dens-pi4 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Aug 14 11:00:20 dens-pi4 kernel: usbcore: registered new interface driver brcmfmac Aug 14 11:00:20 dens-pi4 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Aug 14 11:00:20 dens-pi4 kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Aug 14 11:00:20 dens-pi4 systemd-udevd[244]: Using default interface naming scheme 'v240'. Aug 14 11:00:20 dens-pi4 systemd-udevd[244]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 14 11:00:20 dens-pi4 kernel: snd_usb_audio: unknown parameter 'nrpacks' ignored Aug 14 11:00:20 dens-pi4 kernel: usb 1-1.1: 1:3 : unsupported format bits 0x100000000 Aug 14 11:00:20 dens-pi4 kernel: usbcore: registered new interface driver snd-usb-audio Aug 14 11:00:21 dens-pi4 systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Aug 14 11:00:21 dens-pi4 systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Aug 14 11:00:21 dens-pi4 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Aug 14 11:00:21 dens-pi4 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Aug 14 11:00:21 dens-pi4 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Aug 14 11:00:21 dens-pi4 systemd[1]: Condition check resulted in FUSE Control File System being skipped. Aug 14 11:00:21 dens-pi4 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Aug 14 11:00:21 dens-pi4 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Aug 14 11:00:21 dens-pi4 systemd[1]: Starting File System Check on /dev/mmcblk0p1... Aug 14 11:00:21 dens-pi4 systemd[1]: Starting Load/Save RF Kill Switch Status... Aug 14 11:00:22 dens-pi4 systemd[1]: Started File System Check Daemon to report status. Aug 14 11:00:22 dens-pi4 systemd[1]: Started Load/Save RF Kill Switch Status. Aug 14 11:00:22 dens-pi4 systemd-fsck[410]: fsck.fat 4.1 (2017-01-24) Aug 14 11:00:22 dens-pi4 systemd-fsck[410]: /dev/mmcblk0p1: 297 files, 27487/46774 clusters Aug 14 11:00:22 dens-pi4 systemd[1]: Started File System Check on /dev/mmcblk0p1. Aug 14 11:00:22 dens-pi4 systemd[1]: Mounting /boot... Aug 14 11:00:22 dens-pi4 systemd[1]: Mounted /boot. Aug 14 11:00:22 dens-pi4 systemd[1]: Reached target Local File Systems. Aug 14 11:00:22 dens-pi4 systemd[1]: Started ifup for eth0. Aug 14 11:00:22 dens-pi4 systemd[1]: Started ifup for wlan0. Aug 14 11:00:22 dens-pi4 systemd[1]: Starting Preprocess NFS configuration... Aug 14 11:00:22 dens-pi4 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Aug 14 11:00:22 dens-pi4 systemd[1]: Starting Create Volatile Files and Directories... Aug 14 11:00:22 dens-pi4 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Aug 14 11:00:22 dens-pi4 systemd[1]: Received SIGRTMIN+20 from PID 239 (plymouthd). Aug 14 11:00:22 dens-pi4 systemd[1]: plymouth-read-write.service: Succeeded. Aug 14 11:00:22 dens-pi4 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Aug 14 11:00:22 dens-pi4 systemd[1]: nfs-config.service: Succeeded. Aug 14 11:00:22 dens-pi4 systemd[1]: Started Preprocess NFS configuration. Aug 14 11:00:22 dens-pi4 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Aug 14 11:00:22 dens-pi4 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Aug 14 11:00:22 dens-pi4 systemd[1]: Reached target NFS client services. Aug 14 11:00:22 dens-pi4 systemd[1]: Started Create Volatile Files and Directories. Aug 14 11:00:22 dens-pi4 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Aug 14 11:00:22 dens-pi4 systemd[1]: Starting RPC bind portmap service... Aug 14 11:00:22 dens-pi4 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Aug 14 11:00:22 dens-pi4 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Aug 14 11:00:22 dens-pi4 systemd[1]: Started Update UTMP about System Boot/Shutdown. Aug 14 11:00:22 dens-pi4 systemd[1]: Reached target System Initialization. Aug 14 11:00:22 dens-pi4 systemd[1]: Started Daily apt download activities. Aug 14 11:00:22 dens-pi4 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Aug 14 11:00:22 dens-pi4 systemd[1]: Listening on mpd.socket. Aug 14 11:00:22 dens-pi4 systemd[1]: Started Daily Cleanup of Temporary Directories. Aug 14 11:00:22 dens-pi4 systemd[1]: Started Daily apt upgrade and clean activities. Aug 14 11:00:22 dens-pi4 systemd[1]: Reached target Timers. Aug 14 11:00:22 dens-pi4 systemd[1]: Listening on D-Bus System Message Bus Socket. Aug 14 11:00:22 dens-pi4 systemd[1]: Listening on triggerhappy.socket. Aug 14 11:00:22 dens-pi4 systemd[1]: Reached target Sockets. Aug 14 11:00:22 dens-pi4 systemd[1]: Reached target Basic System. Aug 14 11:00:22 dens-pi4 kernel: 8021q: 802.1Q VLAN Support v1.8 Aug 14 11:00:22 dens-pi4 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 14 11:00:22 dens-pi4 systemd[1]: Started Manage Sound Card State (restore and store). Aug 14 11:00:22 dens-pi4 systemd[1]: Starting Initialize hardware monitoring sensors... Aug 14 11:00:22 dens-pi4 alsactl[461]: alsactl 1.1.8 daemon started Aug 14 11:00:22 dens-pi4 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Aug 14 11:00:22 dens-pi4 systemd[1]: Started D-Bus System Message Bus. Aug 14 11:00:22 dens-pi4 systemd[1]: Started Volumio Iptables Module. Aug 14 11:00:22 dens-pi4 systemd[1]: Started volumio-remote-updater.service. Aug 14 11:00:22 dens-pi4 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Aug 14 11:00:22 dens-pi4 systemd[1]: Starting Configure Bluetooth Modems connected by UART... Aug 14 11:00:22 dens-pi4 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Aug 14 11:00:22 dens-pi4 systemd[1]: Starting Login Service... Aug 14 11:00:22 dens-pi4 systemd[1]: Starting WPA supplicant... Aug 14 11:00:22 dens-pi4 systemd[1]: Starting dhcpcd on all interfaces... Aug 14 11:00:22 dens-pi4 systemd[1]: Started UPnP Renderer front-end to MPD. Aug 14 11:00:22 dens-pi4 systemd[1]: Starting triggerhappy global hotkey daemon... Aug 14 11:00:22 dens-pi4 dhcpcd[477]: Not running dhcpcd because /etc/network/interfaces Aug 14 11:00:22 dens-pi4 dhcpcd[477]: defines some interfaces that will use a Aug 14 11:00:22 dens-pi4 dhcpcd[477]: DHCP client or static address Aug 14 11:00:22 dens-pi4 avahi-daemon[473]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Aug 14 11:00:22 dens-pi4 avahi-daemon[473]: Successfully dropped root privileges. Aug 14 11:00:22 dens-pi4 avahi-daemon[473]: avahi-daemon 0.7 starting up. Aug 14 11:00:22 dens-pi4 systemd[1]: Starting Wireless Services... Aug 14 11:00:23 dens-pi4 thd[486]: Unable to parse trigger line: Aug 14 11:00:23 dens-pi4 thd[486]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Aug 14 11:00:23 dens-pi4 thd[486]: Unable to parse trigger line: Aug 14 11:00:23 dens-pi4 thd[486]: Found socket passed from systemd Aug 14 11:00:23 dens-pi4 systemd[1]: Starting Save/Restore Sound Card State... Aug 14 11:00:23 dens-pi4 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Aug 14 11:00:23 dens-pi4 systemd[1]: Starting Resets System Activity Data Collector... Aug 14 11:00:23 dens-pi4 systemd[1]: Started RPC bind portmap service. Aug 14 11:00:23 dens-pi4 systemd[1]: Started triggerhappy global hotkey daemon. Aug 14 11:00:23 dens-pi4 systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Aug 14 11:00:23 dens-pi4 systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Aug 14 11:00:23 dens-pi4 systemd[1]: Failed to start dhcpcd on all interfaces. Aug 14 11:00:23 dens-pi4 systemd[1]: Started Resets System Activity Data Collector. Aug 14 11:00:23 dens-pi4 sensors[505]: cpu_thermal-virtual-0 Aug 14 11:00:23 dens-pi4 sensors[505]: Adapter: Virtual device Aug 14 11:00:23 dens-pi4 sensors[505]: temp1: +34.6°C Aug 14 11:00:23 dens-pi4 sensors[505]: rpi_volt-isa-0000 Aug 14 11:00:23 dens-pi4 sensors[505]: Adapter: ISA adapter Aug 14 11:00:23 dens-pi4 sensors[505]: in0: N/A Aug 14 11:00:23 dens-pi4 systemd[1]: Started Save/Restore Sound Card State. Aug 14 11:00:23 dens-pi4 systemd-logind[475]: Watching system buttons on /dev/input/event1 (flirc.tv flirc Consumer Control) Aug 14 11:00:23 dens-pi4 systemd-logind[475]: Watching system buttons on /dev/input/event2 (flirc.tv flirc System Control) Aug 14 11:00:23 dens-pi4 systemd-logind[475]: New seat seat0. Aug 14 11:00:23 dens-pi4 systemd[1]: Started Initialize hardware monitoring sensors. Aug 14 11:00:23 dens-pi4 systemd[1]: Reached target Sound Card. Aug 14 11:00:23 dens-pi4 systemd[1]: Reached target RPC Port Mapper. Aug 14 11:00:23 dens-pi4 systemd[1]: Reached target Remote File Systems (Pre). Aug 14 11:00:23 dens-pi4 systemd[1]: Reached target Remote File Systems. Aug 14 11:00:23 dens-pi4 avahi-daemon[473]: Successfully called chroot(). Aug 14 11:00:23 dens-pi4 avahi-daemon[473]: Successfully dropped remaining capabilities. Aug 14 11:00:23 dens-pi4 systemd[1]: Started Regular background program processing daemon. Aug 14 11:00:23 dens-pi4 sh[417]: wlan0=wlan0 Aug 14 11:00:23 dens-pi4 cron[523]: (CRON) INFO (pidfile fd = 3) Aug 14 11:00:23 dens-pi4 kernel: uart-pl011 fe201000.serial: no DMA platform data Aug 14 11:00:23 dens-pi4 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Aug 14 11:00:23 dens-pi4 avahi-daemon[473]: Loading service file /services/volumio.service. Aug 14 11:00:23 dens-pi4 dbus-daemon[465]: [system] Successfully activated service 'org.freedesktop.systemd1' Aug 14 11:00:23 dens-pi4 cron[523]: (CRON) INFO (Running @reboot jobs) Aug 14 11:00:23 dens-pi4 systemd[1]: Started Login Service. Aug 14 11:00:23 dens-pi4 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Aug 14 11:00:23 dens-pi4 avahi-daemon[473]: Network interface enumeration completed. Aug 14 11:00:23 dens-pi4 avahi-daemon[473]: Server startup complete. Host name is dens-pi4.local. Local service cookie is 4240087805. Aug 14 11:00:23 dens-pi4 avahi-daemon[473]: Service "Dens-Pi4" (/services/volumio.service) successfully established. Aug 14 11:00:23 dens-pi4 sh[416]: eth0: waiting for carrier Aug 14 11:00:23 dens-pi4 kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Aug 14 11:00:23 dens-pi4 kernel: bcmgenet fd580000.ethernet eth0: Link is Down Aug 14 11:00:23 dens-pi4 dhcpcd[454]: eth0: waiting for carrier Aug 14 11:00:24 dens-pi4 volumio-remote-updater[470]: Error: No active session Aug 14 11:00:24 dens-pi4 volumio-remote-updater[470]: [2023-08-14 11:00:24] [info] asio async_connect error: system:111 (Connection refused) Aug 14 11:00:24 dens-pi4 volumio-remote-updater[470]: [2023-08-14 11:00:24] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 14 11:00:24 dens-pi4 volumio-remote-updater[470]: [2023-08-14 11:00:24] [error] handle_connect error: Underlying Transport Error Aug 14 11:00:24 dens-pi4 loadcpufreq[524]: Loading cpufreq kernel modules...done (none). Aug 14 11:00:24 dens-pi4 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Aug 14 11:00:24 dens-pi4 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Aug 14 11:00:24 dens-pi4 cpufrequtils[559]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Aug 14 11:00:24 dens-pi4 systemd[1]: Started LSB: set CPUFreq kernel parameters. Aug 14 11:00:24 dens-pi4 systemd[1]: iptables.service: Succeeded. Aug 14 11:00:24 dens-pi4 volumio[481]: Could not open config: /tmp/upmpdcli.conf Aug 14 11:00:24 dens-pi4 systemd[1]: Started WPA supplicant. Aug 14 11:00:24 dens-pi4 wpa_supplicant[476]: Successfully initialized wpa_supplicant Aug 14 11:00:24 dens-pi4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 14 11:00:24 dens-pi4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 14 11:00:25 dens-pi4 haveged[457]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Aug 14 11:00:25 dens-pi4 haveged[457]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Aug 14 11:00:25 dens-pi4 haveged[457]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99992 Aug 14 11:00:25 dens-pi4 haveged[457]: haveged: fills: 0, generated: 0 Aug 14 11:00:25 dens-pi4 kernel: random: crng init done Aug 14 11:00:25 dens-pi4 kernel: random: 7 urandom warning(s) missed due to ratelimiting Aug 14 11:00:25 dens-pi4 wireless.js[494]: Cleaning previous... Aug 14 11:00:25 dens-pi4 sudo[592]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 14 11:00:25 dens-pi4 sudo[592]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:25 dens-pi4 sudo[592]: pam_unix(sudo:session): session closed for user root Aug 14 11:00:25 dens-pi4 sudo[594]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 14 11:00:25 dens-pi4 sudo[594]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:26 dens-pi4 sudo[594]: pam_unix(sudo:session): session closed for user root Aug 14 11:00:26 dens-pi4 wireless.js[494]: Stopped aP Aug 14 11:00:26 dens-pi4 wireless.js[494]: Wireless Networking DISABLED, not starting wireless flow Aug 14 11:00:26 dens-pi4 systemd[1]: wireless.service: Succeeded. Aug 14 11:00:26 dens-pi4 systemd[1]: Started Wireless Services. Aug 14 11:00:26 dens-pi4 systemd[1]: Started Volumio Backend Module. Aug 14 11:00:27 dens-pi4 systemd[1]: systemd-rfkill.service: Succeeded. Aug 14 11:00:27 dens-pi4 sh[416]: eth0: carrier acquired Aug 14 11:00:27 dens-pi4 dhcpcd[454]: eth0: carrier acquired Aug 14 11:00:27 dens-pi4 sh[416]: DUID 00:01:00:01:2b:b8:6c:3f:dc:a6:32:0e:46:39 Aug 14 11:00:27 dens-pi4 sh[416]: eth0: IAID 32:0e:46:39 Aug 14 11:00:27 dens-pi4 sh[416]: eth0: adding address fe80::dea6:32ff:fe0e:4639 Aug 14 11:00:27 dens-pi4 sh[416]: ipv6_addaddr1: Permission denied Aug 14 11:00:27 dens-pi4 kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Aug 14 11:00:27 dens-pi4 dhcpcd[454]: DUID 00:01:00:01:2b:b8:6c:3f:dc:a6:32:0e:46:39 Aug 14 11:00:27 dens-pi4 dhcpcd[454]: eth0: IAID 32:0e:46:39 Aug 14 11:00:27 dens-pi4 dhcpcd[454]: eth0: adding address fe80::dea6:32ff:fe0e:4639 Aug 14 11:00:27 dens-pi4 dhcpcd[454]: ipv6_addaddr1: Permission denied Aug 14 11:00:27 dens-pi4 sh[416]: eth0: soliciting an IPv6 router Aug 14 11:00:27 dens-pi4 dhcpcd[454]: eth0: soliciting an IPv6 router Aug 14 11:00:27 dens-pi4 sh[416]: eth0: soliciting a DHCP lease Aug 14 11:00:27 dens-pi4 dhcpcd[454]: eth0: soliciting a DHCP lease Aug 14 11:00:28 dens-pi4 volumio[602]: info: ------------------------------------------- Aug 14 11:00:28 dens-pi4 volumio[602]: info: ----- Volumio3 ---- Aug 14 11:00:28 dens-pi4 volumio[602]: info: ------------------------------------------- Aug 14 11:00:28 dens-pi4 volumio[602]: info: ----- System startup ---- Aug 14 11:00:28 dens-pi4 volumio[602]: info: ------------------------------------------- Aug 14 11:00:29 dens-pi4 volumio-remote-updater[470]: [2023-08-14 11:00:29] [connect] Successful connection Aug 14 11:00:29 dens-pi4 volumio[602]: info: MYVOLUMIO Environment detected Aug 14 11:00:29 dens-pi4 volumio[602]: info: Plugin folders cleanup Aug 14 11:00:29 dens-pi4 volumio[602]: info: Scanning into folder /volumio/app/plugins/ Aug 14 11:00:29 dens-pi4 volumio[602]: info: Scanning category audio_interface Aug 14 11:00:29 dens-pi4 volumio[602]: info: Scanning category miscellanea Aug 14 11:00:29 dens-pi4 volumio[602]: info: Scanning category music_service Aug 14 11:00:29 dens-pi4 volumio[602]: info: Scanning category plugins.json Aug 14 11:00:29 dens-pi4 volumio[602]: info: Scanning category system_controller Aug 14 11:00:29 dens-pi4 volumio[602]: info: Scanning category user_interface Aug 14 11:00:29 dens-pi4 volumio[602]: info: Scanning into folder /data/plugins/ Aug 14 11:00:29 dens-pi4 volumio[602]: info: Scanning category music_service Aug 14 11:00:29 dens-pi4 volumio[602]: info: Scanning category system_controller Aug 14 11:00:29 dens-pi4 volumio[602]: info: Scanning category system_hardware Aug 14 11:00:29 dens-pi4 volumio[602]: info: Scanning category user_interface Aug 14 11:00:29 dens-pi4 volumio[602]: info: Plugin folders cleanup completed Aug 14 11:00:29 dens-pi4 volumio[602]: info: ------------------------------------------- Aug 14 11:00:29 dens-pi4 volumio[602]: info: ----- Core plugins startup ---- Aug 14 11:00:29 dens-pi4 volumio[602]: info: ------------------------------------------- Aug 14 11:00:29 dens-pi4 volumio[602]: info: Loading plugins from folder /volumio/app/plugins/ Aug 14 11:00:29 dens-pi4 volumio[602]: info: Adding plugin upnp to MyMusic Plugins Aug 14 11:00:29 dens-pi4 volumio[602]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 14 11:00:29 dens-pi4 volumio[602]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 14 11:00:29 dens-pi4 volumio[602]: info: Loading plugins from folder /data/plugins/ Aug 14 11:00:29 dens-pi4 volumio[602]: info: Loading plugin "system"... Aug 14 11:00:29 dens-pi4 volumio[602]: info: Loading plugin "appearance"... Aug 14 11:00:29 dens-pi4 sh[416]: eth0: offered 192.168.1.82 from 192.168.1.2 Aug 14 11:00:29 dens-pi4 dhcpcd[454]: eth0: offered 192.168.1.82 from 192.168.1.2 Aug 14 11:00:30 dens-pi4 sh[416]: eth0: probing address 192.168.1.82/24 Aug 14 11:00:30 dens-pi4 dhcpcd[454]: eth0: probing address 192.168.1.82/24 Aug 14 11:00:30 dens-pi4 volumio[602]: info: Loading plugin "network"... Aug 14 11:00:30 dens-pi4 volumio[602]: info: Refreshing Cached IP Addresses Aug 14 11:00:30 dens-pi4 sudo[621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 14 11:00:30 dens-pi4 sudo[621]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:30 dens-pi4 volumio[602]: info: Loading plugin "services"... Aug 14 11:00:30 dens-pi4 sudo[621]: pam_unix(sudo:session): session closed for user root Aug 14 11:00:30 dens-pi4 sudo[623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 14 11:00:30 dens-pi4 volumio[602]: info: Loading plugin "alsa_controller"... Aug 14 11:00:30 dens-pi4 sudo[623]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:30 dens-pi4 sudo[623]: pam_unix(sudo:session): session closed for user root Aug 14 11:00:30 dens-pi4 volumio[602]: warn: Unable to locate the audio output device MAX98357A. Please configure a valid output device. Aug 14 11:00:30 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 14 11:00:30 dens-pi4 volumio[602]: info: Loading plugin "wizard"... Aug 14 11:00:30 dens-pi4 volumio[602]: info: Loading plugin "volumio_command_line_client"... Aug 14 11:00:30 dens-pi4 volumio[602]: info: Loading plugin "upnp"... Aug 14 11:00:30 dens-pi4 volumio[602]: info: [1691967630316] Starting Upmpd Daemon Aug 14 11:00:30 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 14 11:00:30 dens-pi4 volumio[602]: info: Loading plugin "my_music"... Aug 14 11:00:30 dens-pi4 volumio[602]: info: Loading plugin "mpd"... Aug 14 11:00:30 dens-pi4 kernel: Bluetooth: Core ver 2.22 Aug 14 11:00:30 dens-pi4 kernel: NET: Registered protocol family 31 Aug 14 11:00:30 dens-pi4 kernel: Bluetooth: HCI device and connection manager initialized Aug 14 11:00:30 dens-pi4 kernel: Bluetooth: HCI socket layer initialized Aug 14 11:00:30 dens-pi4 kernel: Bluetooth: L2CAP socket layer initialized Aug 14 11:00:30 dens-pi4 kernel: Bluetooth: SCO socket layer initialized Aug 14 11:00:30 dens-pi4 btuart[474]: bcm43xx_init Aug 14 11:00:30 dens-pi4 btuart[474]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd Aug 14 11:00:30 dens-pi4 btuart[474]: Set Controller UART speed to 3000000 bit/s Aug 14 11:00:30 dens-pi4 btuart[474]: Device setup complete Aug 14 11:00:30 dens-pi4 kernel: Bluetooth: HCI UART driver ver 2.3 Aug 14 11:00:30 dens-pi4 kernel: Bluetooth: HCI UART protocol H4 registered Aug 14 11:00:30 dens-pi4 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Aug 14 11:00:30 dens-pi4 kernel: Bluetooth: HCI UART protocol Broadcom registered Aug 14 11:00:30 dens-pi4 systemd[1]: Starting Load/Save RF Kill Switch Status... Aug 14 11:00:30 dens-pi4 systemd[1]: Started Configure Bluetooth Modems connected by UART. Aug 14 11:00:30 dens-pi4 systemd[1]: Started Load/Save RF Kill Switch Status. Aug 14 11:00:30 dens-pi4 systemd[1]: Created slice system-bthelper.slice. Aug 14 11:00:30 dens-pi4 systemd[1]: Starting Raspberry Pi bluetooth helper... Aug 14 11:00:30 dens-pi4 bthelper[634]: Raspberry Pi BDADDR already set Aug 14 11:00:30 dens-pi4 systemd[1]: Started Raspberry Pi bluetooth helper. Aug 14 11:00:30 dens-pi4 systemd[1]: Starting Bluetooth service... Aug 14 11:00:30 dens-pi4 bluetoothd[643]: Bluetooth daemon 5.50 Aug 14 11:00:30 dens-pi4 bluetoothd[643]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Aug 14 11:00:30 dens-pi4 systemd[1]: Started Bluetooth service. Aug 14 11:00:30 dens-pi4 systemd[1]: Reached target Bluetooth. Aug 14 11:00:30 dens-pi4 bluetoothd[643]: Starting SDP server Aug 14 11:00:30 dens-pi4 bluetoothd[643]: Excluding (cli) sap Aug 14 11:00:30 dens-pi4 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Aug 14 11:00:30 dens-pi4 kernel: Bluetooth: BNEP filters: protocol multicast Aug 14 11:00:30 dens-pi4 kernel: Bluetooth: BNEP socket layer initialized Aug 14 11:00:30 dens-pi4 dbus-daemon[465]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=643 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Aug 14 11:00:30 dens-pi4 bluetoothd[643]: Bluetooth management interface 1.18 initialized Aug 14 11:00:30 dens-pi4 volumio[602]: info: Creating MPD Configuration file Aug 14 11:00:30 dens-pi4 systemd[1]: Starting Hostname Service... Aug 14 11:00:30 dens-pi4 sudo[647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 14 11:00:30 dens-pi4 sudo[647]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:30 dens-pi4 volumio[602]: info: Loading plugin "upnp_browser"... Aug 14 11:00:30 dens-pi4 sudo[650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 14 11:00:30 dens-pi4 sudo[650]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:30 dens-pi4 sudo[647]: pam_unix(sudo:session): session closed for user root Aug 14 11:00:30 dens-pi4 bluetoothd[643]: Failed to set privacy: Rejected (0x0b) Aug 14 11:00:30 dens-pi4 dbus-daemon[465]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 14 11:00:30 dens-pi4 systemd[1]: Started Hostname Service. Aug 14 11:00:31 dens-pi4 volumio[602]: info: Loading plugin "networkfs"... Aug 14 11:00:31 dens-pi4 sudo[655]: 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.20/Review /mnt/NAS/Review Aug 14 11:00:31 dens-pi4 sudo[655]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:31 dens-pi4 sudo[657]: 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.20/Soundtrack /mnt/NAS/Soundtrack Aug 14 11:00:31 dens-pi4 sudo[657]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:31 dens-pi4 sudo[665]: 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.20/audio/hi-definition/stereo /mnt/NAS/Stereo Aug 14 11:00:31 dens-pi4 sudo[665]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:31 dens-pi4 volumio[602]: info: Starting Udev Watcher for removable devices Aug 14 11:00:31 dens-pi4 sudo[669]: 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.20/Own /mnt/NAS/Own Aug 14 11:00:31 dens-pi4 sudo[669]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:31 dens-pi4 sudo[672]: 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.20/audio/hi-definition/Classical /mnt/NAS/Classical Aug 14 11:00:31 dens-pi4 sudo[672]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:31 dens-pi4 kernel: FS-Cache: Netfs 'cifs' registered for caching Aug 14 11:00:31 dens-pi4 kernel: Key type cifs.spnego registered Aug 14 11:00:31 dens-pi4 kernel: Key type cifs.idmap registered Aug 14 11:00:31 dens-pi4 sudo[657]: pam_unix(sudo:session): session closed for user root Aug 14 11:00:31 dens-pi4 kernel: CIFS: Attempting to mount //192.168.1.20/Soundtrack Aug 14 11:00:31 dens-pi4 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. Aug 14 11:00:31 dens-pi4 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 14 11:00:31 dens-pi4 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Aug 14 11:00:31 dens-pi4 kernel: CIFS: Attempting to mount //192.168.1.20/Review Aug 14 11:00:31 dens-pi4 kernel: CIFS: Attempting to mount //192.168.1.20/audio/hi-definition/stereo Aug 14 11:00:31 dens-pi4 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 14 11:00:31 dens-pi4 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Aug 14 11:00:31 dens-pi4 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Aug 14 11:00:31 dens-pi4 sudo[665]: pam_unix(sudo:session): session closed for user root Aug 14 11:00:31 dens-pi4 sudo[655]: pam_unix(sudo:session): session closed for user root Aug 14 11:00:31 dens-pi4 sudo[669]: pam_unix(sudo:session): session closed for user root Aug 14 11:00:31 dens-pi4 sudo[672]: pam_unix(sudo:session): session closed for user root Aug 14 11:00:31 dens-pi4 kernel: CIFS: Attempting to mount //192.168.1.20/Own Aug 14 11:00:31 dens-pi4 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 14 11:00:31 dens-pi4 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Aug 14 11:00:31 dens-pi4 kernel: CIFS: Attempting to mount //192.168.1.20/audio/hi-definition/Classical Aug 14 11:00:31 dens-pi4 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 14 11:00:31 dens-pi4 kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Aug 14 11:00:31 dens-pi4 volumio[602]: info: Ignoring mount for partition: boot Aug 14 11:00:31 dens-pi4 volumio[602]: info: Ignoring mount for partition: volumio Aug 14 11:00:31 dens-pi4 volumio[602]: info: Ignoring mount for partition: volumio_data Aug 14 11:00:31 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 14 11:00:31 dens-pi4 volumio[602]: info: Loading plugin "alarm-clock"... Aug 14 11:00:31 dens-pi4 volumio[602]: info: Loading plugin "airplay_emulation"... Aug 14 11:00:31 dens-pi4 volumio[602]: info: Starting Shairport Sync Aug 14 11:00:31 dens-pi4 volumio[602]: info: Loading plugin "last_100"... Aug 14 11:00:31 dens-pi4 volumio[602]: info: Loading plugin "webradio"... Aug 14 11:00:31 dens-pi4 volumio[602]: info: Loading plugin "i2s_dacs"... Aug 14 11:00:31 dens-pi4 volumio[602]: info: Loading plugin "volumiodiscovery"... Aug 14 11:00:32 dens-pi4 volumio[602]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 14 11:00:32 dens-pi4 volumio[602]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 14 11:00:32 dens-pi4 volumio[602]: *** WARNING *** For more information see Aug 14 11:00:32 dens-pi4 node[602]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 14 11:00:32 dens-pi4 volumio[602]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 14 11:00:32 dens-pi4 volumio[602]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 14 11:00:32 dens-pi4 volumio[602]: *** WARNING *** For more information see Aug 14 11:00:32 dens-pi4 node[602]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 14 11:00:32 dens-pi4 node[602]: *** WARNING *** For more information see Aug 14 11:00:32 dens-pi4 node[602]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 14 11:00:32 dens-pi4 node[602]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 14 11:00:32 dens-pi4 node[602]: *** WARNING *** For more information see Aug 14 11:00:32 dens-pi4 volumio[602]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 14 11:00:32 dens-pi4 volumio[602]: info: Discovery: Started advertising with name: Dens-Pi4 Aug 14 11:00:32 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 14 11:00:32 dens-pi4 volumio[602]: info: Loading plugin "now_playing"... Aug 14 11:00:33 dens-pi4 volumio[602]: info: Loading plugin "outputs"... Aug 14 11:00:33 dens-pi4 volumio[602]: info: Loading plugin "albumart"... Aug 14 11:00:33 dens-pi4 volumio[602]: info: Plugin example_plugin is not enabled Aug 14 11:00:33 dens-pi4 volumio[602]: info: Loading plugin "inputs"... Aug 14 11:00:33 dens-pi4 volumio[602]: info: Loading plugin "updater_comm"... Aug 14 11:00:33 dens-pi4 volumio[602]: info: Plugin mpdemulation is not enabled Aug 14 11:00:33 dens-pi4 volumio[602]: info: Loading plugin "rest_api"... Aug 14 11:00:33 dens-pi4 volumio[602]: info: Loading plugin "websocket"... Aug 14 11:00:33 dens-pi4 volumio[602]: info: Loading plugin "plexamp"... Aug 14 11:00:33 dens-pi4 volumio[602]: Forking 3 albumart workers Aug 14 11:00:33 dens-pi4 volumio[602]: Starting albumart workers Aug 14 11:00:33 dens-pi4 volumio[602]: Starting albumart workers Aug 14 11:00:34 dens-pi4 volumio[602]: Starting albumart workers Aug 14 11:00:34 dens-pi4 volumio[602]: info: Applying required configuration parameters for plugin plexamp Aug 14 11:00:35 dens-pi4 volumio[602]: info: Loading plugin "radio_paradise"... Aug 14 11:00:35 dens-pi4 volumio[602]: info: Applying required configuration parameters for plugin radio_paradise Aug 14 11:00:35 dens-pi4 volumio[602]: info: [1691967635238] [RadioParadise] API delay: 5 Aug 14 11:00:35 dens-pi4 volumio[602]: info: Loading plugin "backup_restore"... Aug 14 11:00:35 dens-pi4 sh[416]: eth0: leased 192.168.1.82 for 86400 seconds Aug 14 11:00:35 dens-pi4 sh[416]: eth0: adding route to 192.168.1.0/24 Aug 14 11:00:35 dens-pi4 sh[416]: eth0: adding default route via 192.168.1.2 Aug 14 11:00:35 dens-pi4 dhcpcd[454]: eth0: leased 192.168.1.82 for 86400 seconds Aug 14 11:00:35 dens-pi4 avahi-daemon[473]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.82. Aug 14 11:00:35 dens-pi4 avahi-daemon[473]: New relevant interface eth0.IPv4 for mDNS. Aug 14 11:00:35 dens-pi4 dhcpcd[454]: eth0: adding route to 192.168.1.0/24 Aug 14 11:00:35 dens-pi4 avahi-daemon[473]: Registering new address record for 192.168.1.82 on eth0.IPv4. Aug 14 11:00:35 dens-pi4 dhcpcd[454]: eth0: adding default route via 192.168.1.2 Aug 14 11:00:35 dens-pi4 volumio[602]: info: Applying required configuration parameters for plugin backup_restore Aug 14 11:00:35 dens-pi4 volumio[602]: info: Loading plugin "crossfader_switch"... Aug 14 11:00:35 dens-pi4 sh[416]: forked to background, child pid 765 Aug 14 11:00:35 dens-pi4 dhcpcd[454]: forked to background, child pid 765 Aug 14 11:00:35 dens-pi4 systemd[1]: systemd-rfkill.service: Succeeded. Aug 14 11:00:35 dens-pi4 sh[416]: eth0=eth0 Aug 14 11:00:35 dens-pi4 bthelper[634]: Changing power off succeeded Aug 14 11:00:35 dens-pi4 volumio[602]: info: Applying required configuration parameters for plugin crossfader_switch Aug 14 11:00:35 dens-pi4 volumio[602]: info: Loading plugin "gpio_control"... Aug 14 11:00:35 dens-pi4 bthelper[634]: [CHG] Controller DC:A6:32:0E:46:3B Class: 0x0000041c Aug 14 11:00:35 dens-pi4 bthelper[634]: Changing power on succeeded Aug 14 11:00:36 dens-pi4 volumio[602]: info: Applying required configuration parameters for plugin gpio_control Aug 14 11:00:36 dens-pi4 volumio[602]: info: [GPIO_Control] Detected Raspberry Pi 4 Model B Rev 1.1 Aug 14 11:00:36 dens-pi4 volumio[602]: info: [GPIO_Control] 40 GPIOs: true Aug 14 11:00:36 dens-pi4 volumio[602]: info: [GPIO_Control] Initialized Aug 14 11:00:36 dens-pi4 volumio[602]: info: Loading plugin "Systeminfo"... Aug 14 11:00:36 dens-pi4 volumio[602]: info: Loading plugin "randomizer"... Aug 14 11:00:36 dens-pi4 volumio[602]: info: Applying required configuration parameters for plugin randomizer Aug 14 11:00:36 dens-pi4 volumio[602]: info: Loading i18n strings for locale en Aug 14 11:00:36 dens-pi4 volumio[602]: Updating browse sources language Aug 14 11:00:36 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::initPlayerControls Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: Express server listening on port 3000 Aug 14 11:00:37 dens-pi4 volumio[602]: [Metrics] WebUI: 9s 519.34ms Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreStateMachine::resetVolumioState Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreStateMachine::getcurrentVolume Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioRetrievevolume Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:00:37 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:00:37 dens-pi4 volumio[602]: info: Cannot mount NAS Classical at system boot, trial number 1 ,retrying in 5 seconds Aug 14 11:00:37 dens-pi4 volumio[602]: info: Cannot mount NAS Own at system boot, trial number 1 ,retrying in 5 seconds Aug 14 11:00:37 dens-pi4 volumio[602]: info: Cannot mount NAS Stereo at system boot, trial number 1 ,retrying in 5 seconds Aug 14 11:00:37 dens-pi4 volumio[602]: info: Cannot mount NAS Soundtrack at system boot, trial number 1 ,retrying in 5 seconds Aug 14 11:00:37 dens-pi4 volumio[602]: info: Cannot mount NAS Review at system boot, trial number 1 ,retrying in 5 seconds Aug 14 11:00:37 dens-pi4 volumio[602]: info: MPD Permissions set Aug 14 11:00:37 dens-pi4 volumio[602]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:00:37 dens-pi4 volumio[602]: info: Setting Device type: Raspberry PI Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreStateMachine::setRepeat false single undefined Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:00:37 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreStateMachine::setRandom null Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:00:37 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:00:37 dens-pi4 volumio[602]: info: Discovery: adding 54db4094-7279-4df4-922a-9628b8ed5d4d Aug 14 11:00:37 dens-pi4 volumio[602]: info: Discovery: Found device Dens-Pi4 Aug 14 11:00:37 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioGetState Aug 14 11:00:37 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:00:40 dens-pi4 kernel: cam-dummy-reg: disabling Aug 14 11:00:40 dens-pi4 kernel: cam1-reg: disabling Aug 14 11:00:40 dens-pi4 sudo[813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 14 11:00:40 dens-pi4 sudo[813]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:40 dens-pi4 sudo[813]: pam_unix(sudo:session): session closed for user root Aug 14 11:00:40 dens-pi4 sudo[816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 14 11:00:40 dens-pi4 sudo[816]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:40 dens-pi4 sudo[816]: pam_unix(sudo:session): session closed for user root Aug 14 11:00:40 dens-pi4 sudo[819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 14 11:00:40 dens-pi4 sudo[819]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:40 dens-pi4 systemd[1]: Started UPnP Renderer front-end to MPD. Aug 14 11:00:40 dens-pi4 sudo[819]: pam_unix(sudo:session): session closed for user root Aug 14 11:00:40 dens-pi4 volumio[602]: info: Upmpdcli Daemon Started Aug 14 11:00:41 dens-pi4 volumio-remote-updater[470]: [2023-08-14 11:00:41] [connect] Successful connection Aug 14 11:00:41 dens-pi4 volumio-remote-updater[470]: [2023-08-14 11:00:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1691967641 101 Aug 14 11:00:41 dens-pi4 volumio[602]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 Aug 14 11:00:42 dens-pi4 sudo[823]: 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.20/audio/hi-definition/Classical /mnt/NAS/Classical Aug 14 11:00:42 dens-pi4 sudo[823]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:42 dens-pi4 sudo[826]: 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.20/Own /mnt/NAS/Own Aug 14 11:00:42 dens-pi4 sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:42 dens-pi4 kernel: CIFS: Attempting to mount //192.168.1.20/audio/hi-definition/Classical Aug 14 11:00:42 dens-pi4 sudo[832]: 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.20/audio/hi-definition/stereo /mnt/NAS/Stereo Aug 14 11:00:42 dens-pi4 sudo[832]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:42 dens-pi4 kernel: CIFS: Attempting to mount //192.168.1.20/Own Aug 14 11:00:42 dens-pi4 kernel: CIFS: Attempting to mount //192.168.1.20/audio/hi-definition/stereo Aug 14 11:00:42 dens-pi4 sudo[843]: 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.20/Soundtrack /mnt/NAS/Soundtrack Aug 14 11:00:42 dens-pi4 sudo[843]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:42 dens-pi4 sudo[851]: 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.20/Review /mnt/NAS/Review Aug 14 11:00:42 dens-pi4 sudo[851]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:00:42 dens-pi4 kernel: CIFS: Attempting to mount //192.168.1.20/Soundtrack Aug 14 11:00:42 dens-pi4 kernel: CIFS: Attempting to mount //192.168.1.20/Review Aug 14 11:00:42 dens-pi4 kernel: FS-Cache: Duplicate cookie detected Aug 14 11:00:42 dens-pi4 kernel: FS-Cache: O-cookie c=d682aa6f [p=46b93f29 fl=222 nc=0 na=1] Aug 14 11:00:42 dens-pi4 kernel: FS-Cache: O-cookie d=c0fd404c n=de02ffcc Aug 14 11:00:42 dens-pi4 kernel: FS-Cache: O-key=[5] '617564696f' Aug 14 11:00:42 dens-pi4 kernel: FS-Cache: N-cookie c=f30e4a77 [p=46b93f29 fl=2 nc=0 na=1] Aug 14 11:00:42 dens-pi4 kernel: FS-Cache: N-cookie d=c0fd404c n=5ff309e1 Aug 14 11:00:42 dens-pi4 kernel: FS-Cache: N-key=[5] '617564696f' Aug 14 11:00:42 dens-pi4 sudo[826]: pam_unix(sudo:session): session closed for user root Aug 14 11:00:42 dens-pi4 sudo[832]: pam_unix(sudo:session): session closed for user root Aug 14 11:00:42 dens-pi4 sudo[843]: pam_unix(sudo:session): session closed for user root Aug 14 11:00:42 dens-pi4 sudo[851]: pam_unix(sudo:session): session closed for user root Aug 14 11:00:42 dens-pi4 sudo[823]: pam_unix(sudo:session): session closed for user root Aug 14 11:00:52 dens-pi4 systemd[1]: systemd-fsckd.service: Succeeded. Aug 14 11:01:00 dens-pi4 systemd[1]: systemd-hostnamed.service: Succeeded. Aug 14 11:01:06 dens-pi4 volumio[602]: error: Plugin music_service mpd failed to complete 'onVolumioStart' in a timely fashion Aug 14 11:01:06 dens-pi4 volumio[602]: info: Preparing to generate the ALSA configuration file Aug 14 11:01:06 dens-pi4 volumio[602]: info: Asound.conf file unchanged, so no further update is needed Aug 14 11:01:06 dens-pi4 volumio[602]: info: Output device has changed, restarting MPD Aug 14 11:01:07 dens-pi4 sudo[866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 14 11:01:07 dens-pi4 sudo[866]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:01:07 dens-pi4 volumio[602]: info: Output device has changed, restarting Shairport Sync Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:07 dens-pi4 sudo[866]: pam_unix(sudo:session): session closed for user root Aug 14 11:01:07 dens-pi4 sudo[869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 14 11:01:07 dens-pi4 sudo[869]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:01:07 dens-pi4 volumio[602]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 14 11:01:07 dens-pi4 volumio[602]: info: ___________ START PLUGINS ___________ Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 14 11:01:07 dens-pi4 volumio[602]: info: [1691967667068] CoreMusicLibrary::Adding element Media Servers Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:07 dens-pi4 volumio[602]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:07 dens-pi4 volumio[602]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 14 11:01:07 dens-pi4 volumio[602]: info: [1691967667163] CoreMusicLibrary::Adding element Last_100 Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 14 11:01:07 dens-pi4 volumio[602]: info: [1691967667166] CoreMusicLibrary::Adding element Webradio Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 14 11:01:07 dens-pi4 volumio[602]: info: [now-playing-config] Config is up to date. Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 14 11:01:07 dens-pi4 volumio[602]: info: Discovery: Getting this device information Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioGetState Aug 14 11:01:07 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 14 11:01:07 dens-pi4 volumio[602]: info: Loading i18n strings for locale en Aug 14 11:01:07 dens-pi4 volumio[602]: Updating browse sources language Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 14 11:01:07 dens-pi4 volumio[602]: info: [1691967667240] CoreMusicLibrary::Adding element Radio Paradise Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 14 11:01:07 dens-pi4 volumio[602]: Cannot find translation for source Radio Paradise Aug 14 11:01:07 dens-pi4 volumio[602]: info: [GPIO_Control] Reading config and creating GPIOs Aug 14 11:01:07 dens-pi4 volumio[602]: info: Volumio Calling Home Aug 14 11:01:07 dens-pi4 volumio[602]: info: [now-playing-app] App is listening on port 4004. Aug 14 11:01:07 dens-pi4 volumio[602]: info: PlexAmp::Plex initialised[object Object] Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 14 11:01:07 dens-pi4 volumio[602]: info: [1691967667314] CoreMusicLibrary::Adding element Plex Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 14 11:01:07 dens-pi4 volumio[602]: Cannot find translation for source Radio Paradise Aug 14 11:01:07 dens-pi4 volumio[602]: Cannot find translation for source Plex Aug 14 11:01:07 dens-pi4 volumio[602]: info: [GPIO_Control] GPIOs created Aug 14 11:01:07 dens-pi4 volumio[602]: info: Completed starting Core Plugins Aug 14 11:01:07 dens-pi4 volumio[602]: info: ------------------------------------------- Aug 14 11:01:07 dens-pi4 volumio[602]: info: ----- MyVolumio plugins startup ---- Aug 14 11:01:07 dens-pi4 volumio[602]: info: ------------------------------------------- Aug 14 11:01:07 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioGetState Aug 14 11:01:07 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:01:07 dens-pi4 volumio[602]: info: MPD Permissions set Aug 14 11:01:07 dens-pi4 volumio[602]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Aug 14 11:01:07 dens-pi4 volumio[602]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:01:07 dens-pi4 volumio[602]: info: Starting Shairport Sync Aug 14 11:01:07 dens-pi4 volumio[602]: info: Starting Shairport Sync Aug 14 11:01:07 dens-pi4 sudo[888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 14 11:01:07 dens-pi4 sudo[888]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:01:07 dens-pi4 volumio[602]: info: Starting Shairport Sync Aug 14 11:01:07 dens-pi4 sudo[891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 14 11:01:07 dens-pi4 sudo[891]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:01:07 dens-pi4 sudo[893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 14 11:01:07 dens-pi4 sudo[893]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:01:07 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioGetState Aug 14 11:01:07 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:01:07 dens-pi4 volumio[602]: info: Volumio called home Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 14 11:01:15 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 14 11:01:15 dens-pi4 volumio[602]: info: Adding plugin bluetooth to MyMusic Plugins Aug 14 11:01:15 dens-pi4 volumio[602]: info: Adding plugin multiroom to MyMusic Plugins Aug 14 11:01:15 dens-pi4 volumio[602]: info: Adding plugin metavolumio to MyMusic Plugins Aug 14 11:01:15 dens-pi4 volumio[602]: info: Adding plugin cd_controller to MyMusic Plugins Aug 14 11:01:15 dens-pi4 volumio[602]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 14 11:01:16 dens-pi4 volumio[602]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 14 11:01:16 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 14 11:01:16 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 14 11:01:16 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 14 11:01:16 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:16 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:16 dens-pi4 volumio[602]: info: Starting MyVolumio Remote Streaming Endpoints Aug 14 11:01:16 dens-pi4 volumio[602]: info: MyVolumio login type: Token Aug 14 11:01:16 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 14 11:01:16 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 14 11:01:17 dens-pi4 volumio[602]: info: Starting Streaming Service Transparent Proxy Aug 14 11:01:17 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 14 11:01:17 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 14 11:01:17 dens-pi4 volumio[602]: info: Streaming services startup Aug 14 11:01:17 dens-pi4 volumio[602]: info: Starting Streaming Daemon Aug 14 11:01:17 dens-pi4 sudo[903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 14 11:01:17 dens-pi4 sudo[903]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:01:17 dens-pi4 sudo[903]: pam_unix(sudo:session): session closed for user root Aug 14 11:01:17 dens-pi4 volumio[602]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 14 11:01:17 dens-pi4 volumio[602]: error: Cannot start Volumio Streaming Daemon Aug 14 11:01:17 dens-pi4 volumio[602]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 14 11:01:17 dens-pi4 volumio[602]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 14 11:01:18 dens-pi4 volumio[602]: STREAMING PROXY: Starting server on port 3245 Aug 14 11:01:18 dens-pi4 volumio[602]: Node JS runtime: 14 Aug 14 11:01:18 dens-pi4 volumio[602]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 14 11:01:18 dens-pi4 volumio[602]: info: MYVOLUMIO: Adding device Aug 14 11:01:18 dens-pi4 volumio[602]: info: MYVOLUMIO: Evaluating Server Aug 14 11:01:19 dens-pi4 volumio[602]: info: MyVolumio status changed Aug 14 11:01:19 dens-pi4 volumio[602]: info: Streaming services startup Aug 14 11:01:19 dens-pi4 volumio[602]: info: Starting Streaming Daemon Aug 14 11:01:19 dens-pi4 volumio[602]: info: Removing browser output: myVolumio user plan is not superstar Aug 14 11:01:19 dens-pi4 volumio[602]: info: Removing audio output: Aug 14 11:01:19 dens-pi4 volumio[602]: info: Stoppping Tunnel 1 Aug 14 11:01:19 dens-pi4 sudo[926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 14 11:01:19 dens-pi4 sudo[926]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:01:19 dens-pi4 sudo[926]: pam_unix(sudo:session): session closed for user root Aug 14 11:01:19 dens-pi4 sudo[929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 14 11:01:19 dens-pi4 sudo[929]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:01:19 dens-pi4 volumio[602]: error: Cannot start Volumio Streaming Daemon Aug 14 11:01:19 dens-pi4 volumio[602]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 14 11:01:19 dens-pi4 volumio[602]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 14 11:01:19 dens-pi4 sudo[929]: pam_unix(sudo:session): session closed for user root Aug 14 11:01:19 dens-pi4 volumio[602]: info: Remote SSH Stopped Aug 14 11:01:19 dens-pi4 volumio[602]: info: Setting Geolocation for MyVolumio to au1 Aug 14 11:01:19 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:19 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:19 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:20 dens-pi4 volumio[602]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 14 11:01:20 dens-pi4 systemd-udevd[233]: Spawned process '/bin/bash -c '/usr/local/bin/volumio usbattach'' [406] is taking longer than 59s to complete Aug 14 11:01:20 dens-pi4 systemd-udevd[223]: pcmC5D0p: Worker [233] processing SEQNUM=1617 is taking a long time Aug 14 11:01:20 dens-pi4 volumio[602]: info: Updating MyVolumio device info Aug 14 11:01:20 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:20 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:20 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:21 dens-pi4 volumio[602]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 14 11:01:24 dens-pi4 volumio[602]: info: MYVOLUMIO: Adding device Aug 14 11:01:24 dens-pi4 volumio[602]: info: MYVOLUMIO: Evaluating Server Aug 14 11:01:25 dens-pi4 volumio[602]: info: Setting Geolocation for MyVolumio to au1 Aug 14 11:01:25 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:25 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:25 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:25 dens-pi4 volumio[602]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 14 11:01:26 dens-pi4 volumio[602]: info: Updating MyVolumio device info Aug 14 11:01:26 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:26 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:26 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:26 dens-pi4 volumio[602]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 14 11:01:31 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 14 11:01:31 dens-pi4 volumio[602]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Aug 14 11:01:31 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioGetState Aug 14 11:01:31 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:01:36 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:01:36 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:01:36 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 14 11:01:36 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 14 11:01:36 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 14 11:01:36 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioGetBrowseSources Aug 14 11:01:36 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 14 11:01:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 14 11:01:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 14 11:01:37 dens-pi4 volumio-remote-updater[470]: Test mode enabled Aug 14 11:01:37 dens-pi4 volumio-remote-updater[470]: No alpha test mode Aug 14 11:01:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 14 11:01:37 dens-pi4 volumio[602]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 14 11:01:37 dens-pi4 volumio[602]: info: Completed starting MyVolumio Plugin Aug 14 11:01:37 dens-pi4 volumio[602]: info: BOOT COMPLETED Aug 14 11:01:37 dens-pi4 volumio[602]: [Metrics] CommandRouter: 69s 544.71ms Aug 14 11:01:37 dens-pi4 volumio[602]: info: CoreCommandRouter::volumiosetStartupVolume Aug 14 11:01:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:01:37 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:01:37 dens-pi4 volumio[602]: info: CoreCommandRouter::Close All Modals sent Aug 14 11:01:37 dens-pi4 volumio[602]: info: CoreCommandRouter::Close All Modals sent Aug 14 11:01:37 dens-pi4 volumio[602]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Aug 14 11:01:37 dens-pi4 volumio[602]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Aug 14 11:01:37 dens-pi4 volumio[602]: aplay: main:828: audio open error: No such device Aug 14 11:01:38 dens-pi4 volumio[602]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 14 11:01:38 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 14 11:01:38 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 14 11:01:38 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 14 11:01:38 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Aug 14 11:01:38 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 14 11:01:47 dens-pi4 systemd[1]: dev-disk-by\x2duuid-BE78\x2d150E.device: Job dev-disk-by\x2duuid-BE78\x2d150E.device/start timed out. Aug 14 11:01:47 dens-pi4 systemd[1]: Timed out waiting for device /dev/disk/by-uuid/BE78-150E. Aug 14 11:01:47 dens-pi4 systemd[1]: Dependency failed for /media/argonssd. Aug 14 11:01:47 dens-pi4 systemd[1]: media-argonssd.mount: Job media-argonssd.mount/start failed with result 'dependency'. Aug 14 11:01:47 dens-pi4 systemd[1]: dev-disk-by\x2duuid-BE78\x2d150E.device: Job dev-disk-by\x2duuid-BE78\x2d150E.device/start failed with result 'timeout'. Aug 14 11:02:19 dens-pi4 systemd[1]: ifupdown-pre.service: Main process exited, code=exited, status=1/FAILURE Aug 14 11:02:19 dens-pi4 systemd[1]: ifupdown-pre.service: Failed with result 'exit-code'. Aug 14 11:02:19 dens-pi4 systemd[1]: Failed to start Helper to synchronize boot up for ifupdown. Aug 14 11:02:19 dens-pi4 systemd[1]: Dependency failed for Raise network interfaces. Aug 14 11:02:19 dens-pi4 systemd[1]: networking.service: Job networking.service/start failed with result 'dependency'. Aug 14 11:02:19 dens-pi4 systemd[1]: Reached target Network. Aug 14 11:02:19 dens-pi4 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Aug 14 11:02:19 dens-pi4 systemd[1]: Starting Permit User Sessions... Aug 14 11:02:19 dens-pi4 systemd[1]: Starting Music Player Daemon... Aug 14 11:02:19 dens-pi4 systemd[1]: Starting Network Time Service... Aug 14 11:02:19 dens-pi4 systemd[1]: Starting OpenBSD Secure Shell server... Aug 14 11:02:19 dens-pi4 systemd[1]: Reached target Network is Online. Aug 14 11:02:19 dens-pi4 systemd[1]: Starting /etc/rc.local Compatibility... Aug 14 11:02:19 dens-pi4 systemd[1]: Starting LSB: Brings up/down network automatically... Aug 14 11:02:19 dens-pi4 systemd[1]: Starting LSB: exim Mail Transport Agent... Aug 14 11:02:19 dens-pi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 14 11:02:19 dens-pi4 sudo[891]: pam_unix(sudo:session): session closed for user root Aug 14 11:02:19 dens-pi4 sudo[893]: pam_unix(sudo:session): session closed for user root Aug 14 11:02:19 dens-pi4 sudo[888]: pam_unix(sudo:session): session closed for user root Aug 14 11:02:19 dens-pi4 volumio[602]: info: Shairport-Sync Started Aug 14 11:02:19 dens-pi4 systemd[1]: Starting Samba NMB Daemon... Aug 14 11:02:19 dens-pi4 volumio[602]: Error adding Membership: Error: addMembership EINVAL Aug 14 11:02:19 dens-pi4 volumio[602]: info: Shairport-Sync Started Aug 14 11:02:19 dens-pi4 volumio[602]: info: Shairport-Sync Started Aug 14 11:02:19 dens-pi4 systemd[1]: Started Permit User Sessions. Aug 14 11:02:19 dens-pi4 systemd[1]: Started /etc/rc.local Compatibility. Aug 14 11:02:19 dens-pi4 systemd[1]: Starting Terminate Plymouth Boot Screen... Aug 14 11:02:19 dens-pi4 systemd[1]: Starting Hold until boot process finishes up... Aug 14 11:02:19 dens-pi4 systemd[1]: Received SIGRTMIN+21 from PID 239 (plymouthd). Aug 14 11:02:19 dens-pi4 systemd[1]: plymouth-quit-wait.service: Succeeded. Aug 14 11:02:19 dens-pi4 systemd[1]: Started Hold until boot process finishes up. Aug 14 11:02:19 dens-pi4 systemd[1]: plymouth-start.service: Succeeded. Aug 14 11:02:19 dens-pi4 systemd[1]: plymouth-quit.service: Succeeded. Aug 14 11:02:19 dens-pi4 systemd[1]: Started Terminate Plymouth Boot Screen. Aug 14 11:02:19 dens-pi4 systemd[1]: Received SIGRTMIN+21 from PID 239 (n/a). Aug 14 11:02:19 dens-pi4 systemd[1]: Started Getty on tty1. Aug 14 11:02:19 dens-pi4 systemd[1]: Reached target Login Prompts. Aug 14 11:02:19 dens-pi4 ntpd[957]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 14 11:02:19 dens-pi4 ntpd[957]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Aug 14 11:02:19 dens-pi4 systemd[1]: Started Network Time Service. Aug 14 11:02:19 dens-pi4 ifplugd(eth0)[993]: ifplugd 0.28 initializing. Aug 14 11:02:19 dens-pi4 ifplugd(eth0)[993]: Using interface eth0/DC:A6:32:0E:46:39 with driver (version: 5.10.92-v7l+) Aug 14 11:02:19 dens-pi4 ifplugd(eth0)[993]: Using detection mode: SIOCETHTOOL Aug 14 11:02:19 dens-pi4 ifplugd(eth0)[993]: Initialization complete, link beat detected. Aug 14 11:02:19 dens-pi4 ntpd[991]: proto: precision = 0.797 usec (-20) Aug 14 11:02:19 dens-pi4 sshd[980]: Server listening on 0.0.0.0 port 22. Aug 14 11:02:19 dens-pi4 systemd[1]: Started OpenBSD Secure Shell server. Aug 14 11:02:19 dens-pi4 sshd[980]: Server listening on :: port 22. Aug 14 11:02:19 dens-pi4 ntpd[991]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 14 11:02:19 dens-pi4 ntpd[991]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 14 11:02:19 dens-pi4 ntpd[991]: Listen and drop on 0 v6wildcard [::]:123 Aug 14 11:02:19 dens-pi4 ntpd[991]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 14 11:02:19 dens-pi4 ntpd[991]: Listen normally on 2 lo 127.0.0.1:123 Aug 14 11:02:19 dens-pi4 ntpd[991]: Listen normally on 3 eth0 192.168.1.82:123 Aug 14 11:02:19 dens-pi4 ntpd[991]: Listening on routing socket on fd #20 for interface updates Aug 14 11:02:19 dens-pi4 ntpd[991]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 14 11:02:19 dens-pi4 ntpd[991]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 14 11:02:19 dens-pi4 ifplugd(eth0)[993]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Aug 14 11:02:19 dens-pi4 ifplugd(eth0)[993]: client: ifup: interface eth0 already configured Aug 14 11:02:19 dens-pi4 ifplugd(eth0)[993]: Program executed successfully. Aug 14 11:02:19 dens-pi4 ifplugd[964]: Network Interface Plugging Daemon...start eth0...done. Aug 14 11:02:19 dens-pi4 systemd[1]: Started LSB: Brings up/down network automatically. Aug 14 11:02:20 dens-pi4 exim4[966]: Starting MTA: exim4. Aug 14 11:02:20 dens-pi4 systemd[1]: Started LSB: exim Mail Transport Agent. Aug 14 11:02:20 dens-pi4 exim[1243]: 2023-08-14 11:02:20 socket bind() to port 25 for address ::1 failed: Cannot assign requested address: waiting 30s before trying again (9 more tries) Aug 14 11:02:20 dens-pi4 exim[1243]: 2023-08-14 11:02:20 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=0 egid=124 Aug 14 11:02:20 dens-pi4 exim[1243]: exim: could not open panic log - aborting: see message(s) above Aug 14 11:02:20 dens-pi4 nmbd[973]: [2023/08/14 11:02:20.296345, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Aug 14 11:02:20 dens-pi4 nmbd[973]: started asyncdns process 1246 Aug 14 11:02:20 dens-pi4 nmbd[973]: [2023/08/14 11:02:20.315375, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 14 11:02:20 dens-pi4 nmbd[973]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Aug 14 11:02:20 dens-pi4 systemd[1]: Started Samba NMB Daemon. Aug 14 11:02:20 dens-pi4 systemd[1]: Starting Samba Winbind Daemon... Aug 14 11:02:20 dens-pi4 ntpd[991]: Soliciting pool server 202.68.92.244 Aug 14 11:02:20 dens-pi4 winbindd[1247]: [2023/08/14 11:02:20.617597, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Aug 14 11:02:20 dens-pi4 winbindd[1247]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 14 11:02:20 dens-pi4 winbindd[1247]: [2023/08/14 11:02:20.638846, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 14 11:02:20 dens-pi4 winbindd[1247]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Aug 14 11:02:20 dens-pi4 systemd[1]: Started Samba Winbind Daemon. Aug 14 11:02:20 dens-pi4 systemd[1]: Starting Samba SMB Daemon... Aug 14 11:02:21 dens-pi4 smbd[1252]: [2023/08/14 11:02:21.103189, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 14 11:02:21 dens-pi4 smbd[1252]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Aug 14 11:02:21 dens-pi4 systemd[1]: Started Samba SMB Daemon. Aug 14 11:02:21 dens-pi4 mpd[956]: Aug 14 11:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 14 11:02:21 dens-pi4 systemd[1]: Started Music Player Daemon. Aug 14 11:02:21 dens-pi4 sudo[869]: pam_unix(sudo:session): session closed for user root Aug 14 11:02:21 dens-pi4 volumio[602]: Upnp client error: Error: This socket has been ended by the other party Aug 14 11:02:21 dens-pi4 volumio[602]: Upnp client error: Error: This socket has been ended by the other party Aug 14 11:02:21 dens-pi4 volumio[602]: Upnp client error: Error: This socket has been ended by the other party Aug 14 11:02:21 dens-pi4 sudo[650]: pam_unix(sudo:session): session closed for user root Aug 14 11:02:21 dens-pi4 systemd[1]: Reached target Multi-User System. Aug 14 11:02:21 dens-pi4 systemd[1]: Reached target Graphical Interface. Aug 14 11:02:21 dens-pi4 systemd[1]: Started Argon One Fan and Button Service. Aug 14 11:02:21 dens-pi4 systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 14 11:02:21 dens-pi4 systemd[1]: Started Fan Shim Service. Aug 14 11:02:21 dens-pi4 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 14 11:02:21 dens-pi4 systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 14 11:02:21 dens-pi4 systemd[1]: Startup finished in 9.267s (kernel) + 2min 5.128s (userspace) = 2min 14.396s. Aug 14 11:02:21 dens-pi4 volumio[602]: error: MPD error: The expression evaluated to a falsy value: Aug 14 11:02:21 dens-pi4 volumio[602]: assert.ok(self.idling) Aug 14 11:02:21 dens-pi4 volumio[602]: error: The expression evaluated to a falsy value: Aug 14 11:02:21 dens-pi4 volumio[602]: assert.ok(self.idling) Aug 14 11:02:21 dens-pi4 volumio[602]: info: MPD running with PID956 Aug 14 11:02:21 dens-pi4 volumio[602]: ,establishing connection Aug 14 11:02:21 dens-pi4 volumio[602]: error: updateQueue error: null Aug 14 11:02:21 dens-pi4 volumio[602]: error: updateQueue error: null Aug 14 11:02:21 dens-pi4 ntpd[991]: Soliciting pool server 130.217.74.61 Aug 14 11:02:21 dens-pi4 ntpd[991]: Soliciting pool server 130.217.74.62 Aug 14 11:02:22 dens-pi4 ntpd[991]: Soliciting pool server 103.106.65.219 Aug 14 11:02:22 dens-pi4 ntpd[991]: Soliciting pool server 103.239.8.21 Aug 14 11:02:22 dens-pi4 ntpd[991]: Soliciting pool server 130.217.74.63 Aug 14 11:02:23 dens-pi4 ntpd[991]: Soliciting pool server 43.252.70.34 Aug 14 11:02:23 dens-pi4 ntpd[991]: Soliciting pool server 123.255.62.42 Aug 14 11:02:23 dens-pi4 ntpd[991]: Soliciting pool server 103.242.68.68 Aug 14 11:02:23 dens-pi4 ntpd[991]: Soliciting pool server 162.159.200.1 Aug 14 11:02:24 dens-pi4 ntpd[991]: Soliciting pool server 162.159.200.123 Aug 14 11:02:24 dens-pi4 ntpd[991]: Soliciting pool server 123.255.61.170 Aug 14 11:02:24 dens-pi4 ntpd[991]: Soliciting pool server 103.242.68.69 Aug 14 11:02:25 dens-pi4 ntpd[991]: Soliciting pool server 103.242.70.5 Aug 14 11:02:25 dens-pi4 ntpd[991]: Soliciting pool server 91.208.208.10 Aug 14 11:02:26 dens-pi4 ntpd[991]: Soliciting pool server 101.100.146.146 Aug 14 11:02:26 dens-pi4 ntpd[991]: Soliciting pool server 2404:1800:700:3300:7cd8:457b:b3d6:c978 Aug 14 11:02:26 dens-pi4 volumio[602]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 14 11:15:49 dens-pi4 ntpd[991]: receive: Unexpected origin timestamp 0xe883df84.8e17cbfa does not match aorg 0000000000.00000000 from server@130.217.74.61 xmt 0xe883e2a5.441b3ceb Aug 14 11:15:49 dens-pi4 ntpd[991]: receive: Unexpected origin timestamp 0xe883df84.8e0c063b does not match aorg 0000000000.00000000 from server@103.242.70.5 xmt 0xe883e2a5.44c9759a Aug 14 11:15:49 dens-pi4 ntpd[991]: receive: Unexpected origin timestamp 0xe883df84.8e0e2453 does not match aorg 0000000000.00000000 from server@123.255.61.170 xmt 0xe883e2a5.44c321b6 Aug 14 11:15:49 dens-pi4 ntpd[991]: receive: Unexpected origin timestamp 0xe883df84.8e1111e9 does not match aorg 0000000000.00000000 from server@123.255.62.42 xmt 0xe883e2a5.45e57a3c Aug 14 11:15:49 dens-pi4 ntpd[991]: receive: Unexpected origin timestamp 0xe883df84.8e0818f8 does not match aorg 0000000000.00000000 from server@91.208.208.10 xmt 0xe883e2a5.47cefa0b Aug 14 11:15:49 dens-pi4 ntpd[991]: receive: Unexpected origin timestamp 0xe883df84.8e1538c0 does not match aorg 0000000000.00000000 from server@103.106.65.219 xmt 0xe883e2a5.482fb5ab Aug 14 11:15:54 dens-pi4 systemd-udevd[233]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 28. Aug 14 11:16:19 dens-pi4 volumio[821]: Generating RSA private key, 4096 bit long modulus (2 primes) Aug 14 11:16:19 dens-pi4 volumio[821]: ......++++ Aug 14 11:16:25 dens-pi4 volumio[821]: ........................................................++++ Aug 14 11:16:25 dens-pi4 volumio[821]: e is 65537 (0x010001) Aug 14 11:16:25 dens-pi4 volumio[821]: writing RSA key Aug 14 11:17:01 dens-pi4 CRON[1313]: pam_unix(cron:session): session opened for user root by (uid=0) Aug 14 11:17:01 dens-pi4 CRON[1314]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) Aug 14 11:17:01 dens-pi4 CRON[1313]: pam_unix(cron:session): session closed for user root Aug 14 11:25:01 dens-pi4 CRON[1346]: pam_unix(cron:session): session opened for user root by (uid=0) Aug 14 11:25:01 dens-pi4 CRON[1347]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1) Aug 14 11:25:01 dens-pi4 CRON[1346]: pam_unix(cron:session): session closed for user root Aug 14 11:27:44 dens-pi4 ntpd[991]: 103.239.8.21 local addr 192.168.1.82 -> Aug 14 11:28:14 dens-pi4 ntpd[991]: 43.252.70.34 local addr 192.168.1.82 -> Aug 14 11:28:15 dens-pi4 ntpd[991]: 101.100.146.146 local addr 192.168.1.82 -> Aug 14 11:28:18 dens-pi4 ntpd[991]: 162.159.200.1 local addr 192.168.1.82 -> Aug 14 11:28:41 dens-pi4 sudo[1364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 14 11:28:41 dens-pi4 sudo[1364]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:28:41 dens-pi4 systemd[1]: Starting Cleanup of Temporary Directories... Aug 14 11:28:41 dens-pi4 sudo[1364]: pam_unix(sudo:session): session closed for user root Aug 14 11:28:41 dens-pi4 sudo[1368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 14 11:28:41 dens-pi4 sudo[1368]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:28:41 dens-pi4 sudo[1368]: pam_unix(sudo:session): session closed for user root Aug 14 11:28:41 dens-pi4 systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Aug 14 11:28:41 dens-pi4 systemd[1]: Started Cleanup of Temporary Directories. Aug 14 11:28:41 dens-pi4 volumio[602]: verbose: New Socket.io Connection to 192.168.1.82 from 192.168.1.140 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/116.0 Total Clients: 6 Aug 14 11:28:41 dens-pi4 sudo[1371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 14 11:28:41 dens-pi4 sudo[1371]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:28:41 dens-pi4 sudo[1371]: pam_unix(sudo:session): session closed for user root Aug 14 11:28:41 dens-pi4 sudo[1374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 14 11:28:41 dens-pi4 sudo[1374]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:28:41 dens-pi4 sudo[1374]: pam_unix(sudo:session): session closed for user root Aug 14 11:28:41 dens-pi4 volumio[602]: verbose: New Socket.io Connection to 192.168.1.82 from 192.168.1.140 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/116.0 Total Clients: 7 Aug 14 11:28:41 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioGetState Aug 14 11:28:41 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:28:41 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 14 11:28:41 dens-pi4 volumio[602]: info: Listing playlists Aug 14 11:28:41 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 14 11:28:41 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 14 11:28:41 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 14 11:28:41 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 14 11:28:43 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 14 11:28:43 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 14 11:28:45 dens-pi4 volumio[602]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 14 11:28:45 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 14 11:28:45 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:28:45 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 14 11:28:45 dens-pi4 volumio[602]: Invalid card number. Aug 14 11:28:45 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 11:28:45 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 11:28:45 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 11:28:45 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 11:28:45 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 11:28:45 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 11:28:45 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 11:28:45 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 14 11:28:45 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 14 11:28:45 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 14 11:28:47 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 14 11:28:47 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 14 11:28:50 dens-pi4 volumio[602]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 14 11:28:50 dens-pi4 volumio[602]: info: Device 5 does not have any Mixer Control Available Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 14 11:28:50 dens-pi4 volumio[602]: info: Updating Volume Controller Parameters: Device: 5 Name: E50 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 14 11:28:50 dens-pi4 volumio[602]: info: Disabling external Volume Control Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:28:50 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:28:50 dens-pi4 volumio[602]: info: Preparing to generate the ALSA configuration file Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 14 11:28:50 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 14 11:28:50 dens-pi4 volumio[602]: info: Asound.conf file written Aug 14 11:28:50 dens-pi4 sudo[1393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 14 11:28:50 dens-pi4 sudo[1393]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:28:50 dens-pi4 sudo[1393]: pam_unix(sudo:session): session closed for user root Aug 14 11:28:50 dens-pi4 volumio[602]: info: Output device has changed, restarting MPD Aug 14 11:28:51 dens-pi4 sudo[1398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 14 11:28:51 dens-pi4 sudo[1398]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:28:51 dens-pi4 sudo[1398]: pam_unix(sudo:session): session closed for user root Aug 14 11:28:51 dens-pi4 volumio[602]: info: Output device has changed, restarting Shairport Sync Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:28:51 dens-pi4 sudo[1401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 14 11:28:51 dens-pi4 sudo[1401]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:28:51 dens-pi4 systemd[1]: Stopping Music Player Daemon... Aug 14 11:28:51 dens-pi4 volumio[602]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 14 11:28:51 dens-pi4 volumio[602]: info: Output device has changed, restarting MPD Aug 14 11:28:51 dens-pi4 sudo[1407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 14 11:28:51 dens-pi4 sudo[1407]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:28:51 dens-pi4 volumio[602]: info: Output device has changed, restarting Shairport Sync Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:28:51 dens-pi4 sudo[1407]: pam_unix(sudo:session): session closed for user root Aug 14 11:28:51 dens-pi4 sudo[1410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 14 11:28:51 dens-pi4 sudo[1410]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:28:51 dens-pi4 systemd[1]: mpd.service: Succeeded. Aug 14 11:28:51 dens-pi4 systemd[1]: Stopped Music Player Daemon. Aug 14 11:28:51 dens-pi4 volumio[602]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 14 11:28:51 dens-pi4 systemd[1]: Starting Music Player Daemon... Aug 14 11:28:51 dens-pi4 volumio[602]: info: MPD Permissions set Aug 14 11:28:51 dens-pi4 volumio[602]: info: MPD Permissions set Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 14 11:28:51 dens-pi4 volumio[602]: info: Starting Shairport Sync Aug 14 11:28:51 dens-pi4 volumio[602]: info: Starting Shairport Sync Aug 14 11:28:51 dens-pi4 sudo[1425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 14 11:28:51 dens-pi4 sudo[1425]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:28:51 dens-pi4 sudo[1428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 14 11:28:51 dens-pi4 sudo[1428]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:28:51 dens-pi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 14 11:28:51 dens-pi4 systemd[1]: shairport-sync.service: Succeeded. Aug 14 11:28:51 dens-pi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 14 11:28:51 dens-pi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 14 11:28:51 dens-pi4 sudo[1425]: pam_unix(sudo:session): session closed for user root Aug 14 11:28:51 dens-pi4 volumio[602]: info: Shairport-Sync Started Aug 14 11:28:51 dens-pi4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 14 11:28:51 dens-pi4 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 14 11:28:51 dens-pi4 systemd[1]: shairport-sync.service: Succeeded. Aug 14 11:28:51 dens-pi4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 14 11:28:51 dens-pi4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 14 11:28:51 dens-pi4 sudo[1428]: pam_unix(sudo:session): session closed for user root Aug 14 11:28:51 dens-pi4 volumio[602]: info: Shairport-Sync Started Aug 14 11:28:51 dens-pi4 mpd[1415]: Aug 14 11:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 14 11:28:51 dens-pi4 systemd[1]: Started Music Player Daemon. Aug 14 11:28:51 dens-pi4 sudo[1401]: pam_unix(sudo:session): session closed for user root Aug 14 11:28:51 dens-pi4 sudo[1410]: pam_unix(sudo:session): session closed for user root Aug 14 11:28:51 dens-pi4 volumio[602]: error: MPD error: The expression evaluated to a falsy value: Aug 14 11:28:51 dens-pi4 volumio[602]: assert.ok(self.idling) Aug 14 11:28:51 dens-pi4 volumio[602]: error: The expression evaluated to a falsy value: Aug 14 11:28:51 dens-pi4 volumio[602]: assert.ok(self.idling) Aug 14 11:28:51 dens-pi4 volumio[602]: error: updateQueue error: null Aug 14 11:28:54 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 14 11:28:54 dens-pi4 volumio[602]: info: CoreStateMachine::getcurrentVolume Aug 14 11:28:54 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioRetrievevolume Aug 14 11:28:54 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:28:54 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:28:54 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:29:00 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 14 11:29:00 dens-pi4 volumio[602]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 14 11:29:00 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 14 11:29:00 dens-pi4 volumio[602]: info: Received Get System Version Aug 14 11:29:00 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 14 11:29:14 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioGetQueue Aug 14 11:29:14 dens-pi4 volumio[602]: info: CoreStateMachine::getQueue Aug 14 11:29:14 dens-pi4 volumio[602]: info: CorePlayQueue::getQueue Aug 14 11:29:14 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioGetVisibleSources Aug 14 11:29:14 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 14 11:29:17 dens-pi4 ntpd[991]: 103.106.65.219 local addr 192.168.1.82 -> Aug 14 11:29:22 dens-pi4 ntpd[991]: 123.255.62.42 local addr 192.168.1.82 -> Aug 14 11:29:24 dens-pi4 ntpd[991]: 162.159.200.123 local addr 192.168.1.82 -> Aug 14 11:29:28 dens-pi4 ntpd[991]: 202.68.92.244 local addr 192.168.1.82 -> Aug 14 11:29:35 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 14 11:29:35 dens-pi4 volumio[602]: info: CURURI: music-library Aug 14 11:29:35 dens-pi4 volumio[602]: info: Preload queue cleared Aug 14 11:29:38 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 14 11:29:38 dens-pi4 volumio[602]: info: CURURI: music-library/NAS Aug 14 11:29:38 dens-pi4 volumio[602]: info: Preload queue cleared Aug 14 11:29:38 dens-pi4 kernel: CIFS: VFS: \\192.168.1.20 Send error in SessSetup = -11 Aug 14 11:29:41 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 14 11:29:41 dens-pi4 volumio[602]: info: CURURI: music-library/NAS/Soundtrack Aug 14 11:29:41 dens-pi4 volumio[602]: info: Preload queue cleared Aug 14 11:29:43 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 14 11:29:43 dens-pi4 volumio[602]: info: CURURI: music-library/NAS/Soundtrack/Hans Zimmer Aug 14 11:29:43 dens-pi4 volumio[602]: info: Preload queue cleared Aug 14 11:29:45 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 14 11:29:45 dens-pi4 volumio[602]: info: CURURI: music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk Aug 14 11:29:45 dens-pi4 volumio[602]: info: Preload queue cleared Aug 14 11:29:45 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/01 Hans Zimmer - The Mole.flac Aug 14 11:29:45 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/02 Hans Zimmer - We Need Our Army Back.flac Aug 14 11:29:45 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/03 Hans Zimmer - Shivering Soldier.flac Aug 14 11:29:45 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/04 Hans Zimmer - Supermarine.flac Aug 14 11:29:45 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/05 Hans Zimmer - The Tide.flac Aug 14 11:29:45 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/06 Hans Zimmer - Regimental Brothers.flac Aug 14 11:29:45 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/07 Hans Zimmer - Impulse.flac Aug 14 11:29:45 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/08 Hans Zimmer - Home.flac Aug 14 11:29:45 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/09 Hans Zimmer - The Oil.flac Aug 14 11:29:45 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/10 Hans Zimmer - Variation 15 (Dunkirk).flac Aug 14 11:29:45 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/11 Hans Zimmer - End Titles.flac Aug 14 11:29:45 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/01 Hans Zimmer - The Mole.flac in service mpd Aug 14 11:29:45 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=Hans%20Zimmer/Dunkirk/extralarge&path=%2Fmnt%2FNAS%2FSoundtrack%2FHans%20Zimmer%2FDunkirk%2F01%20Hans%20Zimmer%20-%20The%20Mole.flac&metadata=false Aug 14 11:29:45 dens-pi4 volumio[602]: info: URI /mnt/NAS/Soundtrack/Hans Zimmer/Dunkirk/01 Hans Zimmer - The Mole.flac Aug 14 11:29:45 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/02 Hans Zimmer - We Need Our Army Back.flac in service mpd Aug 14 11:29:45 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=Hans%20Zimmer/Dunkirk/extralarge&path=%2Fmnt%2FNAS%2FSoundtrack%2FHans%20Zimmer%2FDunkirk%2F02%20Hans%20Zimmer%20-%20We%20Need%20Our%20Army%20Back.flac&metadata=false Aug 14 11:29:45 dens-pi4 volumio[602]: info: URI /mnt/NAS/Soundtrack/Hans Zimmer/Dunkirk/02 Hans Zimmer - We Need Our Army Back.flac Aug 14 11:29:46 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/03 Hans Zimmer - Shivering Soldier.flac in service mpd Aug 14 11:29:46 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=Hans%20Zimmer/Dunkirk/extralarge&path=%2Fmnt%2FNAS%2FSoundtrack%2FHans%20Zimmer%2FDunkirk%2F03%20Hans%20Zimmer%20-%20Shivering%20Soldier.flac&metadata=false Aug 14 11:29:46 dens-pi4 volumio[602]: info: URI /mnt/NAS/Soundtrack/Hans Zimmer/Dunkirk/03 Hans Zimmer - Shivering Soldier.flac Aug 14 11:29:46 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/04 Hans Zimmer - Supermarine.flac in service mpd Aug 14 11:29:46 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=Hans%20Zimmer/Dunkirk/extralarge&path=%2Fmnt%2FNAS%2FSoundtrack%2FHans%20Zimmer%2FDunkirk%2F04%20Hans%20Zimmer%20-%20Supermarine.flac&metadata=false Aug 14 11:29:46 dens-pi4 volumio[602]: info: URI /mnt/NAS/Soundtrack/Hans Zimmer/Dunkirk/04 Hans Zimmer - Supermarine.flac Aug 14 11:29:46 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/05 Hans Zimmer - The Tide.flac in service mpd Aug 14 11:29:46 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=Hans%20Zimmer/Dunkirk/extralarge&path=%2Fmnt%2FNAS%2FSoundtrack%2FHans%20Zimmer%2FDunkirk%2F05%20Hans%20Zimmer%20-%20The%20Tide.flac&metadata=false Aug 14 11:29:46 dens-pi4 volumio[602]: info: URI /mnt/NAS/Soundtrack/Hans Zimmer/Dunkirk/05 Hans Zimmer - The Tide.flac Aug 14 11:29:46 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/06 Hans Zimmer - Regimental Brothers.flac in service mpd Aug 14 11:29:46 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=Hans%20Zimmer/Dunkirk/extralarge&path=%2Fmnt%2FNAS%2FSoundtrack%2FHans%20Zimmer%2FDunkirk%2F06%20Hans%20Zimmer%20-%20Regimental%20Brothers.flac&metadata=false Aug 14 11:29:46 dens-pi4 volumio[602]: info: URI /mnt/NAS/Soundtrack/Hans Zimmer/Dunkirk/06 Hans Zimmer - Regimental Brothers.flac Aug 14 11:29:46 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/07 Hans Zimmer - Impulse.flac in service mpd Aug 14 11:29:46 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=Hans%20Zimmer/Dunkirk/extralarge&path=%2Fmnt%2FNAS%2FSoundtrack%2FHans%20Zimmer%2FDunkirk%2F07%20Hans%20Zimmer%20-%20Impulse.flac&metadata=false Aug 14 11:29:46 dens-pi4 volumio[602]: info: URI /mnt/NAS/Soundtrack/Hans Zimmer/Dunkirk/07 Hans Zimmer - Impulse.flac Aug 14 11:29:46 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/08 Hans Zimmer - Home.flac in service mpd Aug 14 11:29:46 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=Hans%20Zimmer/Dunkirk/extralarge&path=%2Fmnt%2FNAS%2FSoundtrack%2FHans%20Zimmer%2FDunkirk%2F08%20Hans%20Zimmer%20-%20Home.flac&metadata=false Aug 14 11:29:46 dens-pi4 volumio[602]: info: URI /mnt/NAS/Soundtrack/Hans Zimmer/Dunkirk/08 Hans Zimmer - Home.flac Aug 14 11:29:46 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/09 Hans Zimmer - The Oil.flac in service mpd Aug 14 11:29:46 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=Hans%20Zimmer/Dunkirk/extralarge&path=%2Fmnt%2FNAS%2FSoundtrack%2FHans%20Zimmer%2FDunkirk%2F09%20Hans%20Zimmer%20-%20The%20Oil.flac&metadata=false Aug 14 11:29:46 dens-pi4 volumio[602]: info: URI /mnt/NAS/Soundtrack/Hans Zimmer/Dunkirk/09 Hans Zimmer - The Oil.flac Aug 14 11:29:46 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/10 Hans Zimmer - Variation 15 (Dunkirk).flac in service mpd Aug 14 11:29:46 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=Hans%20Zimmer/Dunkirk/extralarge&path=%2Fmnt%2FNAS%2FSoundtrack%2FHans%20Zimmer%2FDunkirk%2F10%20Hans%20Zimmer%20-%20Variation%2015%20(Dunkirk).flac&metadata=false Aug 14 11:29:46 dens-pi4 volumio[602]: info: URI /mnt/NAS/Soundtrack/Hans Zimmer/Dunkirk/10 Hans Zimmer - Variation 15 (Dunkirk).flac Aug 14 11:29:46 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/11 Hans Zimmer - End Titles.flac in service mpd Aug 14 11:29:46 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=Hans%20Zimmer/Dunkirk/extralarge&path=%2Fmnt%2FNAS%2FSoundtrack%2FHans%20Zimmer%2FDunkirk%2F11%20Hans%20Zimmer%20-%20End%20Titles.flac&metadata=false Aug 14 11:29:46 dens-pi4 volumio[602]: info: URI /mnt/NAS/Soundtrack/Hans Zimmer/Dunkirk/11 Hans Zimmer - End Titles.flac Aug 14 11:29:50 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioAddQueueItems Aug 14 11:29:50 dens-pi4 volumio[602]: info: CoreStateMachine::addQueueItems Aug 14 11:29:50 dens-pi4 volumio[602]: info: CorePlayQueue::addQueueItems Aug 14 11:29:50 dens-pi4 volumio[602]: info: Preload queue cleared Aug 14 11:29:50 dens-pi4 volumio[602]: info: Adding Item to queue: music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/01 Hans Zimmer - The Mole.flac Aug 14 11:29:50 dens-pi4 volumio[602]: info: Using cached record of: music-library/NAS/Soundtrack/Hans Zimmer/Dunkirk/01 Hans Zimmer - The Mole.flac Aug 14 11:29:50 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushQueue Aug 14 11:29:50 dens-pi4 volumio[602]: info: CorePlayQueue::saveQueue Aug 14 11:29:50 dens-pi4 volumio[602]: info: CoreStateMachine::updateTrackBlock Aug 14 11:29:50 dens-pi4 volumio[602]: info: CorePlayQueue::getTrackBlock Aug 14 11:30:03 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 14 11:30:03 dens-pi4 volumio[602]: info: CURURI: music-library/NAS/Review Aug 14 11:30:03 dens-pi4 volumio[602]: info: Preload queue cleared Aug 14 11:30:05 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 14 11:30:05 dens-pi4 volumio[602]: info: CURURI: music-library/NAS/Review/America Aug 14 11:30:05 dens-pi4 volumio[602]: info: Preload queue cleared Aug 14 11:30:07 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 14 11:30:07 dens-pi4 volumio[602]: info: CURURI: music-library/NAS/Review/America/America Aug 14 11:30:07 dens-pi4 volumio[602]: info: Preload queue cleared Aug 14 11:30:07 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Review/America/America/01 - America - Riverside.dsf Aug 14 11:30:07 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Review/America/America/02 - America - Sandman.dsf Aug 14 11:30:07 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Review/America/America/03 - America - Three Roses.dsf Aug 14 11:30:07 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Review/America/America/04 - America - Children.dsf Aug 14 11:30:07 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Review/America/America/05 - America - A Horse With No Name.dsf Aug 14 11:30:07 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Review/America/America/06 - America - Here.dsf Aug 14 11:30:07 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Review/America/America/07 - America - I Need You.dsf Aug 14 11:30:07 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Review/America/America/08 - America - Rainy Day.dsf Aug 14 11:30:08 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Review/America/America/09 - America - Never Found The Time.dsf Aug 14 11:30:08 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Review/America/America/10 - America - Clarice.dsf Aug 14 11:30:08 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Review/America/America/11 - America - Donkey Jaw.dsf Aug 14 11:30:08 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Review/America/America/12 - America - Pigeon Song.dsf Aug 14 11:30:08 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Review/America/America/01 - America - Riverside.dsf in service mpd Aug 14 11:30:08 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=America/America/extralarge&path=%2Fmnt%2FNAS%2FReview%2FAmerica%2FAmerica%2F01%20-%20America%20-%20Riverside.dsf&metadata=false Aug 14 11:30:08 dens-pi4 volumio[602]: info: URI /mnt/NAS/Review/America/America/01 - America - Riverside.dsf Aug 14 11:30:08 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Review/America/America/02 - America - Sandman.dsf in service mpd Aug 14 11:30:08 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=America/America/extralarge&path=%2Fmnt%2FNAS%2FReview%2FAmerica%2FAmerica%2F02%20-%20America%20-%20Sandman.dsf&metadata=false Aug 14 11:30:08 dens-pi4 volumio[602]: info: URI /mnt/NAS/Review/America/America/02 - America - Sandman.dsf Aug 14 11:30:08 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Review/America/America/03 - America - Three Roses.dsf in service mpd Aug 14 11:30:08 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=America/America/extralarge&path=%2Fmnt%2FNAS%2FReview%2FAmerica%2FAmerica%2F03%20-%20America%20-%20Three%20Roses.dsf&metadata=false Aug 14 11:30:08 dens-pi4 volumio[602]: info: URI /mnt/NAS/Review/America/America/03 - America - Three Roses.dsf Aug 14 11:30:08 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Review/America/America/04 - America - Children.dsf in service mpd Aug 14 11:30:08 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=America/America/extralarge&path=%2Fmnt%2FNAS%2FReview%2FAmerica%2FAmerica%2F04%20-%20America%20-%20Children.dsf&metadata=false Aug 14 11:30:08 dens-pi4 volumio[602]: info: URI /mnt/NAS/Review/America/America/04 - America - Children.dsf Aug 14 11:30:08 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Review/America/America/05 - America - A Horse With No Name.dsf in service mpd Aug 14 11:30:08 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=America/America/extralarge&path=%2Fmnt%2FNAS%2FReview%2FAmerica%2FAmerica%2F05%20-%20America%20-%20A%20Horse%20With%20No%20Name.dsf&metadata=false Aug 14 11:30:08 dens-pi4 volumio[602]: info: URI /mnt/NAS/Review/America/America/05 - America - A Horse With No Name.dsf Aug 14 11:30:08 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Review/America/America/06 - America - Here.dsf in service mpd Aug 14 11:30:08 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=America/America/extralarge&path=%2Fmnt%2FNAS%2FReview%2FAmerica%2FAmerica%2F06%20-%20America%20-%20Here.dsf&metadata=false Aug 14 11:30:08 dens-pi4 volumio[602]: info: URI /mnt/NAS/Review/America/America/06 - America - Here.dsf Aug 14 11:30:08 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Review/America/America/07 - America - I Need You.dsf in service mpd Aug 14 11:30:08 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=America/America/extralarge&path=%2Fmnt%2FNAS%2FReview%2FAmerica%2FAmerica%2F07%20-%20America%20-%20I%20Need%20You.dsf&metadata=false Aug 14 11:30:08 dens-pi4 volumio[602]: info: URI /mnt/NAS/Review/America/America/07 - America - I Need You.dsf Aug 14 11:30:08 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Review/America/America/08 - America - Rainy Day.dsf in service mpd Aug 14 11:30:08 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=America/America/extralarge&path=%2Fmnt%2FNAS%2FReview%2FAmerica%2FAmerica%2F08%20-%20America%20-%20Rainy%20Day.dsf&metadata=false Aug 14 11:30:08 dens-pi4 volumio[602]: info: URI /mnt/NAS/Review/America/America/08 - America - Rainy Day.dsf Aug 14 11:30:08 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Review/America/America/09 - America - Never Found The Time.dsf in service mpd Aug 14 11:30:08 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=America/America/extralarge&path=%2Fmnt%2FNAS%2FReview%2FAmerica%2FAmerica%2F09%20-%20America%20-%20Never%20Found%20The%20Time.dsf&metadata=false Aug 14 11:30:08 dens-pi4 volumio[602]: info: URI /mnt/NAS/Review/America/America/09 - America - Never Found The Time.dsf Aug 14 11:30:08 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Review/America/America/10 - America - Clarice.dsf in service mpd Aug 14 11:30:08 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=America/America/extralarge&path=%2Fmnt%2FNAS%2FReview%2FAmerica%2FAmerica%2F10%20-%20America%20-%20Clarice.dsf&metadata=false Aug 14 11:30:08 dens-pi4 volumio[602]: info: URI /mnt/NAS/Review/America/America/10 - America - Clarice.dsf Aug 14 11:30:08 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Review/America/America/11 - America - Donkey Jaw.dsf in service mpd Aug 14 11:30:08 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=America/America/extralarge&path=%2Fmnt%2FNAS%2FReview%2FAmerica%2FAmerica%2F11%20-%20America%20-%20Donkey%20Jaw.dsf&metadata=false Aug 14 11:30:08 dens-pi4 volumio[602]: info: URI /mnt/NAS/Review/America/America/11 - America - Donkey Jaw.dsf Aug 14 11:30:08 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Review/America/America/12 - America - Pigeon Song.dsf in service mpd Aug 14 11:30:08 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=America/America/extralarge&path=%2Fmnt%2FNAS%2FReview%2FAmerica%2FAmerica%2F12%20-%20America%20-%20Pigeon%20Song.dsf&metadata=false Aug 14 11:30:08 dens-pi4 volumio[602]: info: URI /mnt/NAS/Review/America/America/12 - America - Pigeon Song.dsf Aug 14 11:30:12 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioAddQueueItems Aug 14 11:30:12 dens-pi4 volumio[602]: info: CoreStateMachine::addQueueItems Aug 14 11:30:12 dens-pi4 volumio[602]: info: CorePlayQueue::addQueueItems Aug 14 11:30:12 dens-pi4 volumio[602]: info: Preload queue cleared Aug 14 11:30:12 dens-pi4 volumio[602]: info: Adding Item to queue: music-library/NAS/Review/America/America/01 - America - Riverside.dsf Aug 14 11:30:12 dens-pi4 volumio[602]: info: Using cached record of: music-library/NAS/Review/America/America/01 - America - Riverside.dsf Aug 14 11:30:12 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushQueue Aug 14 11:30:12 dens-pi4 volumio[602]: info: CorePlayQueue::saveQueue Aug 14 11:30:12 dens-pi4 volumio[602]: info: CoreStateMachine::updateTrackBlock Aug 14 11:30:12 dens-pi4 volumio[602]: info: CorePlayQueue::getTrackBlock Aug 14 11:30:38 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 14 11:30:38 dens-pi4 volumio[602]: info: CURURI: music-library/NAS/Stereo Aug 14 11:30:38 dens-pi4 volumio[602]: info: Preload queue cleared Aug 14 11:30:40 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 14 11:30:40 dens-pi4 volumio[602]: info: CURURI: music-library/NAS/Stereo/10cc Aug 14 11:30:40 dens-pi4 volumio[602]: info: Preload queue cleared Aug 14 11:30:41 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 14 11:30:41 dens-pi4 volumio[602]: info: CURURI: music-library/NAS/Stereo/10cc/Deceptive Bends Aug 14 11:30:41 dens-pi4 volumio[602]: info: Preload queue cleared Aug 14 11:30:41 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Stereo/10cc/Deceptive Bends/01. Good Morning Judge.flac Aug 14 11:30:41 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Stereo/10cc/Deceptive Bends/02. The Things We Do For Love.flac Aug 14 11:30:41 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Stereo/10cc/Deceptive Bends/03. Marriage Bureau Rendezvous.flac Aug 14 11:30:41 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Stereo/10cc/Deceptive Bends/04. People In Love.flac Aug 14 11:30:41 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Stereo/10cc/Deceptive Bends/05. Modern Man Blues.flac Aug 14 11:30:41 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Stereo/10cc/Deceptive Bends/06. Honeymoon With B Troop.flac Aug 14 11:30:41 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Stereo/10cc/Deceptive Bends/07. I Bought A Flat Guitar Tutor.flac Aug 14 11:30:41 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Stereo/10cc/Deceptive Bends/08. You've Got A Cold.flac Aug 14 11:30:41 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Stereo/10cc/Deceptive Bends/09. Feel The Benefit- Reminisce And Speculate - ''A'' Latin Break - Feel The Benefit.flac Aug 14 11:30:41 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Stereo/10cc/Deceptive Bends/10. Hot To Trot.flac Aug 14 11:30:41 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Stereo/10cc/Deceptive Bends/11. Don't Squeeze Me Like Toothpaste.flac Aug 14 11:30:41 dens-pi4 volumio[602]: info: Preloading song: music-library/NAS/Stereo/10cc/Deceptive Bends/12. I'm So Laid Back, I'm Laid Out.flac Aug 14 11:30:41 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Stereo/10cc/Deceptive Bends/01. Good Morning Judge.flac in service mpd Aug 14 11:30:41 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=10cc/Deceptive%20Bends/extralarge&path=%2Fmnt%2FNAS%2FStereo%2F10cc%2FDeceptive%20Bends%2F01.%20Good%20Morning%20Judge.flac&metadata=false Aug 14 11:30:41 dens-pi4 volumio[602]: info: URI /mnt/NAS/Stereo/10cc/Deceptive Bends/01. Good Morning Judge.flac Aug 14 11:30:41 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Stereo/10cc/Deceptive Bends/02. The Things We Do For Love.flac in service mpd Aug 14 11:30:41 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=10cc/Deceptive%20Bends/extralarge&path=%2Fmnt%2FNAS%2FStereo%2F10cc%2FDeceptive%20Bends%2F02.%20The%20Things%20We%20Do%20For%20Love.flac&metadata=false Aug 14 11:30:41 dens-pi4 volumio[602]: info: URI /mnt/NAS/Stereo/10cc/Deceptive Bends/02. The Things We Do For Love.flac Aug 14 11:30:41 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Stereo/10cc/Deceptive Bends/03. Marriage Bureau Rendezvous.flac in service mpd Aug 14 11:30:41 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=10cc/Deceptive%20Bends/extralarge&path=%2Fmnt%2FNAS%2FStereo%2F10cc%2FDeceptive%20Bends%2F03.%20Marriage%20Bureau%20Rendezvous.flac&metadata=false Aug 14 11:30:41 dens-pi4 volumio[602]: info: URI /mnt/NAS/Stereo/10cc/Deceptive Bends/03. Marriage Bureau Rendezvous.flac Aug 14 11:30:42 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Stereo/10cc/Deceptive Bends/04. People In Love.flac in service mpd Aug 14 11:30:42 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=10cc/Deceptive%20Bends/extralarge&path=%2Fmnt%2FNAS%2FStereo%2F10cc%2FDeceptive%20Bends%2F04.%20People%20In%20Love.flac&metadata=false Aug 14 11:30:42 dens-pi4 volumio[602]: info: URI /mnt/NAS/Stereo/10cc/Deceptive Bends/04. People In Love.flac Aug 14 11:30:42 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Stereo/10cc/Deceptive Bends/05. Modern Man Blues.flac in service mpd Aug 14 11:30:42 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=10cc/Deceptive%20Bends/extralarge&path=%2Fmnt%2FNAS%2FStereo%2F10cc%2FDeceptive%20Bends%2F05.%20Modern%20Man%20Blues.flac&metadata=false Aug 14 11:30:42 dens-pi4 volumio[602]: info: URI /mnt/NAS/Stereo/10cc/Deceptive Bends/05. Modern Man Blues.flac Aug 14 11:30:42 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Stereo/10cc/Deceptive Bends/06. Honeymoon With B Troop.flac in service mpd Aug 14 11:30:42 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=10cc/Deceptive%20Bends/extralarge&path=%2Fmnt%2FNAS%2FStereo%2F10cc%2FDeceptive%20Bends%2F06.%20Honeymoon%20With%20B%20Troop.flac&metadata=false Aug 14 11:30:42 dens-pi4 volumio[602]: info: URI /mnt/NAS/Stereo/10cc/Deceptive Bends/06. Honeymoon With B Troop.flac Aug 14 11:30:42 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Stereo/10cc/Deceptive Bends/07. I Bought A Flat Guitar Tutor.flac in service mpd Aug 14 11:30:42 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=10cc/Deceptive%20Bends/extralarge&path=%2Fmnt%2FNAS%2FStereo%2F10cc%2FDeceptive%20Bends%2F07.%20I%20Bought%20A%20Flat%20Guitar%20Tutor.flac&metadata=false Aug 14 11:30:42 dens-pi4 volumio[602]: info: URI /mnt/NAS/Stereo/10cc/Deceptive Bends/07. I Bought A Flat Guitar Tutor.flac Aug 14 11:30:42 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Stereo/10cc/Deceptive Bends/08. You've Got A Cold.flac in service mpd Aug 14 11:30:42 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=10cc/Deceptive%20Bends/extralarge&path=%2Fmnt%2FNAS%2FStereo%2F10cc%2FDeceptive%20Bends%2F08.%20You've%20Got%20A%20Cold.flac&metadata=false Aug 14 11:30:42 dens-pi4 volumio[602]: info: URI /mnt/NAS/Stereo/10cc/Deceptive Bends/08. You've Got A Cold.flac Aug 14 11:30:42 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Stereo/10cc/Deceptive Bends/09. Feel The Benefit- Reminisce And Speculate - ''A'' Latin Break - Feel The Benefit.flac in service mpd Aug 14 11:30:42 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=10cc/Deceptive%20Bends/extralarge&path=%2Fmnt%2FNAS%2FStereo%2F10cc%2FDeceptive%20Bends%2F09.%20Feel%20The%20Benefit-%20Reminisce%20And%20Speculate%20-%20''A''%20Latin%20Break%20-%20Feel%20The%20Benefit.flac&metadata=false Aug 14 11:30:42 dens-pi4 volumio[602]: info: URI /mnt/NAS/Stereo/10cc/Deceptive Bends/09. Feel The Benefit- Reminisce And Speculate - ''A'' Latin Break - Feel The Benefit.flac Aug 14 11:30:42 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Stereo/10cc/Deceptive Bends/10. Hot To Trot.flac in service mpd Aug 14 11:30:42 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=10cc/Deceptive%20Bends/extralarge&path=%2Fmnt%2FNAS%2FStereo%2F10cc%2FDeceptive%20Bends%2F10.%20Hot%20To%20Trot.flac&metadata=false Aug 14 11:30:42 dens-pi4 volumio[602]: info: URI /mnt/NAS/Stereo/10cc/Deceptive Bends/10. Hot To Trot.flac Aug 14 11:30:42 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Stereo/10cc/Deceptive Bends/11. Don't Squeeze Me Like Toothpaste.flac in service mpd Aug 14 11:30:42 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=10cc/Deceptive%20Bends/extralarge&path=%2Fmnt%2FNAS%2FStereo%2F10cc%2FDeceptive%20Bends%2F11.%20Don't%20Squeeze%20Me%20Like%20Toothpaste.flac&metadata=false Aug 14 11:30:42 dens-pi4 volumio[602]: info: URI /mnt/NAS/Stereo/10cc/Deceptive Bends/11. Don't Squeeze Me Like Toothpaste.flac Aug 14 11:30:42 dens-pi4 volumio[602]: info: Exploding uri music-library/NAS/Stereo/10cc/Deceptive Bends/12. I'm So Laid Back, I'm Laid Out.flac in service mpd Aug 14 11:30:42 dens-pi4 volumio[602]: info: ALBUMART /albumart?cacheid=485&web=10cc/Deceptive%20Bends/extralarge&path=%2Fmnt%2FNAS%2FStereo%2F10cc%2FDeceptive%20Bends%2F12.%20I'm%20So%20Laid%20Back%2C%20I'm%20Laid%20Out.flac&metadata=false Aug 14 11:30:42 dens-pi4 volumio[602]: info: URI /mnt/NAS/Stereo/10cc/Deceptive Bends/12. I'm So Laid Back, I'm Laid Out.flac Aug 14 11:30:46 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioAddQueueItems Aug 14 11:30:46 dens-pi4 volumio[602]: info: CoreStateMachine::addQueueItems Aug 14 11:30:46 dens-pi4 volumio[602]: info: CorePlayQueue::addQueueItems Aug 14 11:30:46 dens-pi4 volumio[602]: info: Preload queue cleared Aug 14 11:30:46 dens-pi4 volumio[602]: info: Adding Item to queue: music-library/NAS/Stereo/10cc/Deceptive Bends/01. Good Morning Judge.flac Aug 14 11:30:46 dens-pi4 volumio[602]: info: Using cached record of: music-library/NAS/Stereo/10cc/Deceptive Bends/01. Good Morning Judge.flac Aug 14 11:30:46 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushQueue Aug 14 11:30:46 dens-pi4 volumio[602]: info: CorePlayQueue::saveQueue Aug 14 11:30:46 dens-pi4 volumio[602]: info: CoreStateMachine::updateTrackBlock Aug 14 11:30:46 dens-pi4 volumio[602]: info: CorePlayQueue::getTrackBlock Aug 14 11:31:00 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPlay Aug 14 11:31:00 dens-pi4 volumio[602]: info: CoreStateMachine::play index undefined Aug 14 11:31:00 dens-pi4 volumio[602]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 14 11:31:00 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:31:00 dens-pi4 volumio[602]: info: CoreStateMachine::startPlaybackTimer Aug 14 11:31:00 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:31:00 dens-pi4 volumio[602]: verbose: ControllerMpd::clearAddPlayTracks NAS/Soundtrack/Hans Zimmer/Dunkirk/01 Hans Zimmer - The Mole.flac Aug 14 11:31:00 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand stop Aug 14 11:31:00 dens-pi4 volumio[602]: info: sendMpdCommand stop took 1 milliseconds Aug 14 11:31:00 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand clear Aug 14 11:31:00 dens-pi4 volumio[602]: info: Aug 14 11:31:00 dens-pi4 volumio[602]: ---------------------------- MPD announces system playlist update Aug 14 11:31:00 dens-pi4 volumio[602]: info: Ignoring MPD Status Update Aug 14 11:31:00 dens-pi4 volumio[602]: info: sendMpdCommand clear took 1 milliseconds Aug 14 11:31:00 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand add "NAS/Soundtrack/Hans Zimmer/Dunkirk/01 Hans Zimmer - The Mole.flac" Aug 14 11:31:00 dens-pi4 volumio[602]: info: Aug 14 11:31:00 dens-pi4 volumio[602]: ---------------------------- MPD announces system playlist update Aug 14 11:31:00 dens-pi4 volumio[602]: info: Ignoring MPD Status Update Aug 14 11:31:00 dens-pi4 volumio[602]: error: updateQueue error: null Aug 14 11:31:00 dens-pi4 volumio[602]: info: Aug 14 11:31:00 dens-pi4 volumio[602]: ---------------------------- MPD announces system playlist update Aug 14 11:31:00 dens-pi4 volumio[602]: info: Ignoring MPD Status Update Aug 14 11:31:00 dens-pi4 volumio[602]: info: ------------------------------ 5ms Aug 14 11:31:00 dens-pi4 volumio[602]: info: sendMpdCommand add "NAS/Soundtrack/Hans Zimmer/Dunkirk/01 Hans Zimmer - The Mole.flac" took 3 milliseconds Aug 14 11:31:00 dens-pi4 volumio[602]: info: ------------------------------ 3ms Aug 14 11:31:00 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand play Aug 14 11:31:00 dens-pi4 volumio[602]: info: Aug 14 11:31:00 dens-pi4 volumio[602]: ---------------------------- MPD announces system playlist update Aug 14 11:31:00 dens-pi4 volumio[602]: info: Ignoring MPD Status Update Aug 14 11:31:00 dens-pi4 volumio[602]: info: ------------------------------ 4ms Aug 14 11:31:00 dens-pi4 volumio[602]: info: sendMpdCommand play took 3 milliseconds Aug 14 11:31:00 dens-pi4 volumio[602]: info: ------------------------------ 2ms Aug 14 11:31:00 dens-pi4 volumio[602]: info: Aug 14 11:31:00 dens-pi4 volumio[602]: ---------------------------- MPD announces state update: player Aug 14 11:31:00 dens-pi4 volumio[602]: info: ControllerMpd::getState Aug 14 11:31:00 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand status Aug 14 11:31:00 dens-pi4 volumio[602]: info: Aug 14 11:31:00 dens-pi4 volumio[602]: ---------------------------- MPD announces state update: player Aug 14 11:31:00 dens-pi4 volumio[602]: info: ControllerMpd::getState Aug 14 11:31:00 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand status Aug 14 11:31:01 dens-pi4 volumio[602]: info: Aug 14 11:31:01 dens-pi4 volumio[602]: ---------------------------- MPD announces state update: player Aug 14 11:31:01 dens-pi4 volumio[602]: info: sendMpdCommand status took 256 milliseconds Aug 14 11:31:01 dens-pi4 volumio[602]: info: ControllerMpd::getState Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand status Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: ControllerMpd::parseState Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 11:31:01 dens-pi4 volumio[602]: info: sendMpdCommand status took 257 milliseconds Aug 14 11:31:01 dens-pi4 volumio[602]: info: sendMpdCommand status took 2 milliseconds Aug 14 11:31:01 dens-pi4 volumio[602]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: ControllerMpd::parseState Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: ControllerMpd::parseState Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: ControllerMpd::parseTrackInfo Aug 14 11:31:01 dens-pi4 volumio[602]: info: ControllerMpd::pushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreCommandRouter::servicePushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"376 Kbps","isStreaming":false,"title":"The Mole","artist":"Hans Zimmer","album":"Dunkirk","uri":"NAS/Soundtrack/Hans Zimmer/Dunkirk/01 Hans Zimmer - The Mole.flac","trackType":"flac"} Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: CURRENT POSITION 0 Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreStateMachine::syncState stateService play Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreStateMachine::syncState currentStatus stop Aug 14 11:31:01 dens-pi4 volumio[602]: info: ------------------------------ 265ms Aug 14 11:31:01 dens-pi4 volumio[602]: info: Aug 14 11:31:01 dens-pi4 volumio[602]: ---------------------------- MPD announces state update: player Aug 14 11:31:01 dens-pi4 volumio[602]: info: ControllerMpd::getState Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand status Aug 14 11:31:01 dens-pi4 volumio[602]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 14 11:31:01 dens-pi4 volumio[602]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 14 11:31:01 dens-pi4 volumio[602]: info: sendMpdCommand status took 2 milliseconds Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: ControllerMpd::parseTrackInfo Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: ControllerMpd::parseTrackInfo Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: ControllerMpd::parseState Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 11:31:01 dens-pi4 volumio[602]: info: ControllerMpd::pushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreCommandRouter::servicePushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":458,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"373 Kbps","isStreaming":false,"title":"The Mole","artist":"Hans Zimmer","album":"Dunkirk","uri":"NAS/Soundtrack/Hans Zimmer/Dunkirk/01 Hans Zimmer - The Mole.flac","trackType":"flac"} Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: CURRENT POSITION 0 Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreStateMachine::syncState stateService play Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreStateMachine::syncState currentStatus play Aug 14 11:31:01 dens-pi4 volumio[602]: info: Received an update from plugin. extracting info from payload Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: ControllerMpd::pushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreCommandRouter::servicePushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"379 Kbps","isStreaming":false,"title":"The Mole","artist":"Hans Zimmer","album":"Dunkirk","uri":"NAS/Soundtrack/Hans Zimmer/Dunkirk/01 Hans Zimmer - The Mole.flac","trackType":"flac"} Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: CURRENT POSITION 0 Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreStateMachine::syncState stateService play Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreStateMachine::syncState currentStatus play Aug 14 11:31:01 dens-pi4 volumio[602]: info: Received an update from plugin. extracting info from payload Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: ------------------------------ 294ms Aug 14 11:31:01 dens-pi4 volumio[602]: info: ------------------------------ 41ms Aug 14 11:31:01 dens-pi4 volumio[602]: info: sendMpdCommand playlistinfo took 28 milliseconds Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: ControllerMpd::parseTrackInfo Aug 14 11:31:01 dens-pi4 volumio[602]: info: ControllerMpd::pushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreCommandRouter::servicePushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"379 Kbps","isStreaming":false,"title":"The Mole","artist":"Hans Zimmer","album":"Dunkirk","uri":"NAS/Soundtrack/Hans Zimmer/Dunkirk/01 Hans Zimmer - The Mole.flac","trackType":"flac"} Aug 14 11:31:01 dens-pi4 volumio[602]: verbose: CURRENT POSITION 0 Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreStateMachine::syncState stateService play Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreStateMachine::syncState currentStatus play Aug 14 11:31:01 dens-pi4 volumio[602]: info: Received an update from plugin. extracting info from payload Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:31:01 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:31:01 dens-pi4 volumio[602]: info: ------------------------------ 44ms Aug 14 11:31:34 dens-pi4 ntpd[991]: 123.255.61.170 local addr 192.168.1.82 -> Aug 14 11:35:01 dens-pi4 CRON[1465]: pam_unix(cron:session): session opened for user root by (uid=0) Aug 14 11:35:01 dens-pi4 CRON[1466]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1) Aug 14 11:35:01 dens-pi4 CRON[1465]: pam_unix(cron:session): session closed for user root Aug 14 11:35:56 dens-pi4 ntpd[991]: 103.242.70.5 local addr 192.168.1.82 -> Aug 14 11:36:24 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:36:24 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 1 Aug 14 11:36:24 dens-pi4 volumio[602]: info: Prefetching next song Aug 14 11:36:24 dens-pi4 volumio[602]: info: DOING PREFETCH IN MPD Aug 14 11:36:24 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand add "NAS/Review/America/America/01 - America - Riverside.dsf" Aug 14 11:36:24 dens-pi4 volumio[602]: info: sendMpdCommand add "NAS/Review/America/America/01 - America - Riverside.dsf" took 1 milliseconds Aug 14 11:36:24 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 14 11:36:24 dens-pi4 volumio[602]: info: Aug 14 11:36:24 dens-pi4 volumio[602]: ---------------------------- MPD announces system playlist update Aug 14 11:36:24 dens-pi4 volumio[602]: info: Ignoring MPD Status Update Aug 14 11:36:24 dens-pi4 volumio[602]: info: Aug 14 11:36:24 dens-pi4 volumio[602]: ---------------------------- MPD announces state update: options Aug 14 11:36:24 dens-pi4 volumio[602]: info: sendMpdCommand consume 1 took 2 milliseconds Aug 14 11:36:24 dens-pi4 volumio[602]: info: ControllerMpd::getState Aug 14 11:36:24 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand status Aug 14 11:36:24 dens-pi4 volumio[602]: info: Aug 14 11:36:24 dens-pi4 volumio[602]: ---------------------------- MPD announces system playlist update Aug 14 11:36:24 dens-pi4 volumio[602]: info: Ignoring MPD Status Update Aug 14 11:36:24 dens-pi4 volumio[602]: info: ------------------------------ 3ms Aug 14 11:36:24 dens-pi4 volumio[602]: info: sendMpdCommand status took 3 milliseconds Aug 14 11:36:24 dens-pi4 volumio[602]: info: ------------------------------ 2ms Aug 14 11:36:24 dens-pi4 volumio[602]: verbose: ControllerMpd::parseState Aug 14 11:36:24 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 11:36:24 dens-pi4 volumio[602]: info: Aug 14 11:36:24 dens-pi4 volumio[602]: ---------------------------- MPD announces state update: options Aug 14 11:36:24 dens-pi4 volumio[602]: info: ControllerMpd::getState Aug 14 11:36:24 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand status Aug 14 11:36:24 dens-pi4 volumio[602]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 14 11:36:24 dens-pi4 volumio[602]: info: sendMpdCommand status took 1 milliseconds Aug 14 11:36:24 dens-pi4 volumio[602]: verbose: ControllerMpd::parseTrackInfo Aug 14 11:36:24 dens-pi4 volumio[602]: verbose: ControllerMpd::parseState Aug 14 11:36:24 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 11:36:24 dens-pi4 volumio[602]: info: ControllerMpd::pushState Aug 14 11:36:24 dens-pi4 volumio[602]: info: CoreCommandRouter::servicePushState Aug 14 11:36:24 dens-pi4 volumio[602]: info: CoreStateMachine::startPlaybackTimer Aug 14 11:36:24 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 1 Aug 14 11:36:24 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 1 Aug 14 11:36:24 dens-pi4 volumio[602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":324116,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"824 Kbps","isStreaming":false,"title":"The Mole","artist":"Hans Zimmer","album":"Dunkirk","uri":"NAS/Soundtrack/Hans Zimmer/Dunkirk/01 Hans Zimmer - The Mole.flac","trackType":"flac"} Aug 14 11:36:24 dens-pi4 volumio[602]: verbose: CURRENT POSITION 1 Aug 14 11:36:24 dens-pi4 volumio[602]: info: CoreStateMachine::syncState stateService play Aug 14 11:36:24 dens-pi4 volumio[602]: info: CoreStateMachine::syncState currentStatus play Aug 14 11:36:24 dens-pi4 volumio[602]: info: Received an update from plugin. extracting info from payload Aug 14 11:36:24 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:36:24 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 1 Aug 14 11:36:24 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 11:36:24 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:36:24 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:36:24 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 1 Aug 14 11:36:24 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:36:24 dens-pi4 volumio[602]: info: ------------------------------ 24ms Aug 14 11:36:24 dens-pi4 volumio[602]: info: sendMpdCommand playlistinfo took 19 milliseconds Aug 14 11:36:24 dens-pi4 volumio[602]: verbose: ControllerMpd::parseTrackInfo Aug 14 11:36:24 dens-pi4 volumio[602]: info: ControllerMpd::pushState Aug 14 11:36:24 dens-pi4 volumio[602]: info: CoreCommandRouter::servicePushState Aug 14 11:36:24 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 1 Aug 14 11:36:24 dens-pi4 volumio[602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":324116,"duration":329,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"824 Kbps","isStreaming":false,"title":"The Mole","artist":"Hans Zimmer","album":"Dunkirk","uri":"NAS/Soundtrack/Hans Zimmer/Dunkirk/01 Hans Zimmer - The Mole.flac","trackType":"flac"} Aug 14 11:36:24 dens-pi4 volumio[602]: verbose: CURRENT POSITION 1 Aug 14 11:36:24 dens-pi4 volumio[602]: info: CoreStateMachine::syncState stateService play Aug 14 11:36:24 dens-pi4 volumio[602]: info: CoreStateMachine::syncState currentStatus play Aug 14 11:36:24 dens-pi4 volumio[602]: info: Received an update from plugin. extracting info from payload Aug 14 11:36:24 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:36:24 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 1 Aug 14 11:36:24 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:36:24 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:36:24 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 1 Aug 14 11:36:24 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:36:24 dens-pi4 volumio[602]: info: ------------------------------ 35ms Aug 14 11:36:24 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 1 Aug 14 11:36:24 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 2 Aug 14 11:36:24 dens-pi4 volumio[602]: info: Prefetching next song Aug 14 11:36:24 dens-pi4 volumio[602]: info: DOING PREFETCH IN MPD Aug 14 11:36:24 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand add "NAS/Stereo/10cc/Deceptive Bends/01. Good Morning Judge.flac" Aug 14 11:36:24 dens-pi4 volumio[602]: info: CoreStateMachine::startPlaybackTimer Aug 14 11:36:24 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 2 Aug 14 11:36:24 dens-pi4 volumio[602]: info: Aug 14 11:36:24 dens-pi4 volumio[602]: ---------------------------- MPD announces system playlist update Aug 14 11:36:24 dens-pi4 volumio[602]: info: Ignoring MPD Status Update Aug 14 11:36:24 dens-pi4 volumio[602]: info: sendMpdCommand add "NAS/Stereo/10cc/Deceptive Bends/01. Good Morning Judge.flac" took 2 milliseconds Aug 14 11:36:24 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 14 11:36:24 dens-pi4 volumio[602]: info: Aug 14 11:36:24 dens-pi4 volumio[602]: ---------------------------- MPD announces system playlist update Aug 14 11:36:24 dens-pi4 volumio[602]: info: Ignoring MPD Status Update Aug 14 11:36:24 dens-pi4 volumio[602]: info: ------------------------------ 4ms Aug 14 11:36:24 dens-pi4 volumio[602]: info: sendMpdCommand consume 1 took 3 milliseconds Aug 14 11:36:24 dens-pi4 volumio[602]: info: ------------------------------ 2ms Aug 14 11:36:30 dens-pi4 volumio[602]: info: Aug 14 11:36:30 dens-pi4 volumio[602]: ---------------------------- MPD announces system playlist update Aug 14 11:36:30 dens-pi4 volumio[602]: info: Ignoring MPD Status Update Aug 14 11:36:30 dens-pi4 volumio[602]: info: Aug 14 11:36:30 dens-pi4 volumio[602]: ---------------------------- MPD announces state update: player Aug 14 11:36:30 dens-pi4 volumio[602]: info: ControllerMpd::getState Aug 14 11:36:30 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand status Aug 14 11:36:30 dens-pi4 volumio[602]: info: Aug 14 11:36:30 dens-pi4 volumio[602]: ---------------------------- MPD announces system playlist update Aug 14 11:36:30 dens-pi4 volumio[602]: info: Ignoring MPD Status Update Aug 14 11:36:30 dens-pi4 volumio[602]: info: Aug 14 11:36:30 dens-pi4 volumio[602]: ---------------------------- MPD announces state update: player Aug 14 11:36:30 dens-pi4 volumio[602]: info: ControllerMpd::getState Aug 14 11:36:30 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand status Aug 14 11:36:30 dens-pi4 volumio[602]: info: ------------------------------ 4ms Aug 14 11:36:30 dens-pi4 volumio[602]: info: sendMpdCommand status took 3 milliseconds Aug 14 11:36:30 dens-pi4 volumio[602]: verbose: ControllerMpd::parseState Aug 14 11:36:30 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 11:36:30 dens-pi4 volumio[602]: info: ------------------------------ 3ms Aug 14 11:36:30 dens-pi4 volumio[602]: info: sendMpdCommand status took 3 milliseconds Aug 14 11:36:30 dens-pi4 volumio[602]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 14 11:36:30 dens-pi4 volumio[602]: verbose: ControllerMpd::parseState Aug 14 11:36:30 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 11:36:30 dens-pi4 volumio[602]: verbose: ControllerMpd::parseTrackInfo Aug 14 11:36:30 dens-pi4 volumio[602]: info: ControllerMpd::pushState Aug 14 11:36:30 dens-pi4 volumio[602]: info: CoreCommandRouter::servicePushState Aug 14 11:36:30 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 2 Aug 14 11:36:30 dens-pi4 volumio[602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":143,"duration":186,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Riverside","artist":"America","album":"America","uri":"NAS/Review/America/America/01 - America - Riverside.dsf","trackType":"dsf"} Aug 14 11:36:30 dens-pi4 volumio[602]: verbose: CURRENT POSITION 2 Aug 14 11:36:30 dens-pi4 volumio[602]: info: CoreStateMachine::syncState stateService play Aug 14 11:36:30 dens-pi4 volumio[602]: info: CoreStateMachine::syncState currentStatus play Aug 14 11:36:30 dens-pi4 volumio[602]: info: Received an update from plugin. extracting info from payload Aug 14 11:36:30 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:36:30 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 2 Aug 14 11:36:30 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 11:36:30 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:36:30 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:36:30 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 2 Aug 14 11:36:30 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:36:30 dens-pi4 volumio[602]: info: ------------------------------ 22ms Aug 14 11:36:30 dens-pi4 volumio[602]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 14 11:36:30 dens-pi4 volumio[602]: verbose: ControllerMpd::parseTrackInfo Aug 14 11:36:30 dens-pi4 volumio[602]: info: ControllerMpd::pushState Aug 14 11:36:30 dens-pi4 volumio[602]: info: CoreCommandRouter::servicePushState Aug 14 11:36:30 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 2 Aug 14 11:36:30 dens-pi4 volumio[602]: verbose: STATE SERVICE {"status":"play","position":0,"seek":269,"duration":186,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Riverside","artist":"America","album":"America","uri":"NAS/Review/America/America/01 - America - Riverside.dsf","trackType":"dsf"} Aug 14 11:36:30 dens-pi4 volumio[602]: verbose: CURRENT POSITION 2 Aug 14 11:36:30 dens-pi4 volumio[602]: info: CoreStateMachine::syncState stateService play Aug 14 11:36:30 dens-pi4 volumio[602]: info: CoreStateMachine::syncState currentStatus play Aug 14 11:36:30 dens-pi4 volumio[602]: info: Received an update from plugin. extracting info from payload Aug 14 11:36:30 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:36:30 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 2 Aug 14 11:36:30 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 11:36:30 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:36:30 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:36:30 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 2 Aug 14 11:36:30 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:36:30 dens-pi4 volumio[602]: info: ------------------------------ 41ms Aug 14 11:37:45 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPause Aug 14 11:37:45 dens-pi4 volumio[602]: info: CoreStateMachine::pause Aug 14 11:37:45 dens-pi4 volumio[602]: info: CoreStateMachine::stPlaybackTimer Aug 14 11:37:45 dens-pi4 volumio[602]: info: CoreStateMachine::servicePause Aug 14 11:37:45 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 2 Aug 14 11:37:45 dens-pi4 volumio[602]: info: CoreCommandRouter::servicePause Aug 14 11:37:45 dens-pi4 volumio[602]: info: ControllerMpd::pause Aug 14 11:37:45 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand pause Aug 14 11:37:45 dens-pi4 volumio[602]: info: Aug 14 11:37:45 dens-pi4 volumio[602]: ---------------------------- MPD announces state update: player Aug 14 11:37:45 dens-pi4 volumio[602]: info: sendMpdCommand pause took 1 milliseconds Aug 14 11:37:45 dens-pi4 volumio[602]: info: ControllerMpd::getState Aug 14 11:37:45 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand status Aug 14 11:37:45 dens-pi4 volumio[602]: info: sendMpdCommand status took 0 milliseconds Aug 14 11:37:45 dens-pi4 volumio[602]: verbose: ControllerMpd::parseState Aug 14 11:37:45 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 11:37:45 dens-pi4 volumio[602]: info: Aug 14 11:37:45 dens-pi4 volumio[602]: ---------------------------- MPD announces state update: player Aug 14 11:37:45 dens-pi4 volumio[602]: info: ControllerMpd::getState Aug 14 11:37:45 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand status Aug 14 11:37:45 dens-pi4 volumio[602]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 14 11:37:45 dens-pi4 volumio[602]: info: sendMpdCommand status took 1 milliseconds Aug 14 11:37:45 dens-pi4 volumio[602]: verbose: ControllerMpd::parseTrackInfo Aug 14 11:37:45 dens-pi4 volumio[602]: verbose: ControllerMpd::parseState Aug 14 11:37:45 dens-pi4 volumio[602]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 14 11:37:45 dens-pi4 volumio[602]: info: ControllerMpd::pushState Aug 14 11:37:45 dens-pi4 volumio[602]: info: CoreCommandRouter::servicePushState Aug 14 11:37:45 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 2 Aug 14 11:37:45 dens-pi4 volumio[602]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":75865,"duration":186,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Riverside","artist":"America","album":"America","uri":"NAS/Review/America/America/01 - America - Riverside.dsf","trackType":"dsf"} Aug 14 11:37:45 dens-pi4 volumio[602]: verbose: CURRENT POSITION 2 Aug 14 11:37:45 dens-pi4 volumio[602]: info: CoreStateMachine::syncState stateService pause Aug 14 11:37:45 dens-pi4 volumio[602]: info: CoreStateMachine::syncState currentStatus pause Aug 14 11:37:45 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:37:45 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 2 Aug 14 11:37:45 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 14 11:37:45 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:37:45 dens-pi4 volumio[602]: info: CoreStateMachine::stPlaybackTimer Aug 14 11:37:45 dens-pi4 volumio[602]: info: ------------------------------ 14ms Aug 14 11:37:45 dens-pi4 volumio[602]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 14 11:37:45 dens-pi4 volumio[602]: verbose: ControllerMpd::parseTrackInfo Aug 14 11:37:45 dens-pi4 volumio[602]: info: ControllerMpd::pushState Aug 14 11:37:45 dens-pi4 volumio[602]: info: CoreCommandRouter::servicePushState Aug 14 11:37:45 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 2 Aug 14 11:37:45 dens-pi4 volumio[602]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":75865,"duration":186,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Riverside","artist":"America","album":"America","uri":"NAS/Review/America/America/01 - America - Riverside.dsf","trackType":"dsf"} Aug 14 11:37:45 dens-pi4 volumio[602]: verbose: CURRENT POSITION 2 Aug 14 11:37:45 dens-pi4 volumio[602]: info: CoreStateMachine::syncState stateService pause Aug 14 11:37:45 dens-pi4 volumio[602]: info: CoreStateMachine::syncState currentStatus pause Aug 14 11:37:45 dens-pi4 volumio[602]: info: CoreStateMachine::pushState Aug 14 11:37:45 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 2 Aug 14 11:37:45 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPushState Aug 14 11:37:45 dens-pi4 volumio[602]: info: CoreStateMachine::stPlaybackTimer Aug 14 11:37:45 dens-pi4 volumio[602]: info: ------------------------------ 23ms Aug 14 11:37:51 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPrevious Aug 14 11:37:51 dens-pi4 volumio[602]: info: CoreStateMachine::previous Aug 14 11:37:51 dens-pi4 volumio[602]: info: CoreStateMachine::updateTrackBlock Aug 14 11:37:51 dens-pi4 volumio[602]: info: CorePlayQueue::getTrackBlock Aug 14 11:37:51 dens-pi4 volumio[602]: info: CoreStateMachine::serviceClearAddPlay Aug 14 11:37:51 dens-pi4 volumio[602]: info: CoreCommandRouter::serviceClearAddPlayTracks Aug 14 11:37:51 dens-pi4 volumio[602]: error: WARNING: No clearAddPlayTracks method for service mpd Aug 14 11:37:51 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPrevious Aug 14 11:37:51 dens-pi4 volumio[602]: info: CoreStateMachine::previous Aug 14 11:37:51 dens-pi4 volumio[602]: info: CoreStateMachine::updateTrackBlock Aug 14 11:37:51 dens-pi4 volumio[602]: info: CorePlayQueue::getTrackBlock Aug 14 11:37:51 dens-pi4 volumio[602]: info: CoreStateMachine::serviceClearAddPlay Aug 14 11:37:51 dens-pi4 volumio[602]: info: CoreCommandRouter::serviceClearAddPlayTracks Aug 14 11:37:51 dens-pi4 volumio[602]: error: WARNING: No clearAddPlayTracks method for service mpd Aug 14 11:37:52 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPrevious Aug 14 11:37:52 dens-pi4 volumio[602]: info: CoreStateMachine::previous Aug 14 11:37:52 dens-pi4 volumio[602]: info: CoreStateMachine::updateTrackBlock Aug 14 11:37:52 dens-pi4 volumio[602]: info: CorePlayQueue::getTrackBlock Aug 14 11:37:52 dens-pi4 volumio[602]: info: CoreStateMachine::serviceClearAddPlay Aug 14 11:37:52 dens-pi4 volumio[602]: info: CoreCommandRouter::serviceClearAddPlayTracks Aug 14 11:37:52 dens-pi4 volumio[602]: error: WARNING: No clearAddPlayTracks method for service mpd Aug 14 11:37:53 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPrevious Aug 14 11:37:53 dens-pi4 volumio[602]: info: CoreStateMachine::previous Aug 14 11:37:53 dens-pi4 volumio[602]: info: CoreStateMachine::updateTrackBlock Aug 14 11:37:53 dens-pi4 volumio[602]: info: CorePlayQueue::getTrackBlock Aug 14 11:37:53 dens-pi4 volumio[602]: info: CoreStateMachine::serviceClearAddPlay Aug 14 11:37:53 dens-pi4 volumio[602]: info: CoreCommandRouter::serviceClearAddPlayTracks Aug 14 11:37:53 dens-pi4 volumio[602]: error: WARNING: No clearAddPlayTracks method for service mpd Aug 14 11:38:02 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPrevious Aug 14 11:38:02 dens-pi4 volumio[602]: info: CoreStateMachine::previous Aug 14 11:38:02 dens-pi4 volumio[602]: info: CoreStateMachine::updateTrackBlock Aug 14 11:38:02 dens-pi4 volumio[602]: info: CorePlayQueue::getTrackBlock Aug 14 11:38:02 dens-pi4 volumio[602]: info: CoreStateMachine::serviceClearAddPlay Aug 14 11:38:02 dens-pi4 volumio[602]: info: CoreCommandRouter::serviceClearAddPlayTracks Aug 14 11:38:02 dens-pi4 volumio[602]: error: WARNING: No clearAddPlayTracks method for service mpd Aug 14 11:38:02 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioPrevious Aug 14 11:38:02 dens-pi4 volumio[602]: info: CoreStateMachine::previous Aug 14 11:38:02 dens-pi4 volumio[602]: info: CoreStateMachine::updateTrackBlock Aug 14 11:38:02 dens-pi4 volumio[602]: info: CorePlayQueue::getTrackBlock Aug 14 11:38:02 dens-pi4 volumio[602]: info: CoreStateMachine::serviceClearAddPlay Aug 14 11:38:02 dens-pi4 volumio[602]: info: CoreCommandRouter::serviceClearAddPlayTracks Aug 14 11:38:02 dens-pi4 volumio[602]: error: WARNING: No clearAddPlayTracks method for service mpd Aug 14 11:38:14 dens-pi4 volumio[602]: verbose: New Socket.io Connection to dens-pi4.local from 192.168.1.140 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/116.0 Total Clients: 6 Aug 14 11:38:14 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioGetState Aug 14 11:38:14 dens-pi4 volumio[602]: info: CorePlayQueue::getTrack 0 Aug 14 11:38:14 dens-pi4 volumio[602]: info: CoreCommandRouter::volumioGetQueue Aug 14 11:38:14 dens-pi4 volumio[602]: info: CoreStateMachine::getQueue Aug 14 11:38:14 dens-pi4 volumio[602]: info: CorePlayQueue::getQueue Aug 14 11:38:14 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Aug 14 11:39:01 dens-pi4 volumio[602]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Aug 14 11:39:01 dens-pi4 volumio[602]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Aug 14 11:39:01 dens-pi4 sudo[1497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Aug 14 11:39:01 dens-pi4 sudo[1497]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 14 11:39:01 dens-pi4 sudo[1497]: pam_unix(sudo:session): session closed for user root Aug 14 11:39:09 dens-pi4 sudo[1543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Aug 14 11:39:09 dens-pi4 sudo[1543]: pam_unix(sudo:session): session opened for user root by (uid=0)