Tue Aug 8 23:15:20 PDT 2023 Description="'Volumio will switch to a 1st track of .cue when adjusting progress bar.'" # 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62" # ifconfig --------------- eth0: flags=4099 mtu 1500 ether d8:3a:dd:20:60:7e txqueuelen 1000 (Ethernet) RX packets 0 bytes 0 (0.0 B) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 0 bytes 0 (0.0 B) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 lo: flags=73 mtu 65536 inet 127.0.0.1 netmask 255.0.0.0 loop txqueuelen 1000 (Local Loopback) RX packets 2241 bytes 481570 (470.2 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 2241 bytes 481570 (470.2 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 wlan0: flags=4163 mtu 1500 inet 192.168.31.107 netmask 255.255.255.0 broadcast 192.168.31.255 ether d8:3a:dd:20:60:7f txqueuelen 1000 (Ethernet) RX packets 33467 bytes 45298535 (43.2 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 19344 bytes 6355996 (6.0 MiB) 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:"NotYourWifi_5G_Plus" Mode:Managed Frequency:5.24 GHz Access Point: 9C:9D:7E:7F:CE:48 Bit Rate=390 Mb/s Tx-Power=31 dBm Retry short limit:7 RTS thr:off Fragment thr:off Encryption key:off Power Management:off Link Quality=67/70 Signal level=-43 dBm Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0 Tx excessive retries:0 Invalid misc:0 Missed beacon:0 # aplay -l --------------- **** List of PLAYBACK Hardware Devices **** card 0: b1 [bcm2835 HDMI 1], device 0: bcm2835 HDMI 1 [bcm2835 HDMI 1] Subdevices: 4/4 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 card 1: Headphones [bcm2835 Headphones], device 0: bcm2835 Headphones [bcm2835 Headphones] Subdevices: 4/4 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 card 5: DAC58830752 [ADI-2 DAC (58830752)], 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 1 23:08 ? 00:00:07 /sbin/init root 2 0 0 23:08 ? 00:00:00 [kthreadd] root 3 2 0 23:08 ? 00:00:00 [rcu_gp] root 4 2 0 23:08 ? 00:00:00 [rcu_par_gp] root 7 2 0 23:08 ? 00:00:01 [kworker/u8:0-brcmf_wq/mmc1:0001:1] root 8 2 0 23:08 ? 00:00:00 [mm_percpu_wq] root 9 2 0 23:08 ? 00:00:00 [rcu_tasks_rude_] root 10 2 0 23:08 ? 00:00:00 [rcu_tasks_trace] root 11 2 0 23:08 ? 00:00:00 [ksoftirqd/0] root 12 2 0 23:08 ? 00:00:00 [rcu_sched] root 13 2 0 23:08 ? 00:00:00 [migration/0] root 14 2 0 23:08 ? 00:00:00 [cpuhp/0] root 15 2 0 23:08 ? 00:00:00 [cpuhp/1] root 16 2 0 23:08 ? 00:00:00 [migration/1] root 17 2 0 23:08 ? 00:00:00 [ksoftirqd/1] root 20 2 0 23:08 ? 00:00:00 [cpuhp/2] root 21 2 0 23:08 ? 00:00:00 [migration/2] root 22 2 0 23:08 ? 00:00:00 [ksoftirqd/2] root 23 2 0 23:08 ? 00:00:00 [kworker/2:0-mm_percpu_wq] root 24 2 0 23:08 ? 00:00:00 [kworker/2:0H-kblockd] root 25 2 0 23:08 ? 00:00:00 [cpuhp/3] root 26 2 0 23:08 ? 00:00:00 [migration/3] root 27 2 0 23:08 ? 00:00:00 [ksoftirqd/3] root 30 2 0 23:08 ? 00:00:00 [kdevtmpfs] root 31 2 0 23:08 ? 00:00:00 [netns] root 32 2 0 23:08 ? 00:00:01 [kworker/0:1-events] root 34 2 0 23:08 ? 00:00:00 [kauditd] root 35 2 0 23:08 ? 00:00:00 [kworker/0:2-events] root 36 2 0 23:08 ? 00:00:00 [khungtaskd] root 37 2 0 23:08 ? 00:00:00 [oom_reaper] root 38 2 0 23:08 ? 00:00:00 [writeback] root 39 2 0 23:08 ? 00:00:00 [kcompactd0] root 59 2 0 23:08 ? 00:00:00 [kblockd] root 60 2 0 23:08 ? 00:00:00 [blkcg_punt_bio] root 61 2 0 23:08 ? 00:00:00 [watchdogd] root 62 2 0 23:08 ? 00:00:00 [kworker/1:1H-kblockd] root 63 2 0 23:08 ? 00:00:00 [rpciod] root 64 2 0 23:08 ? 00:00:00 [kworker/u9:0-hci0] root 65 2 0 23:08 ? 00:00:00 [xprtiod] root 66 2 0 23:08 ? 00:00:00 [kswapd0] root 67 2 0 23:08 ? 00:00:00 [nfsiod] root 68 2 0 23:08 ? 00:00:00 [kthrotld] root 70 2 0 23:08 ? 00:00:00 [kworker/3:1-mm_percpu_wq] root 71 2 0 23:08 ? 00:00:00 [kworker/1:2-cifsiod] root 72 2 0 23:08 ? 00:00:00 [iscsi_eh] root 73 2 0 23:08 ? 00:00:00 [iscsi_destroy] root 74 2 0 23:08 ? 00:00:00 [nvme-wq] root 75 2 0 23:08 ? 00:00:00 [nvme-reset-wq] root 76 2 0 23:08 ? 00:00:00 [nvme-delete-wq] root 77 2 0 23:08 ? 00:00:00 [kworker/u8:1-brcmf_wq/mmc1:0001:1] root 79 2 0 23:08 ? 00:00:00 [DWC Notificatio] root 80 2 0 23:08 ? 00:00:00 [uas] root 82 2 0 23:08 ? 00:00:00 [vchiq-slot/0] root 83 2 0 23:08 ? 00:00:00 [vchiq-recy/0] root 84 2 0 23:08 ? 00:00:00 [vchiq-sync/0] root 85 2 0 23:08 ? 00:00:00 [zswap-shrink] root 88 2 0 23:08 ? 00:00:00 [kworker/1:5-events_power_efficient] root 89 2 0 23:08 ? 00:00:00 [sdhci] root 90 2 0 23:08 ? 00:00:00 [irq/48-mmc0] root 92 2 0 23:08 ? 00:00:00 [kworker/3:3-mm_percpu_wq] root 102 2 0 23:08 ? 00:00:00 [mmc_complete] root 103 2 0 23:08 ? 00:00:00 [kworker/3:1H-kblockd] root 104 2 0 23:08 ? 00:00:00 [kworker/0:1H-kblockd] root 124 2 0 23:08 ? 00:00:00 [kworker/1:2H-kblockd] root 128 2 0 23:08 ? 00:00:00 [kworker/0:2H-kblockd] root 129 2 0 23:08 ? 00:00:00 [jbd2/mmcblk0p2-] root 130 2 0 23:08 ? 00:00:00 [ext4-rsv-conver] root 138 2 0 23:08 ? 00:00:01 [loop0] root 164 2 0 23:08 ? 00:00:00 [kworker/3:2H-kblockd] root 165 2 0 23:08 ? 00:00:00 [jbd2/mmcblk0p3-] root 166 2 0 23:08 ? 00:00:00 [ext4-rsv-conver] root 173 2 0 23:08 ? 00:00:00 [ipv6_addrconf] root 194 2 0 23:08 ? 00:00:00 [kworker/2:2H-kblockd] root 206 1 0 23:08 ? 00:00:00 /lib/systemd/systemd-journald root 208 2 0 23:08 ? 00:00:00 [kworker/2:3-events] root 222 1 0 23:08 ? 00:00:00 /lib/systemd/systemd-udevd root 263 2 0 23:08 ? 00:00:00 [vchiq-keep/0] root 267 2 0 23:08 ? 00:00:00 [SMIO] root 304 2 0 23:08 ? 00:00:00 [mmal-vchiq] root 306 2 0 23:08 ? 00:00:00 [mmal-vchiq] root 309 2 0 23:08 ? 00:00:00 [mmal-vchiq] root 312 2 0 23:08 ? 00:00:00 [mmal-vchiq] root 313 2 0 23:08 ? 00:00:00 [mmal-vchiq] root 318 2 0 23:08 ? 00:00:00 [mmal-vchiq] root 360 2 0 23:08 ? 00:00:00 [cfg80211] root 363 2 0 23:08 ? 00:00:00 [brcmf_wq/mmc1:0] root 364 2 0 23:08 ? 00:00:00 [brcmf_wdog/mmc1] _rpc 391 1 0 23:08 ? 00:00:00 /sbin/rpcbind -f -w root 393 1 0 23:08 ? 00:00:02 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 avahi 521 1 0 23:08 ? 00:00:00 avahi-daemon: running [notyourvolumio.local] message+ 522 1 0 23:08 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 524 1 0 23:08 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant root 527 1 0 23:08 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon root 534 1 0 23:08 ? 00:00:00 /lib/systemd/systemd-logind volumio 537 1 0 23:08 ? 00:00:00 /usr/local/bin/volumio-remote-updater nobody 539 1 0 23:08 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* avahi 540 521 0 23:08 ? 00:00:00 avahi-daemon: chroot helper root 619 1 0 23:08 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 634 1 0 23:08 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I root 636 1 0 23:08 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux ntp 640 1 0 23:08 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 root 648 619 0 23:08 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 692 2 0 23:09 ? 00:00:00 [kworker/u9:1-hci0] root 693 1 0 23:09 ? 00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 3000000 flow - root 707 1 0 23:09 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap root 734 1 0 23:09 ? 00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0 root 746 1 0 23:09 ? 00:00:00 /sbin/dhcpcd volumio 829 1 3 23:09 ? 00:00:13 /usr/bin/node /volumio/index.js root 846 1 0 23:09 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 849 846 0 23:09 ? 00:00:00 winbindd: domain child [NOTYOURVOLUMIO] root 851 1 0 23:09 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 858 851 0 23:09 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 859 851 0 23:09 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 863 846 0 23:09 ? 00:00:00 winbindd: idmap child root 867 846 0 23:09 ? 00:00:00 winbindd: domain child [BUILTIN] root 869 851 0 23:09 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 890 2 0 23:09 ? 00:00:00 [cifsiod] root 891 2 0 23:09 ? 00:00:00 [smb3decryptd] root 892 2 0 23:09 ? 00:00:00 [cifsfileinfoput] root 893 2 0 23:09 ? 00:00:00 [cifsoplockd] root 894 2 0 23:09 ? 00:00:00 [cifs-dfscache] root 896 2 0 23:09 ? 00:00:00 [cifsd] volumio 915 829 0 23:09 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 922 915 0 23:09 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 923 915 0 23:09 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 924 915 0 23:09 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart mpd 977 1 0 23:09 ? 00:00:02 /usr/bin/mpd --no-daemon volumio 1008 1 0 23:09 ? 00:00:00 /bin/bash /usr/lib/startconnect.sh volumio 1009 1008 0 23:09 ? 00:00:00 ./usr/bin/vollibrespot -c /tmp/volspotify.toml - shairpo+ 1026 1 0 23:09 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf volumio 1041 1 0 23:09 ? 00:00:01 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf volumio 1059 829 0 23:09 ? 00:00:00 /usr/bin/node /myvolumio/plugins/music_service/streaming_services/streamingProxy.js root 1314 2 0 23:14 ? 00:00:00 [kworker/3:0-events_power_efficient] volumio 1318 829 0 23:15 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'Volumio will switch to a 1st track of .cue when adjusting progress bar.' volumio 1319 1318 0 23:15 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js Volumio will switch to a 1st track of .cue when adjusting progress bar. volumio 1339 1319 0 23:15 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 1340 1339 0 23:15 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.5G 968M 1.4G 42% /imgpart /dev/loop0 443M 443M 0 100% /static overlay 4.7G 392M 4.1G 9% / devtmpfs 1.9G 0 1.9G 0% /dev tmpfs 1.9G 0 1.9G 0% /dev/shm tmpfs 1.9G 5.2M 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 60K 1.9G 1% /tmp tmpfs 20M 28K 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.31.70/NotYourNas\music 21T 2.2T 19T 11% /mnt/NAS/NAS # 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/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) 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/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) cgroup on /sys/fs/cgroup/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event) cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) 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) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=35,pgrp=1,timeout=0,minproto=5,maxproto=5,direct) mqueue on /dev/mqueue type mqueue (rw,relatime) debugfs on /sys/kernel/debug type debugfs (rw,relatime) sunrpc on /run/rpc_pipefs type rpc_pipefs (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.31.70/NotYourNas\music on /mnt/NAS/NAS type cifs (ro,relatime,vers=3.1.1,cache=strict,username=jiajia,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.31.70,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 1041/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 1041/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:38099 0.0.0.0:* LISTEN 1009/./usr/bin/voll tcp 0 0 127.0.0.1:50060 127.0.0.1:6599 ESTABLISHED 1041/upmpdcli tcp 0 0 127.0.0.1:58698 127.0.0.1:3000 ESTABLISHED 537/volumio-remote- tcp 0 0 127.0.0.1:53032 127.0.0.1:6600 ESTABLISHED 829/node tcp 0 0 127.0.0.1:50124 127.0.0.1:6599 ESTABLISHED 1041/upmpdcli tcp 0 0 192.168.31.107:52392 34.120.160.131:443 ESTABLISHED 829/node tcp 0 0 127.0.0.1:58712 127.0.0.1:3000 ESTABLISHED 829/node tcp 0 0 127.0.0.1:58762 127.0.0.1:3000 ESTABLISHED 829/node tcp 0 0 127.0.0.1:52968 127.0.0.1:6600 ESTABLISHED 829/node tcp 0 0 127.0.0.1:58716 127.0.0.1:3000 ESTABLISHED 829/node tcp 0 0 192.168.31.107:52204 192.168.31.70:445 ESTABLISHED - tcp6 0 0 :::3005 :::* LISTEN 829/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::6599 :::* LISTEN 829/node tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::3245 :::* LISTEN 1059/node tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 829/node tcp6 0 0 :::3001 :::* LISTEN 915/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:58698 ESTABLISHED 829/node tcp6 0 0 192.168.31.107:3000 192.168.31.98:63096 ESTABLISHED 829/node tcp6 0 0 192.168.31.107:3000 192.168.31.98:63086 ESTABLISHED 829/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:50060 ESTABLISHED 829/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:50124 ESTABLISHED 829/node tcp6 0 0 127.0.0.1:6600 127.0.0.1:52968 ESTABLISHED - tcp6 0 0 127.0.0.1:3000 127.0.0.1:58712 ESTABLISHED 829/node tcp6 0 0 127.0.0.1:6600 127.0.0.1:53032 ESTABLISHED - tcp6 0 0 127.0.0.1:3000 127.0.0.1:58716 ESTABLISHED 829/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:58762 ESTABLISHED 829/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 "DAC58830752" } # cat /var/log/mpd.log --------------- Aug 08 23:08 : zeroconf: No global port, disabling zeroconf Aug 08 23:08 : zeroconf: No global port, disabling zeroconf Aug 08 23:08 : client: [0] opened from local Aug 08 23:08 : client: [1] opened from local Aug 08 23:09 : zeroconf: No global port, disabling zeroconf Aug 08 23:09 : client: [0] opened from local Aug 08 23:09 : exception: No such directory Aug 08 23:09 : client: [1] opened from 127.0.0.1:52964 Aug 08 23:09 : client: [2] opened from 127.0.0.1:52968 Aug 08 23:09 : exception: Failed to read mixer for 'alsa': no such mixer control: PCM Aug 08 23:10 : client: [1] closed Aug 08 23:12 : exception: Failed to open mixer for 'alsa': no such mixer control: PCM Aug 08 23:12 : client: [3] opened from 127.0.0.1:53032 Aug 08 23:13 : player: played "NAS/NAS/tmp/Mahler Symphonies 1-10 Gary Bertini/CD2/Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2.wav" Aug 08 23:13 : exception: Failed to open mixer for 'alsa': no such mixer control: PCM # volumio plugin list --------------- This command will list installed plugins on your device ----------------------- Installed Plugins: [ { prettyName: 'Spotify', name: 'spop', category: 'music_service', version: '3.0.9', icon: 'fa-spotify', 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" } }, "spop": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "audio_interface": { "outputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "alsa_controller": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "miscellanea": { "alarm-clock": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "albumart": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "appearance": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "wizard": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "my_music": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "user_interface": { "websocket": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpdemulation": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STARTED" } }, "rest_api": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "system_controller": { "updater_comm": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "network": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "networkfs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "services": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "system": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "i2s_dacs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volumiodiscovery": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volumio_command_line_client": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "my_volumio": { "status": { "type": "string", "value": "STARTED" } } } } # cat /etc/mpd.conf --------------- # Volumio MPD Configuration File # Files and directories ####################################################### music_directory "/var/lib/mpd/music" playlist_directory "/var/lib/mpd/playlists" db_file "/var/lib/mpd/tag_cache" log_file "/var/log/mpd.log" #pid_file "/var/run/mpd/pid" #state_file "/var/lib/mpd/state" #sticker_file "/var/lib/mpd/sticker.sql" ############################################################################### # General music daemon options ################################################ user "mpd" group "audio" bind_to_address "any" #port "6600" #log_level "default" #save_absolute_paths_in_playlists "no" #metadata_to_use "artist,album,title,track,name,genre,date,composer,performer,disc" auto_update "no" #auto_update_depth "3" ############################################################################### # Symbolic link behavior ###################################################### follow_outside_symlinks "yes" follow_inside_symlinks "yes" ############################################################################### # Input ####################################################################### # input { plugin "curl" # proxy "proxy.isp.com:8080" # proxy_user "user" # proxy_password "password" } ############################################################################### # Decoder ################################################################ decoder { plugin "faad" enabled "no" } ############################################################################### # Audio Output ################################################################ resampler { plugin "soxr" quality "high" threads "1" } audio_output { type "alsa" name "alsa" device "volumio" dop "no" } audio_output { type "fifo" enabled "no" name "multiroom" path "/tmp/snapfifo" format "44100:16:2" } #replaygain "album" #replaygain_preamp "0" volume_normalization "no" ############################################################################### # MPD Internal Buffering ###################################################### audio_buffer_size "2048" ############################################################################### # Resource Limitations ######################################################## #connection_timeout "60" max_connections "20" max_playlist_length "81920" max_command_list_size "81920" max_output_buffer_size "81920" ############################################################################### # Character Encoding ########################################################## filesystem_charset "UTF-8" ############################################################################### # volumio endpointstest --------------- TESTING REMOTE ENDPOINTS https://google.com, 384 ms: OK https://www.googleapis.com, 266 ms: OK https://securetoken.googleapis.com, 239 ms: OK https://myvolumio.firebaseio.com, 308 ms: OK https://functions.volumio.cloud, 293 ms: OK https://oauth-performer.dfs.volumio.org, 773 ms: OK https://browsing-performer.dfs.volumio.org, 757 ms: OK http://cddb.volumio.org, 1293 ms: OK https://functions.volumio.cloud, 307 ms: OK http://pushupdates.volumio.org, 509 ms: OK http://plugins.volumio.org, 562 ms: OK https://database.volumio.cloud, 474 ms: OK https://radio-directory.firebaseapp.com, 517 ms: OK ---------- REMOTE ENDPOINTS TEST OK, all Endpoints are reachable ---------- # cat /data/updatesHistoryFile --------------- --- Thu Mar 30 2023 15:51:01 GMT+0000 (Coordinated Universal Time) {"systemversion":"3.449","builddate":"Thu 30 Mar 2023 05:45:03 PM CEST","variant":"volumio","hardware":"pi"} ------ Mon Jul 31 2023 23:25:01 GMT-0700 (Pacific Daylight Time) {"systemversion":"3.512","builddate":"Sun 25 Jun 2023 07:20:58 PM CEST","variant":"volumio","hardware":"pi"} --- # sudo journalctl -p 7 --------------- -- Logs begin at Tue 2023-08-08 23:08:26 PDT, end at Tue 2023-08-08 23:15:24 PDT. -- Aug 08 23:08:26 notyourvolumio kernel: Booting Linux on physical CPU 0x0 Aug 08 23:08:26 notyourvolumio 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 Aug 08 23:08:26 notyourvolumio kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Aug 08 23:08:26 notyourvolumio kernel: CPU: div instructions available: patching division code Aug 08 23:08:26 notyourvolumio kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Aug 08 23:08:26 notyourvolumio kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.5 Aug 08 23:08:26 notyourvolumio kernel: random: fast init done Aug 08 23:08:26 notyourvolumio kernel: Memory policy: Data cache writealloc Aug 08 23:08:26 notyourvolumio kernel: Reserved memory: created CMA memory pool at 0x000000002a000000, size 64 MiB Aug 08 23:08:26 notyourvolumio kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Aug 08 23:08:26 notyourvolumio kernel: Zone ranges: Aug 08 23:08:26 notyourvolumio kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] Aug 08 23:08:26 notyourvolumio kernel: Normal empty Aug 08 23:08:26 notyourvolumio kernel: HighMem [mem 0x0000000030000000-0x00000000fbffffff] Aug 08 23:08:26 notyourvolumio kernel: Movable zone start for each node Aug 08 23:08:26 notyourvolumio kernel: Early memory node ranges Aug 08 23:08:26 notyourvolumio kernel: node 0: [mem 0x0000000000000000-0x000000003dffffff] Aug 08 23:08:26 notyourvolumio kernel: node 0: [mem 0x0000000040000000-0x00000000fbffffff] Aug 08 23:08:26 notyourvolumio kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff] Aug 08 23:08:26 notyourvolumio kernel: On node 0 totalpages: 1024000 Aug 08 23:08:26 notyourvolumio kernel: DMA zone: 1728 pages used for memmap Aug 08 23:08:26 notyourvolumio kernel: DMA zone: 0 pages reserved Aug 08 23:08:26 notyourvolumio kernel: DMA zone: 196608 pages, LIFO batch:63 Aug 08 23:08:26 notyourvolumio kernel: HighMem zone: 827392 pages, LIFO batch:63 Aug 08 23:08:26 notyourvolumio kernel: percpu: Embedded 20 pages/cpu s50828 r8192 d22900 u81920 Aug 08 23:08:26 notyourvolumio kernel: pcpu-alloc: s50828 r8192 d22900 u81920 alloc=20*4096 Aug 08 23:08:26 notyourvolumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Aug 08 23:08:26 notyourvolumio kernel: Built 1 zonelists, mobility grouping on. Total pages: 1022272 Aug 08 23:08:26 notyourvolumio 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=D8:3A:DD:20:60:7E 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 Aug 08 23:08:26 notyourvolumio kernel: Kernel parameter elevator= does not have any effect anymore. Please use sysfs to set IO scheduler for individual devices. Aug 08 23:08:26 notyourvolumio kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Aug 08 23:08:26 notyourvolumio kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 08 23:08:26 notyourvolumio kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Aug 08 23:08:26 notyourvolumio kernel: software IO TLB: mapped [mem 0x0000000023c90000-0x0000000027c90000] (64MB) Aug 08 23:08:26 notyourvolumio kernel: Memory: 3895420K/4096000K available (10240K kernel code, 1366K rwdata, 3196K rodata, 2048K init, 882K bss, 135044K reserved, 65536K cma-reserved, 3309568K highmem) Aug 08 23:08:26 notyourvolumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Aug 08 23:08:26 notyourvolumio kernel: ftrace: allocating 34360 entries in 101 pages Aug 08 23:08:26 notyourvolumio kernel: ftrace: allocated 101 pages with 4 groups Aug 08 23:08:26 notyourvolumio kernel: rcu: Hierarchical RCU implementation. Aug 08 23:08:26 notyourvolumio kernel: Rude variant of Tasks RCU enabled. Aug 08 23:08:26 notyourvolumio kernel: Tracing variant of Tasks RCU enabled. Aug 08 23:08:26 notyourvolumio kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Aug 08 23:08:26 notyourvolumio kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Aug 08 23:08:26 notyourvolumio kernel: GIC: Using split EOI/Deactivate mode Aug 08 23:08:26 notyourvolumio kernel: random: get_random_bytes called from start_kernel+0x3c8/0x59c with crng_init=1 Aug 08 23:08:26 notyourvolumio kernel: sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns Aug 08 23:08:26 notyourvolumio kernel: clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns Aug 08 23:08:26 notyourvolumio kernel: bcm2835: system timer (irq = 25) Aug 08 23:08:26 notyourvolumio kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Aug 08 23:08:26 notyourvolumio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Aug 08 23:08:26 notyourvolumio kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Aug 08 23:08:26 notyourvolumio kernel: Switching to timer-based delay loop, resolution 18ns Aug 08 23:08:26 notyourvolumio kernel: Console: colour dummy device 80x30 Aug 08 23:08:26 notyourvolumio kernel: printk: console [tty1] enabled Aug 08 23:08:26 notyourvolumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Aug 08 23:08:26 notyourvolumio kernel: pid_max: default: 32768 minimum: 301 Aug 08 23:08:26 notyourvolumio kernel: LSM: Security Framework initializing Aug 08 23:08:26 notyourvolumio kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Aug 08 23:08:26 notyourvolumio kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Aug 08 23:08:26 notyourvolumio kernel: cgroup: Disabling memory control group subsystem Aug 08 23:08:26 notyourvolumio kernel: CPU: Testing write buffer coherency: ok Aug 08 23:08:26 notyourvolumio kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Aug 08 23:08:26 notyourvolumio kernel: Setting up static identity map for 0x200000 - 0x20003c Aug 08 23:08:26 notyourvolumio kernel: rcu: Hierarchical SRCU implementation. Aug 08 23:08:26 notyourvolumio kernel: smp: Bringing up secondary CPUs ... Aug 08 23:08:26 notyourvolumio kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Aug 08 23:08:26 notyourvolumio kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Aug 08 23:08:26 notyourvolumio kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Aug 08 23:08:26 notyourvolumio kernel: smp: Brought up 1 node, 4 CPUs Aug 08 23:08:26 notyourvolumio kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Aug 08 23:08:26 notyourvolumio kernel: CPU: All CPU(s) started in HYP mode. Aug 08 23:08:26 notyourvolumio kernel: CPU: Virtualization extensions available. Aug 08 23:08:26 notyourvolumio kernel: devtmpfs: initialized Aug 08 23:08:26 notyourvolumio kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Aug 08 23:08:26 notyourvolumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Aug 08 23:08:26 notyourvolumio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Aug 08 23:08:26 notyourvolumio kernel: pinctrl core: initialized pinctrl subsystem Aug 08 23:08:26 notyourvolumio kernel: NET: Registered protocol family 16 Aug 08 23:08:26 notyourvolumio kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Aug 08 23:08:26 notyourvolumio kernel: audit: initializing netlink subsys (disabled) Aug 08 23:08:26 notyourvolumio kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Aug 08 23:08:26 notyourvolumio kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 08 23:08:26 notyourvolumio kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Aug 08 23:08:26 notyourvolumio kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Aug 08 23:08:26 notyourvolumio kernel: Serial: AMBA PL011 UART driver Aug 08 23:08:26 notyourvolumio kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Aug 08 23:08:26 notyourvolumio kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2022-01-20T13:56:48, variant start Aug 08 23:08:26 notyourvolumio kernel: raspberrypi-firmware soc:firmware: Firmware hash is bd88f66f8952d34e4e0613a85c7a6d3da49e13e2 Aug 08 23:08:26 notyourvolumio kernel: Kprobes globally optimized Aug 08 23:08:26 notyourvolumio kernel: bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1 Aug 08 23:08:26 notyourvolumio kernel: vgaarb: loaded Aug 08 23:08:26 notyourvolumio kernel: SCSI subsystem initialized Aug 08 23:08:26 notyourvolumio kernel: usbcore: registered new interface driver usbfs Aug 08 23:08:26 notyourvolumio kernel: usbcore: registered new interface driver hub Aug 08 23:08:26 notyourvolumio kernel: usbcore: registered new device driver usb Aug 08 23:08:26 notyourvolumio kernel: usb_phy_generic phy: supply vcc not found, using dummy regulator Aug 08 23:08:26 notyourvolumio kernel: clocksource: Switched to clocksource arch_sys_counter Aug 08 23:08:26 notyourvolumio kernel: VFS: Disk quotas dquot_6.6.0 Aug 08 23:08:26 notyourvolumio kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Aug 08 23:08:26 notyourvolumio kernel: FS-Cache: Loaded Aug 08 23:08:26 notyourvolumio kernel: CacheFiles: Loaded Aug 08 23:08:26 notyourvolumio kernel: NET: Registered protocol family 2 Aug 08 23:08:26 notyourvolumio kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Aug 08 23:08:26 notyourvolumio kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) Aug 08 23:08:26 notyourvolumio kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Aug 08 23:08:26 notyourvolumio kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 08 23:08:26 notyourvolumio kernel: TCP: Hash tables configured (established 8192 bind 8192) Aug 08 23:08:26 notyourvolumio kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Aug 08 23:08:26 notyourvolumio kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Aug 08 23:08:26 notyourvolumio kernel: NET: Registered protocol family 1 Aug 08 23:08:26 notyourvolumio kernel: RPC: Registered named UNIX socket transport module. Aug 08 23:08:26 notyourvolumio kernel: RPC: Registered udp transport module. Aug 08 23:08:26 notyourvolumio kernel: RPC: Registered tcp transport module. Aug 08 23:08:26 notyourvolumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Aug 08 23:08:26 notyourvolumio kernel: PCI: CLS 0 bytes, default 64 Aug 08 23:08:26 notyourvolumio kernel: Trying to unpack rootfs image as initramfs... Aug 08 23:08:26 notyourvolumio kernel: Freeing initrd memory: 13028K Aug 08 23:08:26 notyourvolumio kernel: Initialise system trusted keyrings Aug 08 23:08:26 notyourvolumio kernel: workingset: timestamp_bits=14 max_order=20 bucket_order=6 Aug 08 23:08:26 notyourvolumio kernel: zbud: loaded Aug 08 23:08:26 notyourvolumio kernel: FS-Cache: Netfs 'nfs' registered for caching Aug 08 23:08:26 notyourvolumio kernel: NFS: Registering the id_resolver key type Aug 08 23:08:26 notyourvolumio kernel: Key type id_resolver registered Aug 08 23:08:26 notyourvolumio kernel: Key type id_legacy registered Aug 08 23:08:26 notyourvolumio kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Aug 08 23:08:26 notyourvolumio kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Aug 08 23:08:26 notyourvolumio kernel: Key type asymmetric registered Aug 08 23:08:26 notyourvolumio kernel: Asymmetric key parser 'x509' registered Aug 08 23:08:26 notyourvolumio kernel: bounce: pool size: 64 pages Aug 08 23:08:26 notyourvolumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Aug 08 23:08:26 notyourvolumio kernel: io scheduler mq-deadline registered Aug 08 23:08:26 notyourvolumio kernel: io scheduler kyber registered Aug 08 23:08:26 notyourvolumio kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Aug 08 23:08:26 notyourvolumio kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Aug 08 23:08:26 notyourvolumio kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Aug 08 23:08:26 notyourvolumio kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00ffffffff -> 0x0400000000 Aug 08 23:08:26 notyourvolumio kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Aug 08 23:08:26 notyourvolumio kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Aug 08 23:08:26 notyourvolumio kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Aug 08 23:08:26 notyourvolumio kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Aug 08 23:08:26 notyourvolumio kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Aug 08 23:08:26 notyourvolumio kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Aug 08 23:08:26 notyourvolumio kernel: PCI: bus0: Fast back to back transfers disabled Aug 08 23:08:26 notyourvolumio kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Aug 08 23:08:26 notyourvolumio kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Aug 08 23:08:26 notyourvolumio kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Aug 08 23:08:26 notyourvolumio kernel: pci 0000:01:00.0: PME# supported from D0 D3hot Aug 08 23:08:26 notyourvolumio kernel: PCI: bus1: Fast back to back transfers disabled Aug 08 23:08:26 notyourvolumio kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Aug 08 23:08:26 notyourvolumio kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Aug 08 23:08:26 notyourvolumio kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Aug 08 23:08:26 notyourvolumio kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Aug 08 23:08:26 notyourvolumio kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Aug 08 23:08:26 notyourvolumio kernel: pcieport 0000:00:00.0: enabling device (0140 -> 0142) Aug 08 23:08:26 notyourvolumio kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 62 Aug 08 23:08:26 notyourvolumio kernel: bcm2708_fb soc:fb: FB found 1 display(s) Aug 08 23:08:26 notyourvolumio kernel: Console: switching to colour frame buffer device 80x30 Aug 08 23:08:26 notyourvolumio kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Aug 08 23:08:26 notyourvolumio kernel: iproc-rng200 fe104000.rng: hwrng registered Aug 08 23:08:26 notyourvolumio kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Aug 08 23:08:26 notyourvolumio kernel: gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000 Aug 08 23:08:26 notyourvolumio kernel: brd: module loaded Aug 08 23:08:26 notyourvolumio kernel: loop: module loaded Aug 08 23:08:26 notyourvolumio kernel: Loading iSCSI transport class v2.0-870. Aug 08 23:08:26 notyourvolumio kernel: libphy: Fixed MDIO Bus: probed Aug 08 23:08:26 notyourvolumio kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Aug 08 23:08:26 notyourvolumio kernel: libphy: bcmgenet MII bus: probed Aug 08 23:08:26 notyourvolumio kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Aug 08 23:08:26 notyourvolumio kernel: usbcore: registered new interface driver r8152 Aug 08 23:08:26 notyourvolumio kernel: usbcore: registered new interface driver lan78xx Aug 08 23:08:26 notyourvolumio kernel: usbcore: registered new interface driver smsc95xx Aug 08 23:08:26 notyourvolumio kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Aug 08 23:08:26 notyourvolumio kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Aug 08 23:08:26 notyourvolumio kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x00000e0000000890 Aug 08 23:08:26 notyourvolumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 Aug 08 23:08:26 notyourvolumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Aug 08 23:08:26 notyourvolumio kernel: usb usb1: Product: xHCI Host Controller Aug 08 23:08:26 notyourvolumio kernel: usb usb1: Manufacturer: Linux 5.10.92-v7l+ xhci-hcd Aug 08 23:08:26 notyourvolumio kernel: usb usb1: SerialNumber: 0000:01:00.0 Aug 08 23:08:26 notyourvolumio kernel: hub 1-0:1.0: USB hub found Aug 08 23:08:26 notyourvolumio kernel: hub 1-0:1.0: 1 port detected Aug 08 23:08:26 notyourvolumio kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Aug 08 23:08:26 notyourvolumio kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Aug 08 23:08:26 notyourvolumio kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Aug 08 23:08:26 notyourvolumio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 Aug 08 23:08:26 notyourvolumio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Aug 08 23:08:26 notyourvolumio kernel: usb usb2: Product: xHCI Host Controller Aug 08 23:08:26 notyourvolumio kernel: usb usb2: Manufacturer: Linux 5.10.92-v7l+ xhci-hcd Aug 08 23:08:26 notyourvolumio kernel: usb usb2: SerialNumber: 0000:01:00.0 Aug 08 23:08:26 notyourvolumio kernel: hub 2-0:1.0: USB hub found Aug 08 23:08:26 notyourvolumio kernel: hub 2-0:1.0: 4 ports detected Aug 08 23:08:26 notyourvolumio kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Aug 08 23:08:26 notyourvolumio kernel: dwc_otg: FIQ enabled Aug 08 23:08:26 notyourvolumio kernel: dwc_otg: NAK holdoff enabled Aug 08 23:08:26 notyourvolumio kernel: dwc_otg: FIQ split-transaction FSM enabled Aug 08 23:08:26 notyourvolumio kernel: Module dwc_common_port init Aug 08 23:08:26 notyourvolumio kernel: usbcore: registered new interface driver uas Aug 08 23:08:26 notyourvolumio kernel: usbcore: registered new interface driver usb-storage Aug 08 23:08:26 notyourvolumio kernel: mousedev: PS/2 mouse device common for all mice Aug 08 23:08:26 notyourvolumio kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Aug 08 23:08:26 notyourvolumio kernel: sdhci: Secure Digital Host Controller Interface driver Aug 08 23:08:26 notyourvolumio kernel: sdhci: Copyright(c) Pierre Ossman Aug 08 23:08:26 notyourvolumio kernel: mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe Aug 08 23:08:26 notyourvolumio kernel: sdhci-pltfm: SDHCI platform and OF driver helper Aug 08 23:08:26 notyourvolumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Aug 08 23:08:26 notyourvolumio kernel: hid: raw HID events driver (C) Jiri Kosina Aug 08 23:08:26 notyourvolumio kernel: usbcore: registered new interface driver usbhid Aug 08 23:08:26 notyourvolumio kernel: usbhid: USB HID core driver Aug 08 23:08:26 notyourvolumio kernel: Initializing XFRM netlink socket Aug 08 23:08:26 notyourvolumio kernel: NET: Registered protocol family 17 Aug 08 23:08:26 notyourvolumio kernel: Key type dns_resolver registered Aug 08 23:08:26 notyourvolumio kernel: Registering SWP/SWPB emulation handler Aug 08 23:08:26 notyourvolumio kernel: registered taskstats version 1 Aug 08 23:08:26 notyourvolumio kernel: Loading compiled-in X.509 certificates Aug 08 23:08:26 notyourvolumio kernel: Key type ._fscrypt registered Aug 08 23:08:26 notyourvolumio kernel: Key type .fscrypt registered Aug 08 23:08:26 notyourvolumio kernel: Key type fscrypt-provisioning registered Aug 08 23:08:26 notyourvolumio kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Aug 08 23:08:26 notyourvolumio kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Aug 08 23:08:26 notyourvolumio kernel: fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2 Aug 08 23:08:26 notyourvolumio kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Aug 08 23:08:26 notyourvolumio kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Aug 08 23:08:26 notyourvolumio kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Aug 08 23:08:26 notyourvolumio kernel: of_cfs_init Aug 08 23:08:26 notyourvolumio kernel: of_cfs_init: OK Aug 08 23:08:26 notyourvolumio kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Aug 08 23:08:26 notyourvolumio kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Aug 08 23:08:26 notyourvolumio kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Aug 08 23:08:26 notyourvolumio kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Aug 08 23:08:26 notyourvolumio kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Aug 08 23:08:26 notyourvolumio kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Aug 08 23:08:26 notyourvolumio kernel: Freeing unused kernel memory: 2048K Aug 08 23:08:26 notyourvolumio kernel: Run /init as init process Aug 08 23:08:26 notyourvolumio kernel: with arguments: Aug 08 23:08:26 notyourvolumio kernel: /init Aug 08 23:08:26 notyourvolumio kernel: splash Aug 08 23:08:26 notyourvolumio kernel: with environment: Aug 08 23:08:26 notyourvolumio kernel: HOME=/ Aug 08 23:08:26 notyourvolumio kernel: TERM=linux Aug 08 23:08:26 notyourvolumio kernel: imgpart=/dev/mmcblk0p2 Aug 08 23:08:26 notyourvolumio kernel: imgfile=/volumio_current.sqsh Aug 08 23:08:26 notyourvolumio kernel: bootdelay=5 Aug 08 23:08:26 notyourvolumio kernel: mmc1: new high speed SDIO card at address 0001 Aug 08 23:08:26 notyourvolumio kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Aug 08 23:08:26 notyourvolumio kernel: mmcblk0: mmc0:aaaa SA08G 7.40 GiB Aug 08 23:08:26 notyourvolumio kernel: mmcblk0: p1 p2 p3 Aug 08 23:08:26 notyourvolumio kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Aug 08 23:08:26 notyourvolumio initramfs: Booting Volumio for BCM2711 Aug 08 23:08:26 notyourvolumio initramfs: This script mounts rootfs RO with an overlay RW layer. Aug 08 23:08:26 notyourvolumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 08 23:08:26 notyourvolumio initramfs: IMGPART=/dev/mmcblk0p2 Aug 08 23:08:26 notyourvolumio initramfs: IMGFILE=/volumio_current.sqsh Aug 08 23:08:26 notyourvolumio initramfs: Boot delay (except first time) will be 5 seconds Aug 08 23:08:26 notyourvolumio initramfs: /dev/mmcblk0p2: Aug 08 23:08:26 notyourvolumio kernel: EXT4-fs (mmcblk0p2): recovery complete Aug 08 23:08:26 notyourvolumio kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Aug 08 23:08:26 notyourvolumio initramfs: Doing a 5 second delay here to give kernel load a headstart Aug 08 23:08:26 notyourvolumio kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Aug 08 23:08:26 notyourvolumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Aug 08 23:08:26 notyourvolumio kernel: usb 1-1: Product: USB2.0 Hub Aug 08 23:08:26 notyourvolumio kernel: hub 1-1:1.0: USB hub found Aug 08 23:08:26 notyourvolumio kernel: hub 1-1:1.0: 4 ports detected Aug 08 23:08:26 notyourvolumio kernel: usb 1-1.1: new high-speed USB device number 3 using xhci_hcd Aug 08 23:08:26 notyourvolumio kernel: usb 1-1.1: New USB device found, idVendor=2a39, idProduct=3fd3, bcdDevice= 0.01 Aug 08 23:08:26 notyourvolumio kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 08 23:08:26 notyourvolumio kernel: usb 1-1.1: Product: ADI-2 DAC (58830752) Aug 08 23:08:26 notyourvolumio kernel: usb 1-1.1: Manufacturer: RME Aug 08 23:08:26 notyourvolumio kernel: usb 1-1.1: SerialNumber: C3F82E92475C8C8 Aug 08 23:08:26 notyourvolumio initramfs: Checking for USB updates Aug 08 23:08:26 notyourvolumio initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Aug 08 23:08:26 notyourvolumio kernel: EXT4-fs (mmcblk0p3): recovery complete Aug 08 23:08:26 notyourvolumio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Aug 08 23:08:26 notyourvolumio initramfs: With Option: Aug 08 23:08:26 notyourvolumio initramfs: VOLUMIO_VERSION="3.512" Aug 08 23:08:26 notyourvolumio initramfs: Finish initramfs, continue booting Volumio Aug 08 23:08:26 notyourvolumio systemd[1]: System time before build time, advancing clock. Aug 08 23:08:26 notyourvolumio kernel: NET: Registered protocol family 10 Aug 08 23:08:26 notyourvolumio kernel: Segment Routing with IPv6 Aug 08 23:08:26 notyourvolumio 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 08 23:08:26 notyourvolumio systemd[1]: Detected architecture arm. Aug 08 23:08:26 notyourvolumio systemd[1]: Set hostname to . Aug 08 23:08:26 notyourvolumio 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 08 23:08:26 notyourvolumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Aug 08 23:08:26 notyourvolumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Aug 08 23:08:26 notyourvolumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Aug 08 23:08:26 notyourvolumio systemd[1]: Listening on fsck to fsckd communication Socket. Aug 08 23:08:26 notyourvolumio systemd[1]: Created slice system-systemd\x2dfsck.slice. Aug 08 23:08:26 notyourvolumio systemd[1]: Listening on udev Kernel Socket. Aug 08 23:08:26 notyourvolumio systemd[1]: Created slice User and Session Slice. Aug 08 23:08:26 notyourvolumio systemd[1]: Listening on Journal Socket (/dev/log). Aug 08 23:08:26 notyourvolumio systemd[1]: Reached target System Time Synchronized. Aug 08 23:08:26 notyourvolumio kernel: i2c /dev entries driver Aug 08 23:08:26 notyourvolumio systemd-journald[206]: Journal started Aug 08 23:08:26 notyourvolumio systemd-journald[206]: Runtime journal (/run/log/journal/4f62df4b7e374c47e98bbc1f6425ae06) is 3.7M, max 30.0M, 26.2M free. Aug 08 23:08:26 notyourvolumio systemd-modules-load[189]: Inserted module 'i2c_dev' Aug 08 23:08:26 notyourvolumio fake-hwclock[193]: Wed Aug 9 06:08:26 UTC 2023 Aug 08 23:08:26 notyourvolumio systemd[1]: Started Create Static Device Nodes in /dev. Aug 08 23:08:26 notyourvolumio systemd[1]: Starting udev Kernel Device Manager... Aug 08 23:08:26 notyourvolumio systemd[1]: Reached target Local File Systems (Pre). Aug 08 23:08:26 notyourvolumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Aug 08 23:08:26 notyourvolumio systemd[1]: Mounting /tmp... Aug 08 23:08:26 notyourvolumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Aug 08 23:08:26 notyourvolumio systemd[1]: Mounting /var/log... Aug 08 23:08:26 notyourvolumio systemd[1]: Mounting /var/spool/cups... Aug 08 23:08:26 notyourvolumio systemd[1]: Mounted /tmp. Aug 08 23:08:26 notyourvolumio systemd[1]: Mounted /var/log. Aug 08 23:08:26 notyourvolumio systemd[1]: Mounted /var/spool/cups. Aug 08 23:08:26 notyourvolumio systemd-udevd[222]: Network interface NamePolicy= disabled on kernel command line, ignoring. Aug 08 23:08:26 notyourvolumio systemd[1]: Mounting /var/spool/cups/tmp... Aug 08 23:08:26 notyourvolumio systemd[1]: Starting Flush Journal to Persistent Storage... Aug 08 23:08:26 notyourvolumio systemd[1]: Mounted /var/spool/cups/tmp. Aug 08 23:08:26 notyourvolumio systemd[1]: Started udev Coldplug all Devices. Aug 08 23:08:26 notyourvolumio systemd[1]: Starting Helper to synchronize boot up for ifupdown... Aug 08 23:08:26 notyourvolumio systemd[1]: Started udev Kernel Device Manager. Aug 08 23:08:26 notyourvolumio systemd[1]: Starting Show Plymouth Boot Screen... Aug 08 23:08:26 notyourvolumio systemd[1]: Started Helper to synchronize boot up for ifupdown. Aug 08 23:08:26 notyourvolumio systemd-journald[206]: Runtime journal (/run/log/journal/4f62df4b7e374c47e98bbc1f6425ae06) is 3.7M, max 30.0M, 26.2M free. Aug 08 23:08:26 notyourvolumio systemd[1]: Started Flush Journal to Persistent Storage. Aug 08 23:08:26 notyourvolumio systemd[1]: Received SIGRTMIN+20 from PID 240 (plymouthd). Aug 08 23:08:26 notyourvolumio systemd[1]: Started Show Plymouth Boot Screen. Aug 08 23:08:26 notyourvolumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Aug 08 23:08:26 notyourvolumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Aug 08 23:08:26 notyourvolumio systemd[1]: Reached target Paths. Aug 08 23:08:26 notyourvolumio systemd[1]: Reached target Local Encrypted Volumes. Aug 08 23:08:26 notyourvolumio kernel: rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000 Aug 08 23:08:26 notyourvolumio kernel: rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000 Aug 08 23:08:26 notyourvolumio kernel: rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000 Aug 08 23:08:26 notyourvolumio kernel: rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000 Aug 08 23:08:26 notyourvolumio systemd-udevd[245]: Using default interface naming scheme 'v240'. Aug 08 23:08:26 notyourvolumio kernel: mc: Linux media interface: v0.10 Aug 08 23:08:26 notyourvolumio kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Aug 08 23:08:26 notyourvolumio kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Aug 08 23:08:26 notyourvolumio kernel: [vc_sm_connected_init]: start Aug 08 23:08:26 notyourvolumio kernel: [vc_sm_connected_init]: installed successfully Aug 08 23:08:27 notyourvolumio systemd-udevd[237]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 08 23:08:27 notyourvolumio kernel: videodev: Linux video capture interface: v2.00 Aug 08 23:08:27 notyourvolumio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Aug 08 23:08:27 notyourvolumio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Aug 08 23:08:27 notyourvolumio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Aug 08 23:08:27 notyourvolumio kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Aug 08 23:08:27 notyourvolumio kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Aug 08 23:08:27 notyourvolumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Aug 08 23:08:27 notyourvolumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Aug 08 23:08:27 notyourvolumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Aug 08 23:08:27 notyourvolumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Aug 08 23:08:27 notyourvolumio kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Aug 08 23:08:27 notyourvolumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Aug 08 23:08:27 notyourvolumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Aug 08 23:08:27 notyourvolumio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Aug 08 23:08:27 notyourvolumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Aug 08 23:08:27 notyourvolumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Aug 08 23:08:27 notyourvolumio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Aug 08 23:08:27 notyourvolumio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Aug 08 23:08:27 notyourvolumio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Aug 08 23:08:27 notyourvolumio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Aug 08 23:08:27 notyourvolumio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Aug 08 23:08:27 notyourvolumio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Aug 08 23:08:27 notyourvolumio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Aug 08 23:08:27 notyourvolumio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Aug 08 23:08:27 notyourvolumio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Aug 08 23:08:27 notyourvolumio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Aug 08 23:08:27 notyourvolumio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Aug 08 23:08:27 notyourvolumio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Aug 08 23:08:27 notyourvolumio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Aug 08 23:08:27 notyourvolumio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Aug 08 23:08:27 notyourvolumio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Aug 08 23:08:27 notyourvolumio kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Aug 08 23:08:27 notyourvolumio systemd-udevd[245]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 08 23:08:27 notyourvolumio kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Aug 08 23:08:27 notyourvolumio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Aug 08 23:08:27 notyourvolumio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Aug 08 23:08:28 notyourvolumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Aug 08 23:08:28 notyourvolumio kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Aug 08 23:08:28 notyourvolumio kernel: cfg80211: loaded regulatory.db is malformed or signature is missing/invalid Aug 08 23:08:28 notyourvolumio kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Aug 08 23:08:28 notyourvolumio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Aug 08 23:08:28 notyourvolumio kernel: usbcore: registered new interface driver brcmfmac Aug 08 23:08:29 notyourvolumio kernel: snd_usb_audio: unknown parameter 'nrpacks' ignored Aug 08 23:08:29 notyourvolumio kernel: usbcore: registered new interface driver snd-usb-audio Aug 08 23:08:29 notyourvolumio systemd[1]: Found device /dev/mmcblk0p1. Aug 08 23:08:29 notyourvolumio systemd[1]: Condition check resulted in Huge Pages File System being skipped. Aug 08 23:08:29 notyourvolumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Aug 08 23:08:29 notyourvolumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Aug 08 23:08:29 notyourvolumio systemd[1]: Condition check resulted in FUSE Control File System being skipped. Aug 08 23:08:29 notyourvolumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Aug 08 23:08:29 notyourvolumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Aug 08 23:08:29 notyourvolumio systemd[1]: Starting File System Check on /dev/mmcblk0p1... Aug 08 23:08:29 notyourvolumio systemd[1]: Started File System Check Daemon to report status. Aug 08 23:08:29 notyourvolumio systemd-fsck[370]: fsck.fat 4.1 (2017-01-24) Aug 08 23:08:29 notyourvolumio systemd-fsck[370]: /dev/mmcblk0p1: 298 files, 27487/46774 clusters Aug 08 23:08:30 notyourvolumio systemd[1]: Started File System Check on /dev/mmcblk0p1. Aug 08 23:08:30 notyourvolumio systemd[1]: Mounting /boot... Aug 08 23:08:30 notyourvolumio systemd[1]: Mounted /boot. Aug 08 23:08:30 notyourvolumio systemd[1]: Reached target Local File Systems. Aug 08 23:08:30 notyourvolumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Aug 08 23:08:30 notyourvolumio systemd[1]: Starting Create Volatile Files and Directories... Aug 08 23:08:30 notyourvolumio systemd[1]: Starting Preprocess NFS configuration... Aug 08 23:08:30 notyourvolumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Aug 08 23:08:30 notyourvolumio systemd[1]: Starting Raise network interfaces... Aug 08 23:08:30 notyourvolumio systemd[1]: nfs-config.service: Succeeded. Aug 08 23:08:30 notyourvolumio systemd[1]: Started Preprocess NFS configuration. Aug 08 23:08:30 notyourvolumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Aug 08 23:08:30 notyourvolumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Aug 08 23:08:30 notyourvolumio systemd[1]: Reached target NFS client services. Aug 08 23:08:30 notyourvolumio ifup[378]: command failed: No such device (-19) Aug 08 23:08:30 notyourvolumio systemd[1]: Started Create Volatile Files and Directories. Aug 08 23:08:30 notyourvolumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Aug 08 23:08:30 notyourvolumio systemd[1]: Starting RPC bind portmap service... Aug 08 23:08:30 notyourvolumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Aug 08 23:08:30 notyourvolumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Aug 08 23:08:30 notyourvolumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Aug 08 23:08:30 notyourvolumio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Aug 08 23:08:30 notyourvolumio 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 08 23:08:30 notyourvolumio systemd[1]: Found device /sys/subsystem/net/devices/eth0. Aug 08 23:08:30 notyourvolumio systemd[1]: Starting Load/Save RF Kill Switch Status... Aug 08 23:08:30 notyourvolumio systemd-udevd[254]: Using default interface naming scheme 'v240'. Aug 08 23:08:30 notyourvolumio systemd-udevd[254]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 08 23:08:30 notyourvolumio systemd[1]: Started ifup for eth0. Aug 08 23:08:31 notyourvolumio systemd[1]: Started Load/Save RF Kill Switch Status. Aug 08 23:08:31 notyourvolumio systemd[1]: Started RPC bind portmap service. Aug 08 23:08:31 notyourvolumio systemd[1]: Reached target RPC Port Mapper. Aug 08 23:08:31 notyourvolumio systemd[1]: Reached target Remote File Systems (Pre). Aug 08 23:08:31 notyourvolumio systemd[1]: Reached target Remote File Systems. Aug 08 23:08:31 notyourvolumio kernel: 8021q: 802.1Q VLAN Support v1.8 Aug 08 23:08:31 notyourvolumio systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Aug 08 23:08:31 notyourvolumio systemd[1]: Started ifup for wlan0. Aug 08 23:08:31 notyourvolumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 08 23:08:31 notyourvolumio systemd[1]: plymouth-read-write.service: Succeeded. Aug 08 23:08:31 notyourvolumio kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Aug 08 23:08:31 notyourvolumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Aug 08 23:08:31 notyourvolumio sh[412]: eth0: waiting for carrier Aug 08 23:08:31 notyourvolumio dhcpcd[440]: eth0: waiting for carrier Aug 08 23:08:31 notyourvolumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Aug 08 23:08:31 notyourvolumio systemd[1]: Received SIGRTMIN+20 from PID 240 (plymouthd). Aug 08 23:08:31 notyourvolumio systemd[1]: Reached target System Initialization. Aug 08 23:08:31 notyourvolumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Aug 08 23:08:31 notyourvolumio systemd[1]: Listening on D-Bus System Message Bus Socket. Aug 08 23:08:31 notyourvolumio systemd[1]: Started Daily apt download activities. Aug 08 23:08:31 notyourvolumio systemd[1]: Listening on mpd.socket. Aug 08 23:08:31 notyourvolumio systemd[1]: Listening on triggerhappy.socket. Aug 08 23:08:31 notyourvolumio systemd[1]: Reached target Sockets. Aug 08 23:08:31 notyourvolumio systemd[1]: Reached target Basic System. Aug 08 23:08:31 notyourvolumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Aug 08 23:08:32 notyourvolumio systemd[1]: Started Volumio Iptables Module. Aug 08 23:08:32 notyourvolumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Aug 08 23:08:32 notyourvolumio systemd[1]: Starting Configure Bluetooth Modems connected by UART... Aug 08 23:08:32 notyourvolumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Aug 08 23:08:32 notyourvolumio systemd[1]: Started D-Bus System Message Bus. Aug 08 23:08:32 notyourvolumio systemd[1]: Starting WPA supplicant... Aug 08 23:08:32 notyourvolumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Aug 08 23:08:32 notyourvolumio systemd[1]: Starting dhcpcd on all interfaces... Aug 08 23:08:32 notyourvolumio systemd[1]: Started Manage Sound Card State (restore and store). Aug 08 23:08:32 notyourvolumio alsactl[527]: alsactl 1.1.8 daemon started Aug 08 23:08:32 notyourvolumio systemd[1]: Starting Save/Restore Sound Card State... Aug 08 23:08:32 notyourvolumio dhcpcd[526]: Not running dhcpcd because /etc/network/interfaces Aug 08 23:08:32 notyourvolumio dhcpcd[526]: defines some interfaces that will use a Aug 08 23:08:32 notyourvolumio dhcpcd[526]: DHCP client or static address Aug 08 23:08:32 notyourvolumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 08 23:08:32 notyourvolumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Aug 08 23:08:32 notyourvolumio systemd[1]: Starting Login Service... Aug 08 23:08:32 notyourvolumio systemd[1]: Started Daily apt upgrade and clean activities. Aug 08 23:08:32 notyourvolumio systemd[1]: Started Daily Cleanup of Temporary Directories. Aug 08 23:08:32 notyourvolumio systemd[1]: Reached target Timers. Aug 08 23:08:32 notyourvolumio systemd[1]: Starting Wireless Services... Aug 08 23:08:32 notyourvolumio systemd[1]: Started volumio-remote-updater.service. Aug 08 23:08:32 notyourvolumio systemd[1]: Starting triggerhappy global hotkey daemon... Aug 08 23:08:32 notyourvolumio systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Aug 08 23:08:32 notyourvolumio systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Aug 08 23:08:32 notyourvolumio systemd[1]: Failed to start dhcpcd on all interfaces. Aug 08 23:08:32 notyourvolumio systemd[1]: Started Save/Restore Sound Card State. Aug 08 23:08:32 notyourvolumio avahi-daemon[521]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Aug 08 23:08:32 notyourvolumio avahi-daemon[521]: Successfully dropped root privileges. Aug 08 23:08:32 notyourvolumio avahi-daemon[521]: avahi-daemon 0.7 starting up. Aug 08 23:08:32 notyourvolumio thd[539]: Unable to parse trigger line: Aug 08 23:08:32 notyourvolumio thd[539]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Aug 08 23:08:32 notyourvolumio thd[539]: Unable to parse trigger line: Aug 08 23:08:32 notyourvolumio thd[539]: Found socket passed from systemd Aug 08 23:08:32 notyourvolumio systemd[1]: Started triggerhappy global hotkey daemon. Aug 08 23:08:32 notyourvolumio sh[448]: wlan0=wlan0 Aug 08 23:08:32 notyourvolumio systemd[1]: Started Raise network interfaces. Aug 08 23:08:32 notyourvolumio systemd[1]: Reached target Sound Card. Aug 08 23:08:32 notyourvolumio systemd-logind[534]: New seat seat0. Aug 08 23:08:32 notyourvolumio kernel: uart-pl011 fe201000.serial: no DMA platform data Aug 08 23:08:32 notyourvolumio systemd[1]: Started Login Service. Aug 08 23:08:32 notyourvolumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Aug 08 23:08:32 notyourvolumio avahi-daemon[521]: Successfully called chroot(). Aug 08 23:08:32 notyourvolumio avahi-daemon[521]: Successfully dropped remaining capabilities. Aug 08 23:08:32 notyourvolumio avahi-daemon[521]: Loading service file /services/volumio.service. Aug 08 23:08:33 notyourvolumio avahi-daemon[521]: Network interface enumeration completed. Aug 08 23:08:33 notyourvolumio avahi-daemon[521]: Server startup complete. Host name is notyourvolumio.local. Local service cookie is 2893401920. Aug 08 23:08:33 notyourvolumio avahi-daemon[521]: Service "NotYourVolumio" (/services/volumio.service) successfully established. Aug 08 23:08:33 notyourvolumio loadcpufreq[533]: Loading cpufreq kernel modules...done (none). Aug 08 23:08:33 notyourvolumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Aug 08 23:08:33 notyourvolumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Aug 08 23:08:33 notyourvolumio cpufrequtils[583]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Aug 08 23:08:33 notyourvolumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Aug 08 23:08:33 notyourvolumio volumio-remote-updater[537]: Error: No active session Aug 08 23:08:33 notyourvolumio volumio-remote-updater[537]: [2023-08-08 23:08:33] [info] asio async_connect error: system:111 (Connection refused) Aug 08 23:08:33 notyourvolumio volumio-remote-updater[537]: [2023-08-08 23:08:33] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 08 23:08:33 notyourvolumio volumio-remote-updater[537]: [2023-08-08 23:08:33] [error] handle_connect error: Underlying Transport Error Aug 08 23:08:33 notyourvolumio systemd-udevd[252]: Process '/bin/bash -c '/usr/local/bin/volumio scanaudioinputs'' failed with exit code 7. Aug 08 23:08:33 notyourvolumio systemd-udevd[230]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Aug 08 23:08:33 notyourvolumio systemd[1]: Started WPA supplicant. Aug 08 23:08:33 notyourvolumio systemd[1]: Reached target Network. Aug 08 23:08:33 notyourvolumio systemd[1]: Reached target Network is Online. Aug 08 23:08:33 notyourvolumio wpa_supplicant[524]: Successfully initialized wpa_supplicant Aug 08 23:08:33 notyourvolumio systemd[1]: Starting LSB: Brings up/down network automatically... Aug 08 23:08:33 notyourvolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 08 23:08:33 notyourvolumio systemd[1]: Starting /etc/rc.local Compatibility... Aug 08 23:08:33 notyourvolumio systemd[1]: Starting Network Time Service... Aug 08 23:08:33 notyourvolumio systemd[1]: Starting Music Player Daemon... Aug 08 23:08:33 notyourvolumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Aug 08 23:08:34 notyourvolumio systemd[1]: Starting Permit User Sessions... Aug 08 23:08:34 notyourvolumio systemd[1]: Starting Samba NMB Daemon... Aug 08 23:08:34 notyourvolumio systemd[1]: Started /etc/rc.local Compatibility. Aug 08 23:08:34 notyourvolumio volumio[532]: Could not open config: /tmp/upmpdcli.conf Aug 08 23:08:34 notyourvolumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 08 23:08:34 notyourvolumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 08 23:08:34 notyourvolumio systemd[1]: iptables.service: Succeeded. Aug 08 23:08:34 notyourvolumio haveged[393]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Aug 08 23:08:34 notyourvolumio haveged[393]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Aug 08 23:08:34 notyourvolumio haveged[393]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.9975 Aug 08 23:08:34 notyourvolumio haveged[393]: haveged: fills: 0, generated: 0 Aug 08 23:08:34 notyourvolumio systemd[1]: Started Permit User Sessions. Aug 08 23:08:34 notyourvolumio kernel: random: crng init done Aug 08 23:08:34 notyourvolumio kernel: random: 7 urandom warning(s) missed due to ratelimiting Aug 08 23:08:34 notyourvolumio systemd[1]: Starting Hold until boot process finishes up... Aug 08 23:08:34 notyourvolumio systemd[1]: Starting Terminate Plymouth Boot Screen... Aug 08 23:08:34 notyourvolumio ifplugd(eth0)[634]: ifplugd 0.28 initializing. Aug 08 23:08:34 notyourvolumio ifplugd(eth0)[634]: Using interface eth0/D8:3A:DD:20:60:7E with driver (version: 5.10.92-v7l+) Aug 08 23:08:34 notyourvolumio ifplugd(eth0)[634]: Using detection mode: SIOCETHTOOL Aug 08 23:08:34 notyourvolumio ifplugd(eth0)[634]: Initialization complete, link beat not detected. Aug 08 23:08:34 notyourvolumio systemd[1]: Received SIGRTMIN+21 from PID 240 (plymouthd). Aug 08 23:08:34 notyourvolumio systemd[1]: Received SIGRTMIN+21 from PID 240 (plymouthd). Aug 08 23:08:34 notyourvolumio systemd[1]: plymouth-quit-wait.service: Succeeded. Aug 08 23:08:34 notyourvolumio systemd[1]: Started Hold until boot process finishes up. Aug 08 23:08:34 notyourvolumio systemd[1]: plymouth-quit.service: Succeeded. Aug 08 23:08:34 notyourvolumio systemd[1]: Started Terminate Plymouth Boot Screen. Aug 08 23:08:34 notyourvolumio systemd[1]: Started Getty on tty1. Aug 08 23:08:34 notyourvolumio systemd[1]: Reached target Login Prompts. Aug 08 23:08:34 notyourvolumio systemd[1]: plymouth-start.service: Succeeded. Aug 08 23:08:34 notyourvolumio ifplugd[604]: Network Interface Plugging Daemon...start eth0...done. Aug 08 23:08:34 notyourvolumio systemd[1]: Started LSB: Brings up/down network automatically. Aug 08 23:08:34 notyourvolumio ntpd[609]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 08 23:08:34 notyourvolumio systemd[1]: Started Network Time Service. Aug 08 23:08:34 notyourvolumio ntpd[609]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Aug 08 23:08:34 notyourvolumio ntpd[640]: proto: precision = 0.778 usec (-20) Aug 08 23:08:34 notyourvolumio ntpd[640]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 08 23:08:34 notyourvolumio ntpd[640]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 08 23:08:34 notyourvolumio ntpd[640]: Listen and drop on 0 v6wildcard [::]:123 Aug 08 23:08:34 notyourvolumio ntpd[640]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 08 23:08:34 notyourvolumio ntpd[640]: Listen normally on 2 lo 127.0.0.1:123 Aug 08 23:08:34 notyourvolumio ntpd[640]: Listening on routing socket on fd #19 for interface updates Aug 08 23:08:34 notyourvolumio ntpd[640]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 08 23:08:34 notyourvolumio ntpd[640]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 08 23:08:35 notyourvolumio ntpd[640]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 08 23:08:35 notyourvolumio nmbd[619]: [2023/08/08 23:08:35.453255, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Aug 08 23:08:35 notyourvolumio nmbd[619]: started asyncdns process 648 Aug 08 23:08:35 notyourvolumio nmbd[619]: [2023/08/08 23:08:35.455850, 0] ../lib/util/become_daemon.c:149(daemon_status) Aug 08 23:08:35 notyourvolumio nmbd[619]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Aug 08 23:08:35 notyourvolumio nmbd[619]: [2023/08/08 23:08:35.456016, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Aug 08 23:08:35 notyourvolumio nmbd[619]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Aug 08 23:08:35 notyourvolumio wireless.js[536]: Cleaning previous... Aug 08 23:08:36 notyourvolumio systemd[1]: systemd-rfkill.service: Succeeded. Aug 08 23:08:36 notyourvolumio sudo[656]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 08 23:08:36 notyourvolumio sudo[656]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:36 notyourvolumio sudo[656]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:36 notyourvolumio sudo[658]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 08 23:08:36 notyourvolumio sudo[658]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:36 notyourvolumio ntpd[640]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 08 23:08:36 notyourvolumio mpd[637]: Aug 08 23:08 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 08 23:08:36 notyourvolumio mpd[637]: Aug 08 23:08 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 08 23:08:36 notyourvolumio mpd[637]: Aug 08 23:08 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 08 23:08:36 notyourvolumio systemd[1]: Started Music Player Daemon. Aug 08 23:08:36 notyourvolumio sudo[658]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:36 notyourvolumio wireless.js[536]: Stopped aP Aug 08 23:08:36 notyourvolumio sudo[667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 08 23:08:36 notyourvolumio sudo[667]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:36 notyourvolumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 08 23:08:36 notyourvolumio sudo[667]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:36 notyourvolumio sudo[669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Aug 08 23:08:36 notyourvolumio sudo[669]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:36 notyourvolumio sudo[669]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:36 notyourvolumio sudo[676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 08 23:08:36 notyourvolumio sudo[676]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:36 notyourvolumio sudo[676]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:36 notyourvolumio sudo[678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Aug 08 23:08:36 notyourvolumio sudo[678]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:37 notyourvolumio ntpd[640]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 08 23:08:38 notyourvolumio ntpd[640]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 08 23:08:38 notyourvolumio volumio-remote-updater[537]: [2023-08-08 23:08:38] [info] asio async_connect error: system:111 (Connection refused) Aug 08 23:08:38 notyourvolumio volumio-remote-updater[537]: [2023-08-08 23:08:38] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 08 23:08:38 notyourvolumio volumio-remote-updater[537]: [2023-08-08 23:08:38] [error] handle_connect error: Underlying Transport Error Aug 08 23:08:39 notyourvolumio sudo[678]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:39 notyourvolumio wireless.js[536]: Start wireless flow Aug 08 23:08:39 notyourvolumio wireless.js[536]: Stopped hotspot (if there).. Aug 08 23:08:39 notyourvolumio sudo[683]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 08 23:08:39 notyourvolumio sudo[683]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:39 notyourvolumio sudo[683]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:39 notyourvolumio sudo[685]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 08 23:08:39 notyourvolumio sudo[685]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:39 notyourvolumio kernel: Bluetooth: Core ver 2.22 Aug 08 23:08:39 notyourvolumio kernel: NET: Registered protocol family 31 Aug 08 23:08:39 notyourvolumio kernel: Bluetooth: HCI device and connection manager initialized Aug 08 23:08:39 notyourvolumio kernel: Bluetooth: HCI socket layer initialized Aug 08 23:08:39 notyourvolumio kernel: Bluetooth: L2CAP socket layer initialized Aug 08 23:08:39 notyourvolumio kernel: Bluetooth: SCO socket layer initialized Aug 08 23:08:39 notyourvolumio kernel: Bluetooth: HCI UART driver ver 2.3 Aug 08 23:08:39 notyourvolumio kernel: Bluetooth: HCI UART protocol H4 registered Aug 08 23:08:39 notyourvolumio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Aug 08 23:08:39 notyourvolumio kernel: Bluetooth: HCI UART protocol Broadcom registered Aug 08 23:08:39 notyourvolumio btuart[520]: bcm43xx_init Aug 08 23:08:39 notyourvolumio btuart[520]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd Aug 08 23:08:39 notyourvolumio btuart[520]: Set Controller UART speed to 3000000 bit/s Aug 08 23:08:39 notyourvolumio btuart[520]: Device setup complete Aug 08 23:08:39 notyourvolumio systemd[1]: Starting Load/Save RF Kill Switch Status... Aug 08 23:08:39 notyourvolumio systemd[1]: Started Configure Bluetooth Modems connected by UART. Aug 08 23:08:39 notyourvolumio systemd[1]: Started Load/Save RF Kill Switch Status. Aug 08 23:08:39 notyourvolumio systemd[1]: Created slice system-bthelper.slice. Aug 08 23:08:39 notyourvolumio systemd[1]: Starting Raspberry Pi bluetooth helper... Aug 08 23:08:39 notyourvolumio bthelper[697]: Raspberry Pi BDADDR already set Aug 08 23:08:39 notyourvolumio systemd[1]: Started Raspberry Pi bluetooth helper. Aug 08 23:08:39 notyourvolumio systemd[1]: Starting Bluetooth service... Aug 08 23:08:39 notyourvolumio bluetoothd[707]: Bluetooth daemon 5.50 Aug 08 23:08:39 notyourvolumio bluetoothd[707]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Aug 08 23:08:39 notyourvolumio systemd[1]: Started Bluetooth service. Aug 08 23:08:39 notyourvolumio systemd[1]: Reached target Bluetooth. Aug 08 23:08:39 notyourvolumio bluetoothd[707]: Starting SDP server Aug 08 23:08:39 notyourvolumio bluetoothd[707]: Excluding (cli) sap Aug 08 23:08:39 notyourvolumio bluetoothd[707]: Bluetooth management interface 1.18 initialized Aug 08 23:08:39 notyourvolumio dbus-daemon[522]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=707 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Aug 08 23:08:39 notyourvolumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Aug 08 23:08:39 notyourvolumio kernel: Bluetooth: BNEP filters: protocol multicast Aug 08 23:08:39 notyourvolumio kernel: Bluetooth: BNEP socket layer initialized Aug 08 23:08:40 notyourvolumio systemd[1]: Starting Hostname Service... Aug 08 23:08:40 notyourvolumio bluetoothd[707]: Failed to set privacy: Rejected (0x0b) Aug 08 23:08:40 notyourvolumio sudo[685]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:40 notyourvolumio wireless.js[536]: DHCP IP Aug 08 23:08:40 notyourvolumio wireless.js[536]: Start ap Aug 08 23:08:40 notyourvolumio wpa_supplicant[710]: Successfully initialized wpa_supplicant Aug 08 23:08:40 notyourvolumio sudo[711]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Aug 08 23:08:40 notyourvolumio sudo[711]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:40 notyourvolumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 08 23:08:40 notyourvolumio dhcpcd[712]: dev: loaded udev Aug 08 23:08:40 notyourvolumio dbus-daemon[522]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 08 23:08:40 notyourvolumio systemd[1]: Started Hostname Service. Aug 08 23:08:40 notyourvolumio dhcpcd-run-hooks[730]: wlan0: starting wpa_supplicant Aug 08 23:08:40 notyourvolumio dhcpcd-run-hooks[735]: wlan0: failed to start wpa_supplicant Aug 08 23:08:40 notyourvolumio dhcpcd-run-hooks[736]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Aug 08 23:08:40 notyourvolumio dhcpcd[712]: wlan0: connected to Access Point `' Aug 08 23:08:40 notyourvolumio dhcpcd[712]: no interfaces have a carrier Aug 08 23:08:40 notyourvolumio dhcpcd[712]: forked to background, child pid 746 Aug 08 23:08:40 notyourvolumio dhcpcd[746]: eth0: waiting for carrier Aug 08 23:08:40 notyourvolumio dhcpcd[746]: wlan0: waiting for carrier Aug 08 23:08:40 notyourvolumio sudo[711]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:41 notyourvolumio wireless.js[536]: trying... Aug 08 23:08:41 notyourvolumio sudo[748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 08 23:08:41 notyourvolumio sudo[748]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:41 notyourvolumio sudo[748]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:42 notyourvolumio wireless.js[536]: trying... Aug 08 23:08:42 notyourvolumio sudo[751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 08 23:08:42 notyourvolumio sudo[751]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:42 notyourvolumio sudo[751]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:43 notyourvolumio wpa_supplicant[734]: wlan0: Trying to associate with SSID 'NotYourWifi_5G_Plus' Aug 08 23:08:43 notyourvolumio wireless.js[536]: trying... Aug 08 23:08:43 notyourvolumio sudo[754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 08 23:08:43 notyourvolumio sudo[754]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:43 notyourvolumio sudo[754]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:44 notyourvolumio wireless.js[536]: trying... Aug 08 23:08:44 notyourvolumio sudo[757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 08 23:08:44 notyourvolumio sudo[757]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:44 notyourvolumio sudo[757]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:44 notyourvolumio systemd[1]: systemd-rfkill.service: Succeeded. Aug 08 23:08:44 notyourvolumio bthelper[697]: Changing power off succeeded Aug 08 23:08:45 notyourvolumio bthelper[697]: [CHG] Controller D8:3A:DD:20:60:80 Class: 0x0000041c Aug 08 23:08:45 notyourvolumio bthelper[697]: Changing power on succeeded Aug 08 23:08:45 notyourvolumio wireless.js[536]: trying... Aug 08 23:08:45 notyourvolumio sudo[767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 08 23:08:45 notyourvolumio sudo[767]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:45 notyourvolumio sudo[767]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:46 notyourvolumio dhcpcd[746]: wlan0: carrier acquired Aug 08 23:08:46 notyourvolumio dhcpcd[746]: wlan0: connected to Access Point `NotYourWifi_5G_Plus' Aug 08 23:08:46 notyourvolumio wpa_supplicant[734]: wlan0: Associated with 9c:9d:7e:7f:ce:48 Aug 08 23:08:46 notyourvolumio wpa_supplicant[734]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:9d:7e:7f:ce:48 completed [id=0 id_str=] Aug 08 23:08:46 notyourvolumio wpa_supplicant[734]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 08 23:08:46 notyourvolumio dhcpcd[746]: DUID 00:01:00:01:2b:b8:6d:2e:d8:3a:dd:20:60:7f Aug 08 23:08:46 notyourvolumio dhcpcd[746]: wlan0: IAID dd:20:60:7f Aug 08 23:08:46 notyourvolumio dhcpcd[746]: wlan0: adding address fe80::71f5:5eb9:5cd9:1421 Aug 08 23:08:46 notyourvolumio dhcpcd[746]: ipv6_addaddr1: Permission denied Aug 08 23:08:46 notyourvolumio dhcpcd[746]: wlan0: carrier lost Aug 08 23:08:46 notyourvolumio dhcpcd[746]: wlan0: carrier acquired Aug 08 23:08:46 notyourvolumio wpa_supplicant[734]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Aug 08 23:08:46 notyourvolumio dhcpcd[746]: wlan0: IAID dd:20:60:7f Aug 08 23:08:46 notyourvolumio wireless.js[536]: trying... Aug 08 23:08:46 notyourvolumio sudo[783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 08 23:08:46 notyourvolumio sudo[783]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:46 notyourvolumio sudo[783]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:46 notyourvolumio volumio-remote-updater[537]: [2023-08-08 23:08:46] [info] asio async_connect error: system:111 (Connection refused) Aug 08 23:08:46 notyourvolumio volumio-remote-updater[537]: [2023-08-08 23:08:46] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 08 23:08:46 notyourvolumio volumio-remote-updater[537]: [2023-08-08 23:08:46] [error] handle_connect error: Underlying Transport Error Aug 08 23:08:46 notyourvolumio dhcpcd[746]: wlan0: soliciting an IPv6 router Aug 08 23:08:46 notyourvolumio dhcpcd[746]: wlan0: rebinding lease of 192.168.31.107 Aug 08 23:08:47 notyourvolumio wireless.js[536]: trying... Aug 08 23:08:47 notyourvolumio sudo[786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 08 23:08:47 notyourvolumio sudo[786]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:47 notyourvolumio sudo[786]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:47 notyourvolumio dhcpcd[746]: wlan0: probing address 192.168.31.107/24 Aug 08 23:08:48 notyourvolumio kernel: cam-dummy-reg: disabling Aug 08 23:08:48 notyourvolumio kernel: cam1-reg: disabling Aug 08 23:08:48 notyourvolumio wireless.js[536]: trying... Aug 08 23:08:48 notyourvolumio sudo[789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 08 23:08:48 notyourvolumio sudo[789]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:48 notyourvolumio sudo[789]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:49 notyourvolumio wireless.js[536]: trying... Aug 08 23:08:49 notyourvolumio sudo[792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 08 23:08:49 notyourvolumio sudo[792]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:49 notyourvolumio sudo[792]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:50 notyourvolumio wireless.js[536]: trying... Aug 08 23:08:50 notyourvolumio sudo[795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 08 23:08:50 notyourvolumio sudo[795]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:50 notyourvolumio sudo[795]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:51 notyourvolumio wireless.js[536]: trying... Aug 08 23:08:51 notyourvolumio sudo[798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 08 23:08:51 notyourvolumio sudo[798]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:51 notyourvolumio sudo[798]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:51 notyourvolumio dhcpcd[746]: wlan0: leased 192.168.31.107 for 43200 seconds Aug 08 23:08:51 notyourvolumio avahi-daemon[521]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.107. Aug 08 23:08:51 notyourvolumio avahi-daemon[521]: New relevant interface wlan0.IPv4 for mDNS. Aug 08 23:08:51 notyourvolumio avahi-daemon[521]: Registering new address record for 192.168.31.107 on wlan0.IPv4. Aug 08 23:08:51 notyourvolumio dhcpcd[746]: wlan0: adding route to 192.168.31.0/24 Aug 08 23:08:51 notyourvolumio dhcpcd[746]: wlan0: adding default route via 192.168.31.1 Aug 08 23:08:52 notyourvolumio wireless.js[536]: trying... Aug 08 23:08:52 notyourvolumio sudo[821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 08 23:08:52 notyourvolumio sudo[821]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:52 notyourvolumio sudo[821]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:52 notyourvolumio wireless.js[536]: Connected to: ----NotYourWifi_5G_Plus Aug 08 23:08:52 notyourvolumio wireless.js[536]: ---- Aug 08 23:08:52 notyourvolumio sudo[824]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 08 23:08:52 notyourvolumio sudo[824]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:52 notyourvolumio sudo[824]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:52 notyourvolumio wireless.js[536]: ... joined AP, wlan0 IPv4 is 192.168.31.107, ipV6 is undefined Aug 08 23:08:52 notyourvolumio wireless.js[536]: It's done! AP Aug 08 23:08:52 notyourvolumio systemd[1]: Started Wireless Services. Aug 08 23:08:52 notyourvolumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 08 23:08:52 notyourvolumio systemd[1]: Started Volumio Backend Module. Aug 08 23:08:53 notyourvolumio ntpd[640]: Listen normally on 3 wlan0 192.168.31.107:123 Aug 08 23:08:53 notyourvolumio ntpd[640]: new interface(s) found: waking up resolver Aug 08 23:08:54 notyourvolumio volumio[829]: info: ------------------------------------------- Aug 08 23:08:54 notyourvolumio volumio[829]: info: ----- Volumio3 ---- Aug 08 23:08:54 notyourvolumio volumio[829]: info: ------------------------------------------- Aug 08 23:08:54 notyourvolumio volumio[829]: info: ----- System startup ---- Aug 08 23:08:54 notyourvolumio volumio[829]: info: ------------------------------------------- Aug 08 23:08:54 notyourvolumio volumio[829]: info: MYVOLUMIO Environment detected Aug 08 23:08:54 notyourvolumio volumio[829]: info: Plugin folders cleanup Aug 08 23:08:54 notyourvolumio volumio[829]: info: Scanning into folder /volumio/app/plugins/ Aug 08 23:08:54 notyourvolumio volumio[829]: info: Scanning category audio_interface Aug 08 23:08:54 notyourvolumio volumio[829]: info: Scanning category miscellanea Aug 08 23:08:54 notyourvolumio volumio[829]: info: Scanning category music_service Aug 08 23:08:54 notyourvolumio volumio[829]: info: Scanning category plugins.json Aug 08 23:08:54 notyourvolumio volumio[829]: info: Scanning category system_controller Aug 08 23:08:54 notyourvolumio volumio[829]: info: Scanning category user_interface Aug 08 23:08:54 notyourvolumio volumio[829]: info: Scanning into folder /data/plugins/ Aug 08 23:08:54 notyourvolumio volumio[829]: info: Scanning category music_service Aug 08 23:08:54 notyourvolumio volumio[829]: info: Plugin folders cleanup completed Aug 08 23:08:54 notyourvolumio volumio[829]: info: ------------------------------------------- Aug 08 23:08:54 notyourvolumio volumio[829]: info: ----- Core plugins startup ---- Aug 08 23:08:54 notyourvolumio volumio[829]: info: ------------------------------------------- Aug 08 23:08:54 notyourvolumio volumio[829]: info: Loading plugins from folder /volumio/app/plugins/ Aug 08 23:08:54 notyourvolumio volumio[829]: info: Adding plugin upnp to MyMusic Plugins Aug 08 23:08:54 notyourvolumio volumio[829]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 08 23:08:54 notyourvolumio volumio[829]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 08 23:08:54 notyourvolumio volumio[829]: info: Loading plugins from folder /data/plugins/ Aug 08 23:08:54 notyourvolumio volumio[829]: info: Loading plugin "system"... Aug 08 23:08:54 notyourvolumio volumio[829]: info: Loading plugin "appearance"... Aug 08 23:08:55 notyourvolumio systemd[1]: Started Samba NMB Daemon. Aug 08 23:08:55 notyourvolumio systemd[1]: Starting Samba Winbind Daemon... Aug 08 23:08:55 notyourvolumio nmbd[619]: [2023/08/08 23:08:55.470487, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 08 23:08:55 notyourvolumio nmbd[619]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Aug 08 23:08:55 notyourvolumio winbindd[846]: [2023/08/08 23:08:55.678420, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Aug 08 23:08:55 notyourvolumio winbindd[846]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 08 23:08:55 notyourvolumio winbindd[846]: [2023/08/08 23:08:55.697839, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 08 23:08:55 notyourvolumio winbindd[846]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Aug 08 23:08:55 notyourvolumio systemd[1]: Started Samba Winbind Daemon. Aug 08 23:08:55 notyourvolumio systemd[1]: Starting Samba SMB Daemon... Aug 08 23:08:55 notyourvolumio volumio[829]: info: Loading plugin "network"... Aug 08 23:08:55 notyourvolumio volumio[829]: info: Refreshing Cached IP Addresses Aug 08 23:08:56 notyourvolumio sudo[854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 08 23:08:56 notyourvolumio sudo[854]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:56 notyourvolumio sudo[854]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:56 notyourvolumio sudo[856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 08 23:08:56 notyourvolumio sudo[856]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:56 notyourvolumio volumio[829]: info: Loading plugin "services"... Aug 08 23:08:56 notyourvolumio sudo[856]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:56 notyourvolumio volumio[829]: info: Loading plugin "alsa_controller"... Aug 08 23:08:56 notyourvolumio sudo[862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 08 23:08:56 notyourvolumio sudo[862]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:56 notyourvolumio smbd[851]: [2023/08/08 23:08:56.115253, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 08 23:08:56 notyourvolumio smbd[851]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Aug 08 23:08:56 notyourvolumio systemd[1]: Started Samba SMB Daemon. Aug 08 23:08:56 notyourvolumio systemd[1]: Reached target Multi-User System. Aug 08 23:08:56 notyourvolumio systemd[1]: Reached target Graphical Interface. Aug 08 23:08:56 notyourvolumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 08 23:08:56 notyourvolumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 08 23:08:56 notyourvolumio systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 08 23:08:56 notyourvolumio systemd[1]: Startup finished in 9.379s (kernel) + 31.735s (userspace) = 41.114s. Aug 08 23:08:56 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 08 23:08:56 notyourvolumio volumio[829]: info: Loading plugin "wizard"... Aug 08 23:08:56 notyourvolumio volumio[829]: info: Loading plugin "volumio_command_line_client"... Aug 08 23:08:56 notyourvolumio volumio[829]: info: Loading plugin "upnp"... Aug 08 23:08:56 notyourvolumio volumio[829]: info: [1691561336200] Starting Upmpd Daemon Aug 08 23:08:56 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 08 23:08:56 notyourvolumio volumio[829]: info: Loading plugin "my_music"... Aug 08 23:08:56 notyourvolumio volumio[829]: info: Loading plugin "mpd"... Aug 08 23:08:56 notyourvolumio volumio-remote-updater[537]: [2023-08-08 23:08:56] [connect] Successful connection Aug 08 23:08:56 notyourvolumio volumio[829]: info: Creating MPD Configuration file Aug 08 23:08:56 notyourvolumio volumio[829]: info: Loading plugin "upnp_browser"... Aug 08 23:08:56 notyourvolumio sudo[874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 08 23:08:56 notyourvolumio sudo[874]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:56 notyourvolumio sudo[874]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:56 notyourvolumio sudo[876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 08 23:08:56 notyourvolumio sudo[876]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:56 notyourvolumio systemd[1]: Stopping Music Player Daemon... Aug 08 23:08:56 notyourvolumio systemd[1]: mpd.service: Succeeded. Aug 08 23:08:56 notyourvolumio systemd[1]: Stopped Music Player Daemon. Aug 08 23:08:56 notyourvolumio systemd[1]: Starting Music Player Daemon... Aug 08 23:08:56 notyourvolumio volumio[829]: info: Loading plugin "networkfs"... Aug 08 23:08:57 notyourvolumio volumio[829]: info: Starting Udev Watcher for removable devices Aug 08 23:08:57 notyourvolumio sudo[884]: 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.31.70/NotYourNas\music /mnt/NAS/NAS Aug 08 23:08:57 notyourvolumio sudo[884]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:57 notyourvolumio kernel: FS-Cache: Netfs 'cifs' registered for caching Aug 08 23:08:57 notyourvolumio kernel: Key type cifs.spnego registered Aug 08 23:08:57 notyourvolumio kernel: Key type cifs.idmap registered Aug 08 23:08:57 notyourvolumio kernel: CIFS: Attempting to mount //192.168.31.70/NotYourNas\music Aug 08 23:08:57 notyourvolumio 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 08 23:08:57 notyourvolumio mpd[881]: Aug 08 23:08 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 08 23:08:57 notyourvolumio mpd[881]: Aug 08 23:08 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 08 23:08:57 notyourvolumio mpd[881]: Aug 08 23:08 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 08 23:08:57 notyourvolumio systemd[1]: Started Music Player Daemon. Aug 08 23:08:57 notyourvolumio sudo[876]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:57 notyourvolumio volumio[829]: info: Ignoring mount for partition: boot Aug 08 23:08:57 notyourvolumio volumio[829]: info: Ignoring mount for partition: volumio Aug 08 23:08:57 notyourvolumio volumio[829]: info: Ignoring mount for partition: volumio_data Aug 08 23:08:57 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 08 23:08:57 notyourvolumio volumio[829]: info: Loading plugin "alarm-clock"... Aug 08 23:08:57 notyourvolumio sudo[862]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:57 notyourvolumio volumio[829]: info: Loading plugin "airplay_emulation"... Aug 08 23:08:57 notyourvolumio sudo[884]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:57 notyourvolumio volumio[829]: info: Starting Shairport Sync Aug 08 23:08:57 notyourvolumio volumio[829]: info: Loading plugin "last_100"... Aug 08 23:08:57 notyourvolumio volumio[829]: info: Loading plugin "webradio"... Aug 08 23:08:57 notyourvolumio volumio[829]: info: Loading plugin "i2s_dacs"... Aug 08 23:08:57 notyourvolumio volumio[829]: info: I2S DAC not set, start Auto-detection Aug 08 23:08:57 notyourvolumio volumio[829]: info: Loading plugin "volumiodiscovery"... Aug 08 23:08:57 notyourvolumio volumio[829]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 08 23:08:57 notyourvolumio volumio[829]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 08 23:08:57 notyourvolumio volumio[829]: *** WARNING *** For more information see Aug 08 23:08:57 notyourvolumio node[829]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 08 23:08:57 notyourvolumio volumio[829]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 08 23:08:57 notyourvolumio volumio[829]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 08 23:08:57 notyourvolumio volumio[829]: *** WARNING *** For more information see Aug 08 23:08:57 notyourvolumio node[829]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 08 23:08:57 notyourvolumio node[829]: *** WARNING *** For more information see Aug 08 23:08:57 notyourvolumio node[829]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 08 23:08:57 notyourvolumio node[829]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 08 23:08:57 notyourvolumio node[829]: *** WARNING *** For more information see Aug 08 23:08:57 notyourvolumio volumio[829]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 08 23:08:57 notyourvolumio volumio[829]: info: Discovery: Started advertising with name: NotYourVolumio Aug 08 23:08:57 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 08 23:08:57 notyourvolumio volumio[829]: info: Loading plugin "spop"... Aug 08 23:08:58 notyourvolumio volumio[829]: info: Loading plugin "outputs"... Aug 08 23:08:58 notyourvolumio volumio[829]: info: Loading plugin "albumart"... Aug 08 23:08:58 notyourvolumio volumio[829]: info: Plugin example_plugin is not enabled Aug 08 23:08:58 notyourvolumio volumio[829]: info: Loading plugin "inputs"... Aug 08 23:08:58 notyourvolumio volumio[829]: info: Loading plugin "updater_comm"... Aug 08 23:08:58 notyourvolumio volumio[829]: info: Plugin mpdemulation is not enabled Aug 08 23:08:58 notyourvolumio volumio[829]: info: Loading plugin "rest_api"... Aug 08 23:08:58 notyourvolumio volumio[829]: info: Loading plugin "websocket"... Aug 08 23:08:59 notyourvolumio volumio[829]: info: Loading i18n strings for locale en Aug 08 23:08:59 notyourvolumio volumio[829]: Updating browse sources language Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 08 23:08:59 notyourvolumio volumio[829]: Forking 3 albumart workers Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::initPlayerControls Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: Express server listening on port 3000 Aug 08 23:08:59 notyourvolumio volumio[829]: [Metrics] WebUI: 5s 800.77ms Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreStateMachine::resetVolumioState Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreStateMachine::getcurrentVolume Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioRetrievevolume Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: MPD Permissions set Aug 08 23:08:59 notyourvolumio volumio-remote-updater[537]: [2023-08-08 23:08:59] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1691561336 101 Aug 08 23:08:59 notyourvolumio volumio[829]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Aug 08 23:08:59 notyourvolumio volumio[829]: info: MPD running with PID881 Aug 08 23:08:59 notyourvolumio volumio[829]: ,establishing connection Aug 08 23:08:59 notyourvolumio volumio[829]: Unable to parse: Aug 08 23:08:59 notyourvolumio volumio[829]: Simple mixer control 'AES Rate',0 Aug 08 23:08:59 notyourvolumio volumio[829]: Capabilities: volume volume-joined Aug 08 23:08:59 notyourvolumio volumio[829]: Playback channels: Mono Aug 08 23:08:59 notyourvolumio volumio[829]: Capture channels: Mono Aug 08 23:08:59 notyourvolumio volumio[829]: Limits: 0 - 200000 Aug 08 23:08:59 notyourvolumio volumio[829]: Mono: 0 [0%] Aug 08 23:08:59 notyourvolumio volumio[829]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: Reloading queue from file Aug 08 23:08:59 notyourvolumio volumio[829]: error: updateQueue error: null Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreStateMachine::setRepeat true single undefined Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreStateMachine::pushState Aug 08 23:08:59 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreStateMachine::setRandom null Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreStateMachine::pushState Aug 08 23:08:59 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 08 23:08:59 notyourvolumio volumio[829]: info: Setting Device type: Raspberry PI Aug 08 23:08:59 notyourvolumio volumio[829]: Unable to parse: Aug 08 23:08:59 notyourvolumio volumio[829]: Simple mixer control 'AES Rate',0 Aug 08 23:08:59 notyourvolumio volumio[829]: Capabilities: volume volume-joined Aug 08 23:08:59 notyourvolumio volumio[829]: Playback channels: Mono Aug 08 23:08:59 notyourvolumio volumio[829]: Capture channels: Mono Aug 08 23:08:59 notyourvolumio volumio[829]: Limits: 0 - 200000 Aug 08 23:08:59 notyourvolumio volumio[829]: Mono: 0 [0%] Aug 08 23:08:59 notyourvolumio volumio[829]: info: VolumeController:: Volume=undefined Mute =false Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreStateMachine::pushState Aug 08 23:08:59 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreStateMachine::updateTrackBlock Aug 08 23:08:59 notyourvolumio volumio[829]: info: CorePlayQueue::getTrackBlock Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioRetrievevolume Aug 08 23:08:59 notyourvolumio volumio[829]: Starting albumart workers Aug 08 23:08:59 notyourvolumio volumio[829]: error: updateQueue error: null Aug 08 23:08:59 notyourvolumio volumio[829]: info: Completed loading Core Plugins Aug 08 23:08:59 notyourvolumio volumio[829]: Starting albumart workers Aug 08 23:08:59 notyourvolumio volumio[829]: info: Preparing to generate the ALSA configuration file Aug 08 23:08:59 notyourvolumio volumio[829]: Starting albumart workers Aug 08 23:08:59 notyourvolumio volumio[829]: info: Asound.conf file unchanged, so no further update is needed Aug 08 23:08:59 notyourvolumio volumio[829]: info: Output device has changed, restarting MPD Aug 08 23:08:59 notyourvolumio volumio[829]: info: Output device has changed, restarting Shairport Sync Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 08 23:08:59 notyourvolumio sudo[962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 08 23:08:59 notyourvolumio sudo[962]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:59 notyourvolumio sudo[964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 08 23:08:59 notyourvolumio sudo[964]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:08:59 notyourvolumio sudo[962]: pam_unix(sudo:session): session closed for user root Aug 08 23:08:59 notyourvolumio systemd[1]: Stopping Music Player Daemon... Aug 08 23:08:59 notyourvolumio systemd[1]: mpd.service: Succeeded. Aug 08 23:08:59 notyourvolumio systemd[1]: Stopped Music Player Daemon. Aug 08 23:08:59 notyourvolumio systemd[1]: Starting Music Player Daemon... Aug 08 23:08:59 notyourvolumio volumio[829]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 08 23:08:59 notyourvolumio volumio[829]: info: ___________ START PLUGINS ___________ Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 08 23:08:59 notyourvolumio volumio[829]: info: [1691561339892] CoreMusicLibrary::Adding element Media Servers Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:08:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 08 23:09:00 notyourvolumio volumio[829]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 08 23:09:00 notyourvolumio volumio[829]: info: [1691561340023] CoreMusicLibrary::Adding element Last_100 Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 08 23:09:00 notyourvolumio volumio[829]: info: [1691561340026] CoreMusicLibrary::Adding element Webradio Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 08 23:09:00 notyourvolumio systemd[1]: systemd-fsckd.service: Succeeded. Aug 08 23:09:00 notyourvolumio volumio[829]: info: Checking for old spotify connect plugin installed Aug 08 23:09:00 notyourvolumio volumio[829]: info: Volumio Calling Home Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 08 23:09:00 notyourvolumio volumio[829]: [SpotifyConnect] Creating VLS config file Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:09:00 notyourvolumio volumio[829]: [SpotifyConnect] Starting metadata listener Aug 08 23:09:00 notyourvolumio sudo[998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Aug 08 23:09:00 notyourvolumio sudo[998]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:09:00 notyourvolumio systemd[1]: Started Volspotconnect2 Daemon. Aug 08 23:09:00 notyourvolumio sudo[998]: pam_unix(sudo:session): session closed for user root Aug 08 23:09:00 notyourvolumio volumio[829]: info: Discovery: adding 162164b3-e4b2-43ca-bf73-0ab4ce623037 Aug 08 23:09:00 notyourvolumio volumio[829]: info: Discovery: Found device NotYourVolumio Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 08 23:09:00 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:09:00 notyourvolumio volumio[829]: [SpotifyConnect] Vollibrespot Daemon service started! Aug 08 23:09:00 notyourvolumio volumio[829]: [Metrics] SpotifyConnect: 0s 203.64ms Aug 08 23:09:00 notyourvolumio volumio[829]: info: Completed starting Core Plugins Aug 08 23:09:00 notyourvolumio volumio[829]: info: ------------------------------------------- Aug 08 23:09:00 notyourvolumio volumio[829]: info: ----- MyVolumio plugins startup ---- Aug 08 23:09:00 notyourvolumio volumio[829]: info: ------------------------------------------- Aug 08 23:09:00 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 08 23:09:00 notyourvolumio volumio[829]: info: MPD Permissions set Aug 08 23:09:00 notyourvolumio volumio[829]: Unable to parse: Aug 08 23:09:00 notyourvolumio volumio[829]: Simple mixer control 'AES Rate',0 Aug 08 23:09:00 notyourvolumio volumio[829]: Capabilities: volume volume-joined Aug 08 23:09:00 notyourvolumio volumio[829]: Playback channels: Mono Aug 08 23:09:00 notyourvolumio volumio[829]: Capture channels: Mono Aug 08 23:09:00 notyourvolumio volumio[829]: Limits: 0 - 200000 Aug 08 23:09:00 notyourvolumio volumio[829]: Mono: 0 [0%] Aug 08 23:09:00 notyourvolumio volumio[1008]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Aug 08 23:09:00 notyourvolumio volumio[1008]: Reading Config from "/tmp/volspotify.toml" Aug 08 23:09:00 notyourvolumio volumio[829]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Aug 08 23:09:00 notyourvolumio volumio[1008]: [Vollibrespot] : Using Alsa backend with device: volumio Aug 08 23:09:00 notyourvolumio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Aug 08 23:09:00 notyourvolumio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:09:00 notyourvolumio volumio[1008]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Aug 08 23:09:00 notyourvolumio volumio[829]: Unable to parse: Aug 08 23:09:00 notyourvolumio volumio[829]: Simple mixer control 'AES Rate',0 Aug 08 23:09:00 notyourvolumio volumio[829]: Capabilities: volume volume-joined Aug 08 23:09:00 notyourvolumio volumio[829]: Playback channels: Mono Aug 08 23:09:00 notyourvolumio volumio[829]: Capture channels: Mono Aug 08 23:09:00 notyourvolumio volumio[829]: Limits: 0 - 200000 Aug 08 23:09:00 notyourvolumio volumio[829]: Mono: 0 [0%] Aug 08 23:09:00 notyourvolumio volumio[829]: info: VolumeController:: Volume=undefined Mute =false Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreStateMachine::pushState Aug 08 23:09:00 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 08 23:09:00 notyourvolumio volumio[829]: info: Volumio called home Aug 08 23:09:00 notyourvolumio volumio[829]: info: Starting Shairport Sync Aug 08 23:09:00 notyourvolumio volumio[829]: info: Starting Shairport Sync Aug 08 23:09:00 notyourvolumio sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 08 23:09:00 notyourvolumio sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:09:00 notyourvolumio volumio[829]: info: Starting Shairport Sync Aug 08 23:09:00 notyourvolumio sudo[1018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 08 23:09:00 notyourvolumio sudo[1018]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:09:00 notyourvolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 08 23:09:00 notyourvolumio systemd[1]: shairport-sync.service: Succeeded. Aug 08 23:09:00 notyourvolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 08 23:09:00 notyourvolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 08 23:09:00 notyourvolumio sudo[1016]: pam_unix(sudo:session): session closed for user root Aug 08 23:09:00 notyourvolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 08 23:09:00 notyourvolumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 08 23:09:00 notyourvolumio systemd[1]: shairport-sync.service: Succeeded. Aug 08 23:09:00 notyourvolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 08 23:09:00 notyourvolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 08 23:09:00 notyourvolumio sudo[1022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 08 23:09:00 notyourvolumio sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:09:00 notyourvolumio sudo[1018]: pam_unix(sudo:session): session closed for user root Aug 08 23:09:00 notyourvolumio mpd[977]: Aug 08 23:09 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 08 23:09:00 notyourvolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 08 23:09:00 notyourvolumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 08 23:09:00 notyourvolumio systemd[1]: shairport-sync.service: Succeeded. Aug 08 23:09:00 notyourvolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 08 23:09:00 notyourvolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 08 23:09:00 notyourvolumio sudo[1022]: pam_unix(sudo:session): session closed for user root Aug 08 23:09:00 notyourvolumio mpd[977]: Aug 08 23:09 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 08 23:09:00 notyourvolumio mpd[977]: Aug 08 23:09 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 08 23:09:00 notyourvolumio systemd[1]: Started Music Player Daemon. Aug 08 23:09:00 notyourvolumio sudo[964]: pam_unix(sudo:session): session closed for user root Aug 08 23:09:00 notyourvolumio volumio[829]: info: Shairport-Sync Started Aug 08 23:09:00 notyourvolumio volumio[829]: Error adding Membership: Error: addMembership EINVAL Aug 08 23:09:00 notyourvolumio volumio[829]: info: Shairport-Sync Started Aug 08 23:09:00 notyourvolumio volumio[829]: info: Shairport-Sync Started Aug 08 23:09:00 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 08 23:09:00 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:09:00 notyourvolumio volumio[829]: error: updateQueue error: null Aug 08 23:09:01 notyourvolumio sh[412]: timed out Aug 08 23:09:01 notyourvolumio dhcpcd[440]: timed out Aug 08 23:09:01 notyourvolumio sh[412]: dhcpcd exited Aug 08 23:09:01 notyourvolumio dhcpcd[440]: dhcpcd exited Aug 08 23:09:01 notyourvolumio sh[412]: ifup: failed to bring up eth0 Aug 08 23:09:01 notyourvolumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Aug 08 23:09:01 notyourvolumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Aug 08 23:09:04 notyourvolumio volumio[829]: info: Checking Spotify Web API Aug 08 23:09:04 notyourvolumio volumio[829]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 08 23:09:06 notyourvolumio sudo[1033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 08 23:09:06 notyourvolumio sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:09:06 notyourvolumio sudo[1033]: pam_unix(sudo:session): session closed for user root Aug 08 23:09:06 notyourvolumio sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 08 23:09:06 notyourvolumio sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:09:06 notyourvolumio sudo[1035]: pam_unix(sudo:session): session closed for user root Aug 08 23:09:06 notyourvolumio sudo[1039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 08 23:09:06 notyourvolumio sudo[1039]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:09:06 notyourvolumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 08 23:09:06 notyourvolumio sudo[1039]: pam_unix(sudo:session): session closed for user root Aug 08 23:09:06 notyourvolumio volumio[829]: info: Upmpdcli Daemon Started Aug 08 23:09:06 notyourvolumio volumio[1041]: Generating RSA private key, 4096 bit long modulus (2 primes) Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 08 23:09:08 notyourvolumio volumio[829]: info: Adding plugin bluetooth to MyMusic Plugins Aug 08 23:09:08 notyourvolumio volumio[829]: info: Adding plugin multiroom to MyMusic Plugins Aug 08 23:09:08 notyourvolumio volumio[829]: info: Adding plugin metavolumio to MyMusic Plugins Aug 08 23:09:08 notyourvolumio volumio[829]: info: Adding plugin cd_controller to MyMusic Plugins Aug 08 23:09:08 notyourvolumio volumio[829]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 08 23:09:08 notyourvolumio volumio[829]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 08 23:09:08 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 08 23:09:09 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 08 23:09:09 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 08 23:09:09 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 08 23:09:09 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 08 23:09:09 notyourvolumio volumio[829]: info: Starting MyVolumio Remote Streaming Endpoints Aug 08 23:09:09 notyourvolumio volumio[829]: info: MyVolumio login type: Token Aug 08 23:09:09 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 08 23:09:09 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 08 23:09:10 notyourvolumio systemd[1]: systemd-hostnamed.service: Succeeded. Aug 08 23:09:10 notyourvolumio volumio[829]: info: Starting Streaming Service Transparent Proxy Aug 08 23:09:10 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 08 23:09:10 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 08 23:09:10 notyourvolumio volumio[829]: info: Streaming services startup Aug 08 23:09:10 notyourvolumio volumio[829]: info: Starting Streaming Daemon Aug 08 23:09:10 notyourvolumio sudo[1066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 08 23:09:10 notyourvolumio volumio[829]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 08 23:09:10 notyourvolumio sudo[1066]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:09:10 notyourvolumio sudo[1066]: pam_unix(sudo:session): session closed for user root Aug 08 23:09:10 notyourvolumio volumio[829]: error: Cannot start Volumio Streaming Daemon Aug 08 23:09:10 notyourvolumio volumio[829]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 08 23:09:10 notyourvolumio volumio[829]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 08 23:09:10 notyourvolumio volumio[829]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 08 23:09:10 notyourvolumio volumio[829]: info: MYVOLUMIO: Adding device Aug 08 23:09:10 notyourvolumio volumio[829]: info: MYVOLUMIO: Evaluating Server Aug 08 23:09:11 notyourvolumio volumio[829]: STREAMING PROXY: Starting server on port 3245 Aug 08 23:09:11 notyourvolumio volumio[829]: Node JS runtime: 14 Aug 08 23:09:11 notyourvolumio volumio[829]: info: MyVolumio status changed Aug 08 23:09:11 notyourvolumio volumio[829]: info: Streaming services startup Aug 08 23:09:11 notyourvolumio volumio[829]: info: Starting Streaming Daemon Aug 08 23:09:11 notyourvolumio volumio[829]: info: Removing browser output: myVolumio user plan is not superstar Aug 08 23:09:11 notyourvolumio volumio[829]: info: Removing audio output: Aug 08 23:09:11 notyourvolumio volumio[829]: info: Stoppping Tunnel 1 Aug 08 23:09:11 notyourvolumio sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 08 23:09:11 notyourvolumio sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:09:11 notyourvolumio sudo[1092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 08 23:09:11 notyourvolumio sudo[1092]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:09:11 notyourvolumio sudo[1089]: pam_unix(sudo:session): session closed for user root Aug 08 23:09:11 notyourvolumio sudo[1092]: pam_unix(sudo:session): session closed for user root Aug 08 23:09:11 notyourvolumio volumio[829]: info: Remote SSH Stopped Aug 08 23:09:11 notyourvolumio volumio[829]: error: Cannot start Volumio Streaming Daemon Aug 08 23:09:11 notyourvolumio volumio[829]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 08 23:09:11 notyourvolumio volumio[829]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 08 23:09:11 notyourvolumio volumio[829]: info: Setting Geolocation for MyVolumio to us2 Aug 08 23:09:11 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 08 23:09:11 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 08 23:09:11 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 08 23:09:11 notyourvolumio volumio[829]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 08 23:09:12 notyourvolumio volumio[1041]: .................................................................++++ Aug 08 23:09:12 notyourvolumio volumio[829]: info: Updating MyVolumio device info Aug 08 23:09:12 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 08 23:09:13 notyourvolumio volumio[829]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 08 23:09:15 notyourvolumio volumio[829]: info: MYVOLUMIO: Adding device Aug 08 23:09:15 notyourvolumio volumio[829]: info: MYVOLUMIO: Evaluating Server Aug 08 23:09:16 notyourvolumio volumio[829]: info: Setting Geolocation for MyVolumio to us2 Aug 08 23:09:16 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 08 23:09:16 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 08 23:09:16 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 08 23:09:17 notyourvolumio volumio[829]: info: Updating MyVolumio device info Aug 08 23:09:17 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 08 23:09:17 notyourvolumio volumio[829]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 08 23:09:17 notyourvolumio volumio[829]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 08 23:09:21 notyourvolumio volumio[1041]: ......................................................................................................++++ Aug 08 23:09:21 notyourvolumio volumio[1041]: e is 65537 (0x010001) Aug 08 23:09:21 notyourvolumio volumio[1041]: writing RSA key Aug 08 23:09:24 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 08 23:09:24 notyourvolumio volumio[829]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Aug 08 23:09:24 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 08 23:09:24 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:09:29 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:09:29 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:09:29 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 08 23:09:29 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 08 23:09:29 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 08 23:09:29 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioGetBrowseSources Aug 08 23:09:29 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 08 23:09:30 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 08 23:09:30 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 08 23:09:30 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 08 23:09:30 notyourvolumio volumio-remote-updater[537]: No test mode Aug 08 23:09:30 notyourvolumio volumio-remote-updater[537]: No alpha test mode Aug 08 23:09:30 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 08 23:09:30 notyourvolumio volumio[829]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 08 23:09:30 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 08 23:09:30 notyourvolumio volumio[829]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 08 23:09:30 notyourvolumio volumio[829]: info: Completed starting MyVolumio Plugin Aug 08 23:09:30 notyourvolumio volumio[829]: info: BOOT COMPLETED Aug 08 23:09:30 notyourvolumio volumio[829]: [Metrics] CommandRouter: 36s 539.33ms Aug 08 23:09:30 notyourvolumio volumio[829]: info: CoreCommandRouter::volumiosetStartupVolume Aug 08 23:09:30 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 08 23:09:30 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 08 23:09:30 notyourvolumio volumio[829]: info: CoreCommandRouter::Close All Modals sent Aug 08 23:09:30 notyourvolumio volumio[829]: info: CoreCommandRouter::Close All Modals sent Aug 08 23:09:31 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 08 23:09:31 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 08 23:09:31 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 08 23:09:40 notyourvolumio ntpd[640]: Soliciting pool server 198.211.103.209 Aug 08 23:09:41 notyourvolumio ntpd[640]: Soliciting pool server 209.51.161.238 Aug 08 23:09:42 notyourvolumio ntpd[640]: Soliciting pool server 129.250.35.251 Aug 08 23:09:42 notyourvolumio ntpd[640]: Soliciting pool server 192.210.199.110 Aug 08 23:09:43 notyourvolumio ntpd[640]: Soliciting pool server 142.202.190.19 Aug 08 23:09:43 notyourvolumio ntpd[640]: Soliciting pool server 204.17.205.8 Aug 08 23:09:44 notyourvolumio ntpd[640]: Soliciting pool server 74.6.168.72 Aug 08 23:09:44 notyourvolumio ntpd[640]: Soliciting pool server 209.94.190.139 Aug 08 23:09:45 notyourvolumio ntpd[640]: Soliciting pool server 2620:149:a00:4000::1f2 Aug 08 23:09:45 notyourvolumio ntpd[640]: Soliciting pool server 66.85.78.80 Aug 08 23:09:45 notyourvolumio ntpd[640]: Soliciting pool server 198.137.202.56 Aug 08 23:09:46 notyourvolumio ntpd[640]: Soliciting pool server 162.159.200.123 Aug 08 23:09:47 notyourvolumio ntpd[640]: Soliciting pool server 69.89.207.199 Aug 08 23:09:48 notyourvolumio ntpd[640]: Soliciting pool server 64.111.99.224 Aug 08 23:11:49 notyourvolumio volumio[829]: verbose: New Socket.io Connection to 192.168.31.107 from 192.168.31.98 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/16.3 Safari/605.1.15 Total Clients: 5 Aug 08 23:11:49 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 08 23:11:49 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:11:49 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioGetQueue Aug 08 23:11:49 notyourvolumio volumio[829]: info: CoreStateMachine::getQueue Aug 08 23:11:49 notyourvolumio volumio[829]: info: CorePlayQueue::getQueue Aug 08 23:11:49 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Aug 08 23:11:56 notyourvolumio volumio[1008]: [Vollibrespot] : couldn't parse packet from 192.168.31.98:5353: query type 65 is invalid Aug 08 23:11:56 notyourvolumio sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 08 23:11:56 notyourvolumio sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:11:56 notyourvolumio sudo[1137]: pam_unix(sudo:session): session closed for user root Aug 08 23:11:56 notyourvolumio sudo[1139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 08 23:11:56 notyourvolumio sudo[1139]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:11:56 notyourvolumio sudo[1139]: pam_unix(sudo:session): session closed for user root Aug 08 23:11:57 notyourvolumio volumio[829]: verbose: New Socket.io Connection to 192.168.31.107 from 192.168.31.98 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/16.3 Safari/605.1.15 Total Clients: 6 Aug 08 23:11:57 notyourvolumio sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 08 23:11:57 notyourvolumio sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:11:57 notyourvolumio sudo[1143]: pam_unix(sudo:session): session closed for user root Aug 08 23:11:57 notyourvolumio sudo[1145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 08 23:11:57 notyourvolumio sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:11:57 notyourvolumio sudo[1145]: pam_unix(sudo:session): session closed for user root Aug 08 23:11:57 notyourvolumio volumio[829]: verbose: New Socket.io Connection to 192.168.31.107 from 192.168.31.98 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/16.3 Safari/605.1.15 Total Clients: 7 Aug 08 23:11:57 notyourvolumio volumio[1008]: [Vollibrespot] : couldn't parse packet from 192.168.31.98:5353: query type 65 is invalid Aug 08 23:11:57 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 08 23:11:57 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 08 23:11:57 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioGetVisibleSources Aug 08 23:11:57 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 08 23:11:57 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 08 23:11:57 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:11:57 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 08 23:11:57 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 08 23:11:57 notyourvolumio volumio[829]: info: Received Get System Info Aug 08 23:11:57 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 08 23:11:57 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 08 23:11:57 notyourvolumio volumio[829]: info: Discovery: Getting this device information Aug 08 23:11:57 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 08 23:11:57 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:11:57 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 08 23:11:57 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 08 23:11:57 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:11:57 notyourvolumio volumio[829]: info: Listing playlists Aug 08 23:11:57 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 08 23:11:57 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 08 23:11:57 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 08 23:11:58 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 08 23:11:58 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:11:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 08 23:11:59 notyourvolumio volumio[829]: info: Received Get System Info Aug 08 23:11:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 08 23:11:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 08 23:11:59 notyourvolumio volumio[829]: info: Discovery: Getting this device information Aug 08 23:11:59 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 08 23:11:59 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:11:59 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 08 23:12:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 08 23:12:00 notyourvolumio volumio[829]: info: Received Get System Info Aug 08 23:12:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 08 23:12:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 08 23:12:00 notyourvolumio volumio[829]: info: Discovery: Getting this device information Aug 08 23:12:00 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 08 23:12:00 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:12:00 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 08 23:12:01 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioGetQueue Aug 08 23:12:01 notyourvolumio volumio[829]: info: CoreStateMachine::getQueue Aug 08 23:12:01 notyourvolumio volumio[829]: info: CorePlayQueue::getQueue Aug 08 23:12:04 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 08 23:12:04 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:12:06 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioClearQueue Aug 08 23:12:06 notyourvolumio volumio[829]: info: CoreStateMachine::ClearQueue Aug 08 23:12:06 notyourvolumio volumio[829]: info: CoreStateMachine::stop Aug 08 23:12:06 notyourvolumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 08 23:12:06 notyourvolumio volumio[829]: info: CorePlayQueue::clearPlayQueue Aug 08 23:12:06 notyourvolumio volumio[829]: info: CorePlayQueue::saveQueue Aug 08 23:12:06 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 08 23:12:06 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushQueue Aug 08 23:12:08 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 08 23:12:08 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:12:16 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 08 23:12:16 notyourvolumio volumio[829]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 08 23:12:16 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 08 23:12:16 notyourvolumio volumio[829]: info: Received Get System Version Aug 08 23:12:16 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 08 23:12:16 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 08 23:12:16 notyourvolumio volumio[829]: info: CURURI: music-library/NAS/NAS/tmp/Mahler Symphonies 1-10 Gary Bertini/CD2 Aug 08 23:12:16 notyourvolumio volumio[829]: info: Preload queue cleared Aug 08 23:12:16 notyourvolumio volumio[829]: info: Preloading song: music-library/NAS/NAS/tmp/Mahler Symphonies 1-10 Gary Bertini/CD2/Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2.wav Aug 08 23:12:16 notyourvolumio volumio[829]: info: Exploding uri music-library/NAS/NAS/tmp/Mahler Symphonies 1-10 Gary Bertini/CD2/Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2.wav in service mpd Aug 08 23:12:16 notyourvolumio volumio[829]: info: ALBUMART /albumart?cacheid=915&web=//extralarge&path=%2Fmnt%2FNAS%2FNAS%2Ftmp%2FMahler%20Symphonies%201-10%20Gary%20Bertini%2FCD2%2FKolner%20Rundfunk-Sinfonieorchester%20%26%20Gary%20Bertini%20-%20Mahler%20Symphonies%201-10%20(Disc%202)%20-%20Sym%20No.2.wav&metadata=false Aug 08 23:12:16 notyourvolumio volumio[829]: info: URI /mnt/NAS/NAS/tmp/Mahler Symphonies 1-10 Gary Bertini/CD2/Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2.wav Aug 08 23:12:32 notyourvolumio volumio[829]: info: Preload queue cleared Aug 08 23:12:32 notyourvolumio volumio[829]: info: Aug 08 23:12:32 notyourvolumio volumio[829]: [1691561552043] ---------------------------- Client requests add and Play Volumio CUE entry Aug 08 23:12:32 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Aug 08 23:12:32 notyourvolumio volumio[829]: info: Adding CUE individual entry: 7 NAS/NAS/tmp/Mahler Symphonies 1-10 Gary Bertini/CD2/Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2.cue Aug 08 23:12:32 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioAddQueueItems Aug 08 23:12:32 notyourvolumio volumio[829]: info: CoreStateMachine::addQueueItems Aug 08 23:12:32 notyourvolumio volumio[829]: info: CorePlayQueue::addQueueItems Aug 08 23:12:32 notyourvolumio volumio[829]: info: Preload queue cleared Aug 08 23:12:32 notyourvolumio volumio[829]: info: Adding Item to queue: cue://NAS/NAS/tmp/Mahler Symphonies 1-10 Gary Bertini/CD2/Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2.cue@7 Aug 08 23:12:32 notyourvolumio volumio[829]: info: Exploding uri cue://NAS/NAS/tmp/Mahler Symphonies 1-10 Gary Bertini/CD2/Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2.cue@7 in service mpd Aug 08 23:12:32 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPlay Aug 08 23:12:32 notyourvolumio volumio[829]: info: CoreStateMachine::play index 0 Aug 08 23:12:32 notyourvolumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 08 23:12:32 notyourvolumio volumio[829]: info: CoreStateMachine::stop Aug 08 23:12:32 notyourvolumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 08 23:12:32 notyourvolumio volumio[829]: info: CoreStateMachine::play index undefined Aug 08 23:12:32 notyourvolumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 08 23:12:32 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:12:32 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushQueue Aug 08 23:12:32 notyourvolumio volumio[829]: info: CorePlayQueue::saveQueue Aug 08 23:12:32 notyourvolumio volumio[829]: info: CoreStateMachine::updateTrackBlock Aug 08 23:12:32 notyourvolumio volumio[829]: info: CorePlayQueue::getTrackBlock Aug 08 23:12:34 notyourvolumio volumio[1008]: [Vollibrespot] : couldn't parse packet from 192.168.31.98:5353: query type 65 is invalid Aug 08 23:12:35 notyourvolumio volumio[1008]: [Vollibrespot] : couldn't parse packet from 192.168.31.98:5353: query type 65 is invalid Aug 08 23:12:35 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 08 23:12:35 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:12:41 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPlay Aug 08 23:12:41 notyourvolumio volumio[829]: info: CoreStateMachine::play index undefined Aug 08 23:12:41 notyourvolumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 08 23:12:41 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:12:41 notyourvolumio volumio[829]: info: CoreStateMachine::startPlaybackTimer Aug 08 23:12:41 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:12:41 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Aug 08 23:12:41 notyourvolumio volumio[829]: info: sendMpdCommand stop took 2 milliseconds Aug 08 23:12:41 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand clear Aug 08 23:12:41 notyourvolumio volumio[829]: info: Aug 08 23:12:41 notyourvolumio volumio[829]: ---------------------------- MPD announces system playlist update Aug 08 23:12:41 notyourvolumio volumio[829]: info: Ignoring MPD Status Update Aug 08 23:12:41 notyourvolumio volumio[829]: info: sendMpdCommand clear took 3 milliseconds Aug 08 23:12:41 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand load "NAS/NAS/tmp/Mahler Symphonies 1-10 Gary Bertini/CD2/Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2.cue" Aug 08 23:12:41 notyourvolumio volumio[829]: error: updateQueue error: null Aug 08 23:12:41 notyourvolumio volumio[829]: info: ------------------------------ 6ms Aug 08 23:12:41 notyourvolumio volumio[829]: error: Upnp client error: Error: This socket has been ended by the other party Aug 08 23:12:41 notyourvolumio volumio[829]: info: Aug 08 23:12:41 notyourvolumio volumio[829]: ---------------------------- MPD announces system playlist update Aug 08 23:12:41 notyourvolumio volumio[829]: info: Ignoring MPD Status Update Aug 08 23:12:41 notyourvolumio volumio[829]: info: sendMpdCommand load "NAS/NAS/tmp/Mahler Symphonies 1-10 Gary Bertini/CD2/Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2.cue" took 29 milliseconds Aug 08 23:12:41 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand play Aug 08 23:12:41 notyourvolumio volumio[829]: info: ------------------------------ 5ms Aug 08 23:12:41 notyourvolumio volumio[829]: info: sendMpdCommand play took 4 milliseconds Aug 08 23:12:41 notyourvolumio volumio[829]: info: Aug 08 23:12:41 notyourvolumio volumio[829]: ---------------------------- MPD announces state update: player Aug 08 23:12:41 notyourvolumio volumio[829]: info: ControllerMpd::getState Aug 08 23:12:41 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Aug 08 23:12:41 notyourvolumio volumio[829]: info: Aug 08 23:12:41 notyourvolumio volumio[829]: ---------------------------- MPD announces state update: player Aug 08 23:12:41 notyourvolumio volumio[829]: info: sendMpdCommand status took 4 milliseconds Aug 08 23:12:41 notyourvolumio volumio[829]: info: ControllerMpd::getState Aug 08 23:12:41 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Aug 08 23:12:41 notyourvolumio volumio[829]: verbose: ControllerMpd::parseState Aug 08 23:12:41 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 08 23:12:41 notyourvolumio volumio[829]: info: sendMpdCommand status took 3 milliseconds Aug 08 23:12:41 notyourvolumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 08 23:12:41 notyourvolumio volumio[829]: verbose: ControllerMpd::parseState Aug 08 23:12:41 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 08 23:12:41 notyourvolumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Aug 08 23:12:41 notyourvolumio volumio[829]: info: ControllerMpd::pushState Aug 08 23:12:41 notyourvolumio volumio[829]: info: CoreCommandRouter::servicePushState Aug 08 23:12:41 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:12:41 notyourvolumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":7,"seek":0,"duration":404,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mahler Langsam. Misterioso","artist":"Krisztina Laki (soprano), Florence Quivar (mezzo-soprano), Kolner Rundfunk-Si...","album":"Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2","uri":"NAS/NAS/tmp/Mahler Symphonies 1-10 Gary Bertini/CD2/Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2.wav","trackType":"wav"} Aug 08 23:12:41 notyourvolumio volumio[829]: verbose: CURRENT POSITION 0 Aug 08 23:12:41 notyourvolumio volumio[829]: info: CoreStateMachine::syncState stateService play Aug 08 23:12:41 notyourvolumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Aug 08 23:12:41 notyourvolumio volumio[829]: info: ------------------------------ 21ms Aug 08 23:12:41 notyourvolumio volumio[829]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 08 23:12:41 notyourvolumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Aug 08 23:12:41 notyourvolumio volumio[829]: info: ControllerMpd::pushState Aug 08 23:12:41 notyourvolumio volumio[829]: info: CoreCommandRouter::servicePushState Aug 08 23:12:41 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:12:41 notyourvolumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":7,"seek":0,"duration":404,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mahler Langsam. Misterioso","artist":"Krisztina Laki (soprano), Florence Quivar (mezzo-soprano), Kolner Rundfunk-Si...","album":"Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2","uri":"NAS/NAS/tmp/Mahler Symphonies 1-10 Gary Bertini/CD2/Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2.wav","trackType":"wav"} Aug 08 23:12:41 notyourvolumio volumio[829]: verbose: CURRENT POSITION 0 Aug 08 23:12:41 notyourvolumio volumio[829]: info: CoreStateMachine::syncState stateService play Aug 08 23:12:41 notyourvolumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Aug 08 23:12:41 notyourvolumio volumio[829]: info: Received an update from plugin. extracting info from payload Aug 08 23:12:41 notyourvolumio volumio[829]: info: CoreStateMachine::pushState Aug 08 23:12:41 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:12:41 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 08 23:12:41 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 08 23:12:41 notyourvolumio volumio[829]: info: CoreStateMachine::pushState Aug 08 23:12:41 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:12:41 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 08 23:12:41 notyourvolumio volumio[829]: info: ------------------------------ 35ms Aug 08 23:12:56 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioSeek Aug 08 23:12:56 notyourvolumio volumio[829]: info: CoreStateMachine::seek Aug 08 23:12:56 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:12:56 notyourvolumio volumio[829]: info: TRACKBLOCK {"uri":"cue://NAS/NAS/tmp/Mahler Symphonies 1-10 Gary Bertini/CD2/Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2.cue@7","type":"cuesong","service":"mpd","name":"Mahler Langsam. Misterioso","artist":"Krisztina Laki (soprano), Florence Quivar (mezzo-soprano), Kolner Rundfunk-Si...","album":"Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2","number":7,"albumart":"/albumart?cacheid=915&web=Krisztina%20Laki%20(soprano)%2C%20Florence%20Quivar%20(mezzo-soprano)%2C%20Kolner%20Rundfunk-Si.../Kolner%20Rundfunk-Sinfonieorchester%20%26%20Gary%20Bertini%20-%20Mahler%20Symphonies%201-10%20(Disc%202)%20-%20Sym%20No.2/extralarge&path=%2FNAS%2FNAS%2Ftmp%2FMahler%20Symphonies%201-10%20Gary%20Bertini%2FCD2%2F&metadata=false","samplerate":"","bitdepth":0,"channels":0,"duration":404} Aug 08 23:12:56 notyourvolumio volumio[829]: info: CoreStateMachine::startPlaybackTimer Aug 08 23:12:56 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:12:56 notyourvolumio volumio[829]: info: ControllerMpd::seek Aug 08 23:12:56 notyourvolumio volumio[829]: info: CoreStateMachine::pushState Aug 08 23:12:56 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:12:56 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 08 23:12:56 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 08 23:12:56 notyourvolumio volumio[829]: error: null Aug 08 23:12:56 notyourvolumio volumio[829]: info: Aug 08 23:12:56 notyourvolumio volumio[829]: ---------------------------- MPD announces state update: player Aug 08 23:12:56 notyourvolumio volumio[829]: info: ControllerMpd::getState Aug 08 23:12:56 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Aug 08 23:12:56 notyourvolumio volumio[829]: info: sendMpdCommand status took 1 milliseconds Aug 08 23:12:56 notyourvolumio volumio[829]: verbose: ControllerMpd::parseState Aug 08 23:12:56 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 08 23:12:56 notyourvolumio volumio[829]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 08 23:12:56 notyourvolumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Aug 08 23:12:56 notyourvolumio volumio[829]: info: ControllerMpd::pushState Aug 08 23:12:56 notyourvolumio volumio[829]: info: CoreCommandRouter::servicePushState Aug 08 23:12:56 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:12:56 notyourvolumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":98000,"duration":643,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mahler Symphony No.2 in C minor, 'Resurrection' - II. Andante moderato. Sehr ...","artist":"Krisztina Laki (soprano), Florence Quivar (mezzo-soprano), Kolner Rundfunk-Si...","album":"Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2","uri":"NAS/NAS/tmp/Mahler Symphonies 1-10 Gary Bertini/CD2/Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2.wav","trackType":"wav"} Aug 08 23:12:56 notyourvolumio volumio[829]: verbose: CURRENT POSITION 0 Aug 08 23:12:56 notyourvolumio volumio[829]: info: CoreStateMachine::syncState stateService play Aug 08 23:12:56 notyourvolumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Aug 08 23:12:56 notyourvolumio volumio[829]: info: Received an update from plugin. extracting info from payload Aug 08 23:12:56 notyourvolumio volumio[829]: info: CoreStateMachine::pushState Aug 08 23:12:56 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:12:56 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 08 23:12:56 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 08 23:12:56 notyourvolumio volumio[829]: info: CoreStateMachine::pushState Aug 08 23:12:56 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:12:56 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 08 23:12:56 notyourvolumio volumio[829]: info: ------------------------------ 25ms Aug 08 23:13:08 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPause Aug 08 23:13:08 notyourvolumio volumio[829]: info: CoreStateMachine::pause Aug 08 23:13:08 notyourvolumio volumio[829]: info: CoreStateMachine::stPlaybackTimer Aug 08 23:13:08 notyourvolumio volumio[829]: info: CoreStateMachine::servicePause Aug 08 23:13:08 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:13:08 notyourvolumio volumio[829]: info: CoreCommandRouter::servicePause Aug 08 23:13:08 notyourvolumio volumio[829]: info: ControllerMpd::pause Aug 08 23:13:08 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand pause Aug 08 23:13:08 notyourvolumio volumio[829]: info: sendMpdCommand pause took 54 milliseconds Aug 08 23:13:08 notyourvolumio volumio[829]: info: Aug 08 23:13:08 notyourvolumio volumio[829]: ---------------------------- MPD announces state update: player Aug 08 23:13:08 notyourvolumio volumio[829]: info: ControllerMpd::getState Aug 08 23:13:08 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Aug 08 23:13:08 notyourvolumio volumio[829]: info: sendMpdCommand status took 1 milliseconds Aug 08 23:13:08 notyourvolumio volumio[829]: verbose: ControllerMpd::parseState Aug 08 23:13:08 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 08 23:13:08 notyourvolumio volumio[829]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 08 23:13:08 notyourvolumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Aug 08 23:13:08 notyourvolumio volumio[829]: info: ControllerMpd::pushState Aug 08 23:13:08 notyourvolumio volumio[829]: info: CoreCommandRouter::servicePushState Aug 08 23:13:08 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:13:08 notyourvolumio volumio[829]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":110594,"duration":643,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mahler Symphony No.2 in C minor, 'Resurrection' - II. Andante moderato. Sehr ...","artist":"Krisztina Laki (soprano), Florence Quivar (mezzo-soprano), Kolner Rundfunk-Si...","album":"Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2","uri":"NAS/NAS/tmp/Mahler Symphonies 1-10 Gary Bertini/CD2/Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2.wav","trackType":"wav"} Aug 08 23:13:08 notyourvolumio volumio[829]: verbose: CURRENT POSITION 0 Aug 08 23:13:08 notyourvolumio volumio[829]: info: CoreStateMachine::syncState stateService pause Aug 08 23:13:08 notyourvolumio volumio[829]: info: CoreStateMachine::syncState currentStatus pause Aug 08 23:13:08 notyourvolumio volumio[829]: info: CoreStateMachine::pushState Aug 08 23:13:08 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:13:08 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 08 23:13:08 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 08 23:13:08 notyourvolumio volumio[829]: info: CoreStateMachine::stPlaybackTimer Aug 08 23:13:08 notyourvolumio volumio[829]: info: ------------------------------ 18ms Aug 08 23:13:18 notyourvolumio volumio[1008]: [Vollibrespot] : couldn't parse packet from 192.168.31.98:5353: query type 65 is invalid Aug 08 23:13:19 notyourvolumio volumio[1008]: [Vollibrespot] : couldn't parse packet from 192.168.31.98:5353: query type 65 is invalid Aug 08 23:13:21 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 08 23:13:21 notyourvolumio volumio[829]: info: CURURI: music-library Aug 08 23:13:21 notyourvolumio volumio[829]: info: Preload queue cleared Aug 08 23:13:22 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 08 23:13:22 notyourvolumio volumio[829]: info: CURURI: music-library/NAS Aug 08 23:13:22 notyourvolumio volumio[829]: info: Preload queue cleared Aug 08 23:13:22 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 08 23:13:22 notyourvolumio volumio[829]: info: CURURI: music-library/NAS/NAS Aug 08 23:13:22 notyourvolumio volumio[829]: info: Preload queue cleared Aug 08 23:13:23 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 08 23:13:23 notyourvolumio volumio[829]: info: CURURI: music-library/NAS/NAS/test Aug 08 23:13:23 notyourvolumio volumio[829]: info: Preload queue cleared Aug 08 23:13:26 notyourvolumio volumio[1008]: [Vollibrespot] : couldn't parse packet from 192.168.31.98:5353: query type 65 is invalid Aug 08 23:13:27 notyourvolumio volumio[1008]: [Vollibrespot] : couldn't parse packet from 192.168.31.98:5353: query type 65 is invalid Aug 08 23:13:27 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 08 23:13:27 notyourvolumio volumio[829]: info: CURURI: music-library Aug 08 23:13:27 notyourvolumio volumio[829]: info: Preload queue cleared Aug 08 23:13:28 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 08 23:13:28 notyourvolumio volumio[829]: info: CURURI: music-library/NAS Aug 08 23:13:28 notyourvolumio volumio[829]: info: Preload queue cleared Aug 08 23:13:29 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 08 23:13:29 notyourvolumio volumio[829]: info: CURURI: music-library/NAS/NAS Aug 08 23:13:29 notyourvolumio volumio[829]: info: Preload queue cleared Aug 08 23:13:31 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 08 23:13:31 notyourvolumio volumio[829]: info: CURURI: music-library/NAS/NAS/tmp Aug 08 23:13:31 notyourvolumio volumio[829]: info: Preload queue cleared Aug 08 23:13:33 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 08 23:13:33 notyourvolumio volumio[829]: info: CURURI: music-library/NAS/NAS/tmp/Haitink - Bruckner The Syms - RCO Aug 08 23:13:33 notyourvolumio volumio[829]: info: Preload queue cleared Aug 08 23:13:34 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 08 23:13:34 notyourvolumio volumio[829]: info: CURURI: music-library/NAS/NAS/tmp/Haitink - Bruckner The Syms - RCO/Bernard Haitink - BrucknerThe Symphonies CD5 Aug 08 23:13:35 notyourvolumio volumio[829]: info: Preload queue cleared Aug 08 23:13:35 notyourvolumio volumio[829]: info: Preloading song: music-library/NAS/NAS/tmp/Haitink - Bruckner The Syms - RCO/Bernard Haitink - BrucknerThe Symphonies CD5/Bernard Haitink - BrucknerThe Symphonies CD5.ape Aug 08 23:13:35 notyourvolumio volumio[829]: info: Exploding uri music-library/NAS/NAS/tmp/Haitink - Bruckner The Syms - RCO/Bernard Haitink - BrucknerThe Symphonies CD5/Bernard Haitink - BrucknerThe Symphonies CD5.ape in service mpd Aug 08 23:13:35 notyourvolumio volumio[829]: info: ALBUMART /albumart?cacheid=915&web=//extralarge&path=%2Fmnt%2FNAS%2FNAS%2Ftmp%2FHaitink%20-%20Bruckner%20The%20Syms%20-%20RCO%2FBernard%20Haitink%20-%20BrucknerThe%20Symphonies%20CD5%2FBernard%20Haitink%20-%20BrucknerThe%20Symphonies%20CD5.ape&metadata=false Aug 08 23:13:35 notyourvolumio volumio[829]: info: URI /mnt/NAS/NAS/tmp/Haitink - Bruckner The Syms - RCO/Bernard Haitink - BrucknerThe Symphonies CD5/Bernard Haitink - BrucknerThe Symphonies CD5.ape Aug 08 23:13:37 notyourvolumio volumio[829]: info: Preload queue cleared Aug 08 23:13:37 notyourvolumio volumio[829]: info: Aug 08 23:13:37 notyourvolumio volumio[829]: [1691561617765] ---------------------------- Client requests add and Play Volumio CUE entry Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Aug 08 23:13:37 notyourvolumio volumio[829]: info: Adding CUE individual entry: 1 NAS/NAS/tmp/Haitink - Bruckner The Syms - RCO/Bernard Haitink - BrucknerThe Symphonies CD5/Bernard Haitink - BrucknerThe Symphonies CD5.cue Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioAddQueueItems Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreStateMachine::addQueueItems Aug 08 23:13:37 notyourvolumio volumio[829]: info: CorePlayQueue::addQueueItems Aug 08 23:13:37 notyourvolumio volumio[829]: info: Preload queue cleared Aug 08 23:13:37 notyourvolumio volumio[829]: info: Adding Item to queue: cue://NAS/NAS/tmp/Haitink - Bruckner The Syms - RCO/Bernard Haitink - BrucknerThe Symphonies CD5/Bernard Haitink - BrucknerThe Symphonies CD5.cue@1 Aug 08 23:13:37 notyourvolumio volumio[829]: info: Exploding uri cue://NAS/NAS/tmp/Haitink - Bruckner The Syms - RCO/Bernard Haitink - BrucknerThe Symphonies CD5/Bernard Haitink - BrucknerThe Symphonies CD5.cue@1 in service mpd Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPlay Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreStateMachine::play index 1 Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreStateMachine::stop Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreStateMachine::updateTrackBlock Aug 08 23:13:37 notyourvolumio volumio[829]: info: CorePlayQueue::getTrackBlock Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreStateMachine::stPlaybackTimer Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreStateMachine::pushState Aug 08 23:13:37 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreStateMachine::serviceStop Aug 08 23:13:37 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreCommandRouter::serviceStop Aug 08 23:13:37 notyourvolumio volumio[829]: info: ControllerMpd::stop Aug 08 23:13:37 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushQueue Aug 08 23:13:37 notyourvolumio volumio[829]: info: CorePlayQueue::saveQueue Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreStateMachine::updateTrackBlock Aug 08 23:13:37 notyourvolumio volumio[829]: info: CorePlayQueue::getTrackBlock Aug 08 23:13:37 notyourvolumio volumio[829]: info: Aug 08 23:13:37 notyourvolumio volumio[829]: ---------------------------- MPD announces state update: player Aug 08 23:13:37 notyourvolumio volumio[829]: info: sendMpdCommand stop took 18 milliseconds Aug 08 23:13:37 notyourvolumio volumio[829]: info: ControllerMpd::getState Aug 08 23:13:37 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreStateMachine::play index undefined Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 08 23:13:37 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 1 Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreStateMachine::startPlaybackTimer Aug 08 23:13:37 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 1 Aug 08 23:13:37 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Aug 08 23:13:37 notyourvolumio volumio[829]: info: sendMpdCommand status took 8 milliseconds Aug 08 23:13:37 notyourvolumio volumio[829]: info: sendMpdCommand stop took 6 milliseconds Aug 08 23:13:37 notyourvolumio volumio[829]: verbose: ControllerMpd::parseState Aug 08 23:13:37 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 08 23:13:37 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand clear Aug 08 23:13:37 notyourvolumio volumio[829]: info: Aug 08 23:13:37 notyourvolumio volumio[829]: ---------------------------- MPD announces system playlist update Aug 08 23:13:37 notyourvolumio volumio[829]: info: Ignoring MPD Status Update Aug 08 23:13:37 notyourvolumio volumio[829]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 08 23:13:37 notyourvolumio volumio[829]: info: sendMpdCommand clear took 2 milliseconds Aug 08 23:13:37 notyourvolumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Aug 08 23:13:37 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand load "NAS/NAS/tmp/Haitink - Bruckner The Syms - RCO/Bernard Haitink - BrucknerThe Symphonies CD5/Bernard Haitink - BrucknerThe Symphonies CD5.cue" Aug 08 23:13:37 notyourvolumio volumio[829]: info: ControllerMpd::pushState Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreCommandRouter::servicePushState Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreStateMachine::pushState Aug 08 23:13:37 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 1 Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 08 23:13:37 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 1 Aug 08 23:13:37 notyourvolumio volumio[829]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Mahler Symphony No.2 in C minor, 'Resurrection' - II. Andante moderato. Sehr ...","artist":"Krisztina Laki (soprano), Florence Quivar (mezzo-soprano), Kolner Rundfunk-Si...","album":"Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2","uri":"NAS/NAS/tmp/Mahler Symphonies 1-10 Gary Bertini/CD2/Kolner Rundfunk-Sinfonieorchester & Gary Bertini - Mahler Symphonies 1-10 (Disc 2) - Sym No.2.wav","trackType":"wav"} Aug 08 23:13:37 notyourvolumio volumio[829]: verbose: CURRENT POSITION 1 Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreStateMachine::syncState stateService stop Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreStateMachine::pushState Aug 08 23:13:37 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 1 Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 08 23:13:37 notyourvolumio volumio[829]: info: No code Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreStateMachine::pushState Aug 08 23:13:37 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 1 Aug 08 23:13:37 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 08 23:13:37 notyourvolumio volumio[829]: info: ------------------------------ 33ms Aug 08 23:13:37 notyourvolumio volumio[829]: error: updateQueue error: null Aug 08 23:13:37 notyourvolumio volumio[829]: info: Aug 08 23:13:37 notyourvolumio volumio[829]: ---------------------------- MPD announces system playlist update Aug 08 23:13:37 notyourvolumio volumio[829]: info: Ignoring MPD Status Update Aug 08 23:13:37 notyourvolumio volumio[829]: info: ------------------------------ 33ms Aug 08 23:13:37 notyourvolumio volumio[829]: info: sendMpdCommand load "NAS/NAS/tmp/Haitink - Bruckner The Syms - RCO/Bernard Haitink - BrucknerThe Symphonies CD5/Bernard Haitink - BrucknerThe Symphonies CD5.cue" took 32 milliseconds Aug 08 23:13:37 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand play Aug 08 23:13:37 notyourvolumio volumio[829]: info: ------------------------------ 3ms Aug 08 23:13:37 notyourvolumio volumio[829]: info: sendMpdCommand play took 3 milliseconds Aug 08 23:13:38 notyourvolumio volumio[829]: info: Aug 08 23:13:38 notyourvolumio volumio[829]: ---------------------------- MPD announces state update: player Aug 08 23:13:38 notyourvolumio volumio[829]: info: ControllerMpd::getState Aug 08 23:13:38 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Aug 08 23:13:38 notyourvolumio volumio[829]: info: Aug 08 23:13:38 notyourvolumio volumio[829]: ---------------------------- MPD announces state update: player Aug 08 23:13:38 notyourvolumio volumio[829]: info: ControllerMpd::getState Aug 08 23:13:38 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Aug 08 23:13:38 notyourvolumio volumio[829]: info: sendMpdCommand status took 3 milliseconds Aug 08 23:13:38 notyourvolumio volumio[829]: info: sendMpdCommand status took 1 milliseconds Aug 08 23:13:38 notyourvolumio volumio[829]: verbose: ControllerMpd::parseState Aug 08 23:13:38 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 08 23:13:38 notyourvolumio volumio[829]: verbose: ControllerMpd::parseState Aug 08 23:13:38 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 08 23:13:38 notyourvolumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 08 23:13:38 notyourvolumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 08 23:13:38 notyourvolumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Aug 08 23:13:38 notyourvolumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Aug 08 23:13:38 notyourvolumio volumio[829]: info: ControllerMpd::pushState Aug 08 23:13:38 notyourvolumio volumio[829]: info: CoreCommandRouter::servicePushState Aug 08 23:13:38 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 1 Aug 08 23:13:38 notyourvolumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":1115,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Symphony No. 5 in B flat major - 2. Adagio. Sehr langsam","artist":"Bernard Haitink & Royal Concertgebouw Orchestra","album":"Bruckner: The Symphonies [Disc 5]","uri":"NAS/NAS/tmp/Haitink - Bruckner The Syms - RCO/Bernard Haitink - BrucknerThe Symphonies CD5/Bernard Haitink - BrucknerThe Symphonies CD5.ape","trackType":"ape"} Aug 08 23:13:38 notyourvolumio volumio[829]: verbose: CURRENT POSITION 1 Aug 08 23:13:38 notyourvolumio volumio[829]: info: CoreStateMachine::syncState stateService play Aug 08 23:13:38 notyourvolumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Aug 08 23:13:38 notyourvolumio volumio[829]: info: ControllerMpd::pushState Aug 08 23:13:38 notyourvolumio volumio[829]: info: CoreCommandRouter::servicePushState Aug 08 23:13:38 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 1 Aug 08 23:13:38 notyourvolumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":1115,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Symphony No. 5 in B flat major - 2. Adagio. Sehr langsam","artist":"Bernard Haitink & Royal Concertgebouw Orchestra","album":"Bruckner: The Symphonies [Disc 5]","uri":"NAS/NAS/tmp/Haitink - Bruckner The Syms - RCO/Bernard Haitink - BrucknerThe Symphonies CD5/Bernard Haitink - BrucknerThe Symphonies CD5.ape","trackType":"ape"} Aug 08 23:13:38 notyourvolumio volumio[829]: verbose: CURRENT POSITION 1 Aug 08 23:13:38 notyourvolumio volumio[829]: info: CoreStateMachine::syncState stateService play Aug 08 23:13:38 notyourvolumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Aug 08 23:13:38 notyourvolumio volumio[829]: info: Received an update from plugin. extracting info from payload Aug 08 23:13:38 notyourvolumio volumio[829]: info: CoreStateMachine::pushState Aug 08 23:13:38 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 1 Aug 08 23:13:38 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 08 23:13:38 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 08 23:13:38 notyourvolumio volumio[829]: info: CoreStateMachine::pushState Aug 08 23:13:38 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 1 Aug 08 23:13:38 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 08 23:13:38 notyourvolumio volumio[829]: info: ------------------------------ 25ms Aug 08 23:13:38 notyourvolumio volumio[829]: info: ------------------------------ 32ms Aug 08 23:14:22 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPause Aug 08 23:14:22 notyourvolumio volumio[829]: info: CoreStateMachine::pause Aug 08 23:14:22 notyourvolumio volumio[829]: info: CoreStateMachine::stPlaybackTimer Aug 08 23:14:22 notyourvolumio volumio[829]: info: CoreStateMachine::servicePause Aug 08 23:14:22 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 1 Aug 08 23:14:22 notyourvolumio volumio[829]: info: CoreCommandRouter::servicePause Aug 08 23:14:22 notyourvolumio volumio[829]: info: ControllerMpd::pause Aug 08 23:14:22 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand pause Aug 08 23:14:22 notyourvolumio volumio[829]: info: sendMpdCommand pause took 12 milliseconds Aug 08 23:14:22 notyourvolumio volumio[829]: info: Aug 08 23:14:22 notyourvolumio volumio[829]: ---------------------------- MPD announces state update: player Aug 08 23:14:22 notyourvolumio volumio[829]: info: ControllerMpd::getState Aug 08 23:14:22 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Aug 08 23:14:22 notyourvolumio volumio[829]: info: sendMpdCommand status took 1 milliseconds Aug 08 23:14:22 notyourvolumio volumio[829]: verbose: ControllerMpd::parseState Aug 08 23:14:22 notyourvolumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 08 23:14:22 notyourvolumio volumio[829]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 08 23:14:22 notyourvolumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Aug 08 23:14:22 notyourvolumio volumio[829]: info: ControllerMpd::pushState Aug 08 23:14:22 notyourvolumio volumio[829]: info: CoreCommandRouter::servicePushState Aug 08 23:14:22 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 1 Aug 08 23:14:22 notyourvolumio volumio[829]: verbose: STATE SERVICE {"status":"pause","position":1,"seek":45211,"duration":1115,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Symphony No. 5 in B flat major - 2. Adagio. Sehr langsam","artist":"Bernard Haitink & Royal Concertgebouw Orchestra","album":"Bruckner: The Symphonies [Disc 5]","uri":"NAS/NAS/tmp/Haitink - Bruckner The Syms - RCO/Bernard Haitink - BrucknerThe Symphonies CD5/Bernard Haitink - BrucknerThe Symphonies CD5.ape","trackType":"ape"} Aug 08 23:14:22 notyourvolumio volumio[829]: verbose: CURRENT POSITION 1 Aug 08 23:14:22 notyourvolumio volumio[829]: info: CoreStateMachine::syncState stateService pause Aug 08 23:14:22 notyourvolumio volumio[829]: info: CoreStateMachine::syncState currentStatus pause Aug 08 23:14:22 notyourvolumio volumio[829]: info: CoreStateMachine::pushState Aug 08 23:14:22 notyourvolumio volumio[829]: info: CorePlayQueue::getTrack 1 Aug 08 23:14:22 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 08 23:14:22 notyourvolumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 08 23:14:22 notyourvolumio volumio[829]: info: CoreStateMachine::stPlaybackTimer Aug 08 23:14:22 notyourvolumio volumio[829]: info: ------------------------------ 13ms Aug 08 23:15:20 notyourvolumio volumio[829]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Aug 08 23:15:20 notyourvolumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Aug 08 23:15:20 notyourvolumio sudo[1335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Aug 08 23:15:20 notyourvolumio sudo[1335]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 08 23:15:20 notyourvolumio sudo[1335]: pam_unix(sudo:session): session closed for user root Aug 08 23:15:24 notyourvolumio sudo[1380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Aug 08 23:15:24 notyourvolumio sudo[1380]: pam_unix(sudo:session): session opened for user root by (uid=0)