Mon Oct 18 22:43:25 UTC 2021 Description="'Wireless ON'" # cat /proc/version --------------- Linux version 5.4.83-v7+ (dom@buildbot) (gcc version 8.4.0 (Ubuntu/Linaro 8.4.0-3ubuntu1)) #1379 SMP Mon Dec 14 13:08:57 GMT 2020 # 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="610b208fd10d9c2d727c38c144cc03a3632b2d1f" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_FE3_VERSION="2c893652079de9d2fda4c908ff5de8f733b655f0" VOLUMIO_BE_VERSION="31a299547351af4d1f51c88891d86e55561de92f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumiobuster" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 15 Oct 2021 12:50:00 PM CEST" VOLUMIO_VERSION="3.114" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="05afd5f192191c10f1dad8578bcb3958" # ifconfig --------------- eth0: flags=4163 mtu 1500 inet 192.168.0.26 netmask 255.255.255.0 broadcast 192.168.0.255 ether b8:27:eb:c7:4c:67 txqueuelen 1000 (Ethernet) RX packets 10546 bytes 14280686 (13.6 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 7870 bytes 2847474 (2.7 MiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 lo: flags=73 mtu 65536 inet 127.0.0.1 netmask 255.0.0.0 loop txqueuelen 1000 (Local Loopback) RX packets 3160 bytes 351829 (343.5 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 3160 bytes 351829 (343.5 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 wlan0: flags=4163 mtu 1500 inet 192.168.0.25 netmask 255.255.255.0 broadcast 192.168.0.255 ether b8:27:eb:92:19:32 txqueuelen 1000 (Ethernet) RX packets 599 bytes 128025 (125.0 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 47 bytes 12015 (11.7 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 # sudo iwconfig --------------- eth0 no wireless extensions. lo no wireless extensions. wlan0 IEEE 802.11 ESSID:"Mapear 2.4 GHz" Mode:Managed Frequency:2.462 GHz Access Point: 3C:B7:4B:3A:4D:50 Bit Rate=24 Mb/s Tx-Power=31 dBm Retry short limit:7 RTS thr:off Fragment thr:off Encryption key:off Power Management:off Link Quality=70/70 Signal level=-38 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 2: BossDAC [BossDAC], device 0: Boss DAC HiFi [Master] pcm512x-hifi-0 [Boss DAC HiFi [Master] pcm512x-hifi-0] Subdevices: 0/1 Subdevice #0: subdevice #0 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 7 22:40 ? 00:00:11 /sbin/init root 2 0 0 22:40 ? 00:00:00 [kthreadd] root 3 2 0 22:40 ? 00:00:00 [rcu_gp] root 4 2 0 22:40 ? 00:00:00 [rcu_par_gp] root 5 2 0 22:40 ? 00:00:00 [kworker/0:0-events] root 6 2 0 22:40 ? 00:00:00 [kworker/0:0H-kblockd] root 7 2 0 22:40 ? 00:00:00 [kworker/u8:0-events_unbound] root 8 2 0 22:40 ? 00:00:00 [mm_percpu_wq] root 9 2 0 22:40 ? 00:00:00 [ksoftirqd/0] root 10 2 0 22:40 ? 00:00:00 [rcu_sched] root 11 2 0 22:40 ? 00:00:00 [migration/0] root 12 2 0 22:40 ? 00:00:00 [cpuhp/0] root 13 2 0 22:40 ? 00:00:00 [cpuhp/1] root 14 2 0 22:40 ? 00:00:00 [migration/1] root 15 2 0 22:40 ? 00:00:00 [ksoftirqd/1] root 16 2 0 22:40 ? 00:00:00 [kworker/1:0-mm_percpu_wq] root 17 2 0 22:40 ? 00:00:00 [kworker/1:0H-kblockd] root 18 2 0 22:40 ? 00:00:00 [cpuhp/2] root 19 2 0 22:40 ? 00:00:00 [migration/2] root 20 2 0 22:40 ? 00:00:00 [ksoftirqd/2] root 21 2 0 22:40 ? 00:00:00 [kworker/2:0-events] root 22 2 0 22:40 ? 00:00:00 [kworker/2:0H-kblockd] root 23 2 0 22:40 ? 00:00:00 [cpuhp/3] root 24 2 0 22:40 ? 00:00:00 [migration/3] root 25 2 0 22:40 ? 00:00:00 [ksoftirqd/3] root 26 2 0 22:40 ? 00:00:00 [kworker/3:0-events] root 27 2 0 22:40 ? 00:00:00 [kworker/3:0H-kblockd] root 28 2 0 22:40 ? 00:00:00 [kdevtmpfs] root 29 2 0 22:40 ? 00:00:00 [netns] root 30 2 0 22:40 ? 00:00:00 [kworker/0:1-events] root 31 2 0 22:40 ? 00:00:00 [kworker/1:1-events] root 32 2 0 22:40 ? 00:00:00 [kauditd] root 33 2 0 22:40 ? 00:00:00 [khungtaskd] root 34 2 0 22:40 ? 00:00:00 [oom_reaper] root 35 2 0 22:40 ? 00:00:00 [writeback] root 36 2 0 22:40 ? 00:00:00 [kcompactd0] root 54 2 0 22:40 ? 00:00:00 [kblockd] root 55 2 0 22:40 ? 00:00:00 [blkcg_punt_bio] root 56 2 0 22:40 ? 00:00:00 [watchdogd] root 57 2 0 22:40 ? 00:00:00 [kworker/2:1-cgroup_destroy] root 58 2 0 22:40 ? 00:00:00 [kworker/3:1-events] root 59 2 0 22:40 ? 00:00:00 [rpciod] root 60 2 0 22:40 ? 00:00:00 [kworker/u9:0-hci0] root 61 2 0 22:40 ? 00:00:00 [xprtiod] root 62 2 0 22:41 ? 00:00:00 [kswapd0] root 63 2 0 22:41 ? 00:00:00 [nfsiod] root 64 2 0 22:41 ? 00:00:00 [iscsi_eh] root 65 2 0 22:41 ? 00:00:00 [dwc_otg] root 66 2 0 22:41 ? 00:00:00 [DWC Notificatio] root 67 2 0 22:41 ? 00:00:00 [kworker/3:2-events_power_efficient] root 68 2 0 22:41 ? 00:00:00 [vchiq-slot/0] root 69 2 0 22:41 ? 00:00:00 [vchiq-recy/0] root 70 2 0 22:41 ? 00:00:00 [vchiq-sync/0] root 71 2 0 22:41 ? 00:00:00 [vchiq-keep/0] root 72 2 0 22:41 ? 00:00:00 [SMIO] root 73 2 0 22:41 ? 00:00:00 [kworker/3:3-mm_percpu_wq] root 81 2 0 22:41 ? 00:00:00 [mmc_complete] root 82 2 0 22:41 ? 00:00:00 [kworker/3:1H-kblockd] root 83 2 0 22:41 ? 00:00:00 [kworker/0:1H-mmc_complete] root 101 2 0 22:41 ? 00:00:00 [kworker/2:2-mm_percpu_wq] root 104 2 0 22:41 ? 00:00:00 [kworker/2:1H-kblockd] root 105 2 0 22:41 ? 00:00:00 [kworker/2:2H-kblockd] root 109 2 0 22:41 ? 00:00:00 [kworker/1:1H-kblockd] root 110 2 0 22:41 ? 00:00:00 [jbd2/mmcblk0p2-] root 111 2 0 22:41 ? 00:00:00 [ext4-rsv-conver] root 114 2 0 22:41 ? 00:00:00 [kworker/u8:1-brcmf_wq/mmc1:0001:1] root 119 2 0 22:41 ? 00:00:01 [loop0] root 145 2 0 22:41 ? 00:00:00 [kworker/3:2H-kblockd] root 146 2 0 22:41 ? 00:00:00 [jbd2/mmcblk0p3-] root 147 2 0 22:41 ? 00:00:00 [ext4-rsv-conver] root 154 2 0 22:41 ? 00:00:00 [kworker/0:2H-kblockd] root 155 2 0 22:41 ? 00:00:00 [ipv6_addrconf] root 174 1 0 22:41 ? 00:00:01 /lib/systemd/systemd-journald root 198 2 0 22:41 ? 00:00:00 [kworker/1:2H-kblockd] root 205 1 0 22:41 ? 00:00:01 /lib/systemd/systemd-udevd root 211 2 0 22:41 ? 00:00:00 [kworker/u8:2-events_unbound] root 238 2 0 22:41 ? 00:00:00 [rc0] root 246 2 0 22:41 ? 00:00:00 [SMIO] root 261 2 0 22:41 ? 00:00:00 [mmal-vchiq] root 266 2 0 22:41 ? 00:00:00 [mmal-vchiq] root 268 2 0 22:41 ? 00:00:00 [mmal-vchiq] root 269 2 0 22:41 ? 00:00:00 [mmal-vchiq] root 299 2 0 22:41 ? 00:00:00 [kworker/2:3] root 301 2 0 22:41 ? 00:00:00 [cfg80211] root 313 2 0 22:41 ? 00:00:00 [brcmf_wq/mmc1:0] root 314 2 0 22:41 ? 00:00:00 [brcmf_wdog/mmc1] root 324 2 0 22:41 ? 00:00:00 [kworker/3:4-events] root 330 2 0 22:41 ? 00:00:00 [kworker/0:2-events] root 348 2 0 22:41 ? 00:00:00 [kworker/1:2-events_power_efficient] _rpc 395 1 0 22:41 ? 00:00:00 /sbin/rpcbind -f -w root 403 1 1 22:41 ? 00:00:01 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 message+ 419 1 0 22:41 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 422 1 0 22:41 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon root 427 1 0 22:41 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant root 434 2 0 22:41 ? 00:00:00 [kworker/0:3-cgroup_destroy] root 441 2 0 22:41 ? 00:00:00 [kworker/0:4-events] volumio 449 1 0 22:41 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 451 1 0 22:41 ? 00:00:00 /lib/systemd/systemd-logind nobody 457 1 0 22:41 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* avahi 473 1 0 22:41 ? 00:00:00 avahi-daemon: running [volumiobuster.local] root 495 2 0 22:41 ? 00:00:00 [kworker/u8:3-brcmf_wq/mmc1:0001:1] avahi 504 473 0 22:41 ? 00:00:00 avahi-daemon: chroot helper root 508 1 0 22:41 ? 00:00:00 /sbin/dhcpcd -q -b root 534 2 0 22:41 ? 00:00:00 [kworker/0:5-events] root 535 2 0 22:41 ? 00:00:00 [kworker/0:6-mm_percpu_wq] root 633 1 0 22:41 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 647 1 0 22:41 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I ntp 651 1 0 22:41 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 root 670 1 0 22:41 ? 00:00:00 /usr/sbin/sshd -D root 671 2 0 22:41 ? 00:00:00 [kworker/1:3-events] root 676 2 0 22:41 ? 00:00:00 [kworker/1:4-rcu_gp] root 677 2 0 22:41 ? 00:00:00 [kworker/1:5-events_power_efficient] root 692 633 0 22:41 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 694 1 0 22:41 ? 00:00:00 /usr/sbin/lircd --nodaemon root 695 694 0 22:41 ? 00:00:00 [sh] root 723 1 0 22:41 ? 00:00:00 irexec -d root 724 2 0 22:41 ? 00:00:00 [kworker/u9:1-hci0] root 725 2 0 22:41 ? 00:00:00 [kworker/u9:2-hci0] root 726 1 0 22:41 ? 00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 460800 noflow - b8:27:eb:6d:e6:cd root 756 1 0 22:41 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap root 758 1 0 22:41 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux root 759 1 0 22:41 ? 00:00:00 /sbin/agetty -o -p -- \u --keep-baud 115200,38400,9600 ttyAMA0 vt220 root 849 1 0 22:41 ? 00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0 root 937 1 0 22:41 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 955 937 0 22:41 ? 00:00:00 winbindd: domain child [VOLUMIOBUSTER] root 956 1 0 22:41 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 961 956 0 22:41 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 962 956 0 22:41 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 963 937 0 22:41 ? 00:00:00 winbindd: idmap child root 964 937 0 22:41 ? 00:00:00 winbindd: domain child [BUILTIN] root 965 956 0 22:41 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group volumio 1018 1 17 22:41 ? 00:00:20 /usr/bin/node /volumio/index.js root 1069 2 0 22:41 ? 00:00:00 [cifsiod] root 1070 2 0 22:41 ? 00:00:00 [smb3decryptd] root 1071 2 0 22:41 ? 00:00:00 [cifsfileinfoput] root 1072 2 0 22:41 ? 00:00:00 [cifsoplockd] root 1075 2 0 22:41 ? 00:00:00 [cifsd] volumio 1094 1018 0 22:41 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1101 1094 1 22:41 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1102 1094 1 22:41 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1103 1094 1 22:41 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart shairpo+ 1237 1 0 22:41 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf volumio 1238 1 0 22:41 ? 00:00:00 /bin/bash /data/plugins/music_service/volspotconnect2/startconnect.sh volumio 1239 1238 0 22:41 ? 00:00:00 ./vollibrespot -c volspotify.toml mpd 1241 1 3 22:41 ? 00:00:03 /usr/bin/mpd --no-daemon volumio 1256 1 0 22:41 ? 00:00:00 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf mpd 1283 1241 6 22:41 ? 00:00:06 camilladsp /data/configuration/audio_interface/fusiondsp/camilladsp.yml -p 9876 -l warn -f S16LE -r 44100 -n 2 -e 4096 volumio 1316 1 0 22:41 ? 00:00:00 /bin/streaming-daemon volumio 1722 1018 0 22:43 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'Wireless ON' volumio 1723 1722 39 22:43 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js Wireless ON volumio 1745 1723 0 22:43 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 1746 1745 0 22:43 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.5G 1.1G 1.3G 45% /imgpart /dev/loop0 456M 456M 0 100% /static overlay 12G 285M 11G 3% / devtmpfs 441M 0 441M 0% /dev tmpfs 485M 0 485M 0% /dev/shm tmpfs 485M 9.0M 476M 2% /run tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 485M 0 485M 0% /sys/fs/cgroup tmpfs 485M 52K 485M 1% /tmp tmpfs 485M 0 485M 0% /var/spool/cups tmpfs 20M 28K 20M 1% /var/log tmpfs 485M 0 485M 0% /var/spool/cups/tmp /dev/mmcblk0p1 92M 61M 31M 67% /boot //192.168.0.18/Musica_Flac_DSD 1.9T 1.3T 622G 67% /mnt/NAS/Musica_PC # 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=451324k,nr_inodes=112831,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/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) 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/net_cls,net_prio type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls,net_prio) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=30,pgrp=1,timeout=0,minproto=5,maxproto=5,direct) sunrpc on /run/rpc_pipefs type rpc_pipefs (rw,relatime) debugfs on /sys/kernel/debug type debugfs (rw,relatime) mqueue on /dev/mqueue type mqueue (rw,relatime) configfs on /sys/kernel/config type configfs (rw,relatime) tmpfs on /tmp type tmpfs (rw,noatime,mode=755) tmpfs on /var/spool/cups 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/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.0.18/Musica_Flac_DSD on /mnt/NAS/Musica_PC type cifs (ro,relatime,vers=3.1.1,cache=strict,username=dpozzi@gmail.com,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.0.18,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 127.0.0.1:9876 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:22 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:49149 0.0.0.0:* LISTEN 1256/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 1256/upmpdcli tcp 0 0 0.0.0.0:33349 0.0.0.0:* LISTEN 1239/./vollibrespot 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 192.168.0.26:60222 192.168.0.18:445 ESTABLISHED - tcp 0 0 192.168.0.26:51792 35.201.97.85:443 ESTABLISHED 1018/node tcp 0 0 192.168.0.26:46166 192.168.0.30:3000 ESTABLISHED 1018/node tcp 0 0 127.0.0.1:52842 127.0.0.1:3000 ESTABLISHED 449/volumio-remote- tcp 0 0 192.168.0.26:46378 192.168.0.30:3000 ESTABLISHED 1018/node tcp 0 0 192.168.0.26:39542 23.29.126.124:443 ESTABLISHED - tcp 0 0 127.0.0.1:41556 127.0.0.1:6599 ESTABLISHED 1256/upmpdcli tcp 0 0 127.0.0.1:41560 127.0.0.1:6599 ESTABLISHED 1256/upmpdcli tcp 0 0 127.0.0.1:58854 127.0.0.1:6600 ESTABLISHED 1018/node tcp 0 0 127.0.0.1:58850 127.0.0.1:6600 ESTABLISHED 1018/node tcp 0 0 127.0.0.1:52886 127.0.0.1:3000 ESTABLISHED 1018/node tcp 0 0 127.0.0.1:52844 127.0.0.1:3000 ESTABLISHED 1018/node tcp 0 0 127.0.0.1:52854 127.0.0.1:3000 ESTABLISHED 1018/node tcp6 0 0 :::22 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 1018/node tcp6 0 0 :::3001 :::* LISTEN 1094/node tcp6 0 0 :::3005 :::* LISTEN 1018/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::7777 :::* LISTEN 1316/streaming-daem tcp6 0 0 :::6599 :::* LISTEN 1018/node tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 192.168.0.26:3000 192.168.0.65:55479 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:52854 ESTABLISHED 1018/node tcp6 0 0 192.168.0.26:3000 192.168.0.30:42412 ESTABLISHED 1018/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:41560 ESTABLISHED 1018/node tcp6 0 0 192.168.0.25:3000 192.168.0.65:62819 TIME_WAIT - tcp6 0 0 192.168.0.25:3000 192.168.0.65:61864 TIME_WAIT - tcp6 0 0 192.168.0.26:3000 192.168.0.65:64567 TIME_WAIT - tcp6 0 0 192.168.0.25:3000 192.168.0.65:54733 TIME_WAIT - tcp6 0 0 192.168.0.26:3000 192.168.0.65:63639 TIME_WAIT - tcp6 0 0 192.168.0.25:3000 192.168.0.65:49688 TIME_WAIT - tcp6 0 0 127.0.0.1:6599 127.0.0.1:41556 ESTABLISHED 1018/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:52842 ESTABLISHED 1018/node tcp6 0 0 192.168.0.25:3000 192.168.0.65:56731 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:52844 ESTABLISHED 1018/node tcp6 0 0 192.168.0.26:3000 192.168.0.65:62116 ESTABLISHED 1018/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:52886 ESTABLISHED 1018/node tcp6 0 0 192.168.0.26:3000 192.168.0.65:64170 TIME_WAIT - tcp6 0 0 192.168.0.25:3000 192.168.0.65:58368 TIME_WAIT - tcp6 0 0 192.168.0.26:3000 192.168.0.65:56695 TIME_WAIT - tcp6 0 0 127.0.0.1:6600 127.0.0.1:58854 ESTABLISHED - tcp6 0 0 127.0.0.1:6600 127.0.0.1:58850 ESTABLISHED - # cat /etc/asound.conf --------------- pcm.!default { type empty slave.pcm "volumio" } pcm.volumio { type empty slave.pcm "volumioDsp" } pcm.volumioDsp { type plug slave { pcm "volumioDspc" format "unchanged" channels "unchanged" } } pcm.volumioDspc { type cdsp cpath "/data/plugins/audio_interface/fusiondsp/camilladsp" config_out "/data/configuration/audio_interface/fusiondsp/camilladsp.yml" channels 2 config_cdsp 1 rates = [ 44100 48000 88200 96000 176400 192000 352800 384000 ] cargs [ -p "9876" -l warn ] extra_samples 4096 } pcm.fromDsp1 { type plug slave { pcm "postDsp" format "unchanged" channels "unchanged" rate "unchanged" } } pcm.postDsp { type empty slave.pcm "volumioOutput" } # There is always a plug before the hardware to be safe pcm.volumioOutput { type plug slave.pcm "volumioHw" } pcm.volumioHw { type hw card "BossDAC" } # cat /var/log/mpd.log --------------- Oct 16 03:01 : zeroconf: No global port, disabling zeroconf Oct 16 03:01 : zeroconf: No global port, disabling zeroconf Oct 16 03:02 : client: [0] opened from 127.0.0.1:58834 Oct 16 03:02 : client: [1] opened from local Oct 16 03:02 : exception: No such directory Oct 16 03:02 : client: [2] opened from local Oct 16 03:02 : zeroconf: No global port, disabling zeroconf Oct 16 03:02 : client: [0] opened from local Oct 16 03:02 : client: [1] opened from local Oct 16 03:02 : client: [2] opened from 127.0.0.1:58850 Oct 16 03:02 : client: [3] opened from 127.0.0.1:58854 Oct 16 03:02 : exception: Failed to read mixer for 'alsa': no such mixer control: PCM Oct 16 03:02 : exception: Failed to open mixer for 'alsa': no such mixer control: PCM Oct 16 03:02 : client: [4] opened from local # cat /data/configuration/plugins.json --------------- { "music_service": { "airplay_emulation": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "inputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpd": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "webradio": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "example_plugin": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "last_100": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp_browser": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "streaming_services": { "status": { "type": "string", "value": "STARTING" } }, "volspotconnect2": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "radio_paradise": { "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" } }, "fusiondsp": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "miscellanea": { "alarm-clock": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "albumart": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "appearance": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "wizard": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "my_music": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "auto_play": { "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 ################################################################ ############################################################################### # 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, 5419 ms: FAILED https://www.googleapis.com, 373 ms: OK https://securetoken.googleapis.com, 622 ms: OK https://myvolumio.firebaseio.com, 915 ms: OK https://functions.volumio.cloud, 5667 ms: FAILED https://oauth-performer.dfs.volumio.org, 1845 ms: OK https://browsing-performer.dfs.volumio.org, 5139 ms: FAILED http://cddb.volumio.org, 5141 ms: FAILED https://functions.volumio.cloud, 5144 ms: FAILED http://pushupdates.volumio.org, 5144 ms: FAILED http://plugins.volumio.org, 5146 ms: FAILED https://database.volumio.cloud, 5149 ms: FAILED https://radio-directory.firebaseapp.com, 5152 ms: FAILED ---------- WARNING!!! Some remote endpoints cannot be reached! Failing endpoints: https://google.com, 5152 ms https://functions.volumio.cloud, 5152 ms https://browsing-performer.dfs.volumio.org, 5152 ms http://cddb.volumio.org, 5152 ms https://functions.volumio.cloud, 5152 ms http://pushupdates.volumio.org, 5152 ms http://plugins.volumio.org, 5152 ms https://database.volumio.cloud, 5152 ms https://radio-directory.firebaseapp.com, 5152 ms ---------- # sudo journalctl -p 7 --------------- -- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Mon 2021-10-18 22:43:39 UTC. -- Feb 14 10:11:59 volumiobuster kernel: Booting Linux on physical CPU 0x0 Feb 14 10:11:59 volumiobuster kernel: Linux version 5.4.83-v7+ (dom@buildbot) (gcc version 8.4.0 (Ubuntu/Linaro 8.4.0-3ubuntu1)) #1379 SMP Mon Dec 14 13:08:57 GMT 2020 Feb 14 10:11:59 volumiobuster kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d Feb 14 10:11:59 volumiobuster kernel: CPU: div instructions available: patching division code Feb 14 10:11:59 volumiobuster kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Feb 14 10:11:59 volumiobuster kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2 Feb 14 10:11:59 volumiobuster kernel: Memory policy: Data cache writealloc Feb 14 10:11:59 volumiobuster kernel: Reserved memory: created CMA memory pool at 0x3a000000, size 64 MiB Feb 14 10:11:59 volumiobuster kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Feb 14 10:11:59 volumiobuster kernel: On node 0 totalpages: 253952 Feb 14 10:11:59 volumiobuster kernel: Normal zone: 2232 pages used for memmap Feb 14 10:11:59 volumiobuster kernel: Normal zone: 0 pages reserved Feb 14 10:11:59 volumiobuster kernel: Normal zone: 253952 pages, LIFO batch:63 Feb 14 10:11:59 volumiobuster kernel: percpu: Embedded 20 pages/cpu s49740 r8192 d23988 u81920 Feb 14 10:11:59 volumiobuster kernel: pcpu-alloc: s49740 r8192 d23988 u81920 alloc=20*4096 Feb 14 10:11:59 volumiobuster kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 14 10:11:59 volumiobuster kernel: Built 1 zonelists, mobility grouping on. Total pages: 251720 Feb 14 10:11:59 volumiobuster kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Feb 14 10:11:59 volumiobuster kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Feb 14 10:11:59 volumiobuster kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 14 10:11:59 volumiobuster kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 14 10:11:59 volumiobuster kernel: Memory: 902648K/1015808K available (9216K kernel code, 698K rwdata, 2608K rodata, 1024K init, 827K bss, 47624K reserved, 65536K cma-reserved) Feb 14 10:11:59 volumiobuster kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 14 10:11:59 volumiobuster kernel: ftrace: allocating 29205 entries in 58 pages Feb 14 10:11:59 volumiobuster kernel: rcu: Hierarchical RCU implementation. Feb 14 10:11:59 volumiobuster kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Feb 14 10:11:59 volumiobuster kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Feb 14 10:11:59 volumiobuster kernel: random: get_random_bytes called from start_kernel+0x324/0x4f8 with crng_init=0 Feb 14 10:11:59 volumiobuster kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Feb 14 10:11:59 volumiobuster kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Feb 14 10:11:59 volumiobuster kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Feb 14 10:11:59 volumiobuster kernel: Switching to timer-based delay loop, resolution 52ns Feb 14 10:11:59 volumiobuster kernel: Console: colour dummy device 80x30 Feb 14 10:11:59 volumiobuster kernel: printk: console [tty1] enabled Feb 14 10:11:59 volumiobuster kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Feb 14 10:11:59 volumiobuster kernel: pid_max: default: 32768 minimum: 301 Feb 14 10:11:59 volumiobuster kernel: LSM: Security Framework initializing Feb 14 10:11:59 volumiobuster kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 10:11:59 volumiobuster kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 10:11:59 volumiobuster kernel: Disabling memory control group subsystem Feb 14 10:11:59 volumiobuster kernel: CPU: Testing write buffer coherency: ok Feb 14 10:11:59 volumiobuster kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Feb 14 10:11:59 volumiobuster kernel: Setting up static identity map for 0x100000 - 0x10003c Feb 14 10:11:59 volumiobuster kernel: rcu: Hierarchical SRCU implementation. Feb 14 10:11:59 volumiobuster kernel: smp: Bringing up secondary CPUs ... Feb 14 10:11:59 volumiobuster kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Feb 14 10:11:59 volumiobuster kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Feb 14 10:11:59 volumiobuster kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Feb 14 10:11:59 volumiobuster kernel: smp: Brought up 1 node, 4 CPUs Feb 14 10:11:59 volumiobuster kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). Feb 14 10:11:59 volumiobuster kernel: CPU: All CPU(s) started in HYP mode. Feb 14 10:11:59 volumiobuster kernel: CPU: Virtualization extensions available. Feb 14 10:11:59 volumiobuster kernel: devtmpfs: initialized Feb 14 10:11:59 volumiobuster kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 Feb 14 10:11:59 volumiobuster kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Feb 14 10:11:59 volumiobuster kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Feb 14 10:11:59 volumiobuster kernel: pinctrl core: initialized pinctrl subsystem Feb 14 10:11:59 volumiobuster kernel: NET: Registered protocol family 16 Feb 14 10:11:59 volumiobuster kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Feb 14 10:11:59 volumiobuster kernel: audit: initializing netlink subsys (disabled) Feb 14 10:11:59 volumiobuster kernel: audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1 Feb 14 10:11:59 volumiobuster kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Feb 14 10:11:59 volumiobuster kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Feb 14 10:11:59 volumiobuster kernel: Serial: AMBA PL011 UART driver Feb 14 10:11:59 volumiobuster kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled Feb 14 10:11:59 volumiobuster kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2021-01-08 14:33, variant start Feb 14 10:11:59 volumiobuster kernel: raspberrypi-firmware soc:firmware: Firmware hash is 194a85abd768c7334bbadc3f1911c10a7d18ed14 Feb 14 10:11:59 volumiobuster kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1 Feb 14 10:11:59 volumiobuster kernel: SCSI subsystem initialized Feb 14 10:11:59 volumiobuster kernel: usbcore: registered new interface driver usbfs Feb 14 10:11:59 volumiobuster kernel: usbcore: registered new interface driver hub Feb 14 10:11:59 volumiobuster kernel: usbcore: registered new device driver usb Feb 14 10:11:59 volumiobuster kernel: clocksource: Switched to clocksource arch_sys_counter Feb 14 10:11:59 volumiobuster kernel: VFS: Disk quotas dquot_6.6.0 Feb 14 10:11:59 volumiobuster kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Feb 14 10:11:59 volumiobuster kernel: FS-Cache: Loaded Feb 14 10:11:59 volumiobuster kernel: CacheFiles: Loaded Feb 14 10:11:59 volumiobuster kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 14 10:11:59 volumiobuster kernel: NET: Registered protocol family 2 Feb 14 10:11:59 volumiobuster kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) Feb 14 10:11:59 volumiobuster kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Feb 14 10:11:59 volumiobuster kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) Feb 14 10:11:59 volumiobuster kernel: TCP: Hash tables configured (established 8192 bind 8192) Feb 14 10:11:59 volumiobuster kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 10:11:59 volumiobuster kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 10:11:59 volumiobuster kernel: NET: Registered protocol family 1 Feb 14 10:11:59 volumiobuster kernel: RPC: Registered named UNIX socket transport module. Feb 14 10:11:59 volumiobuster kernel: RPC: Registered udp transport module. Feb 14 10:11:59 volumiobuster kernel: RPC: Registered tcp transport module. Feb 14 10:11:59 volumiobuster kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 14 10:11:59 volumiobuster kernel: Trying to unpack rootfs image as initramfs... Feb 14 10:11:59 volumiobuster kernel: Freeing initrd memory: 22488K Feb 14 10:11:59 volumiobuster kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Feb 14 10:11:59 volumiobuster kernel: Initialise system trusted keyrings Feb 14 10:11:59 volumiobuster kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 Feb 14 10:11:59 volumiobuster kernel: FS-Cache: Netfs 'nfs' registered for caching Feb 14 10:11:59 volumiobuster kernel: NFS: Registering the id_resolver key type Feb 14 10:11:59 volumiobuster kernel: Key type id_resolver registered Feb 14 10:11:59 volumiobuster kernel: Key type id_legacy registered Feb 14 10:11:59 volumiobuster kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Feb 14 10:11:59 volumiobuster kernel: Key type asymmetric registered Feb 14 10:11:59 volumiobuster kernel: Asymmetric key parser 'x509' registered Feb 14 10:11:59 volumiobuster kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Feb 14 10:11:59 volumiobuster kernel: io scheduler mq-deadline registered Feb 14 10:11:59 volumiobuster kernel: io scheduler kyber registered Feb 14 10:11:59 volumiobuster kernel: bcm2708_fb soc:fb: FB found 1 display(s) Feb 14 10:11:59 volumiobuster kernel: Console: switching to colour frame buffer device 80x30 Feb 14 10:11:59 volumiobuster kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Feb 14 10:11:59 volumiobuster kernel: Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled Feb 14 10:11:59 volumiobuster kernel: bcm2835-rng 3f104000.rng: hwrng registered Feb 14 10:11:59 volumiobuster kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Feb 14 10:11:59 volumiobuster kernel: vc-sm: Videocore shared memory driver Feb 14 10:11:59 volumiobuster kernel: gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 Feb 14 10:11:59 volumiobuster kernel: brd: module loaded Feb 14 10:11:59 volumiobuster kernel: loop: module loaded Feb 14 10:11:59 volumiobuster kernel: Loading iSCSI transport class v2.0-870. Feb 14 10:11:59 volumiobuster kernel: libphy: Fixed MDIO Bus: probed Feb 14 10:11:59 volumiobuster kernel: usbcore: registered new interface driver lan78xx Feb 14 10:11:59 volumiobuster kernel: usbcore: registered new interface driver smsc95xx Feb 14 10:11:59 volumiobuster kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Feb 14 10:11:59 volumiobuster kernel: Core Release: 2.80a Feb 14 10:11:59 volumiobuster kernel: Setting default values for core params Feb 14 10:11:59 volumiobuster kernel: Finished setting default values for core params Feb 14 10:11:59 volumiobuster kernel: Using Buffer DMA mode Feb 14 10:11:59 volumiobuster kernel: Periodic Transfer Interrupt Enhancement - disabled Feb 14 10:11:59 volumiobuster kernel: Multiprocessor Interrupt Enhancement - disabled Feb 14 10:11:59 volumiobuster kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Feb 14 10:11:59 volumiobuster kernel: Dedicated Tx FIFOs mode Feb 14 10:11:59 volumiobuster kernel: WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = ba104000 dma = 0xfa104000 len=9024 Feb 14 10:11:59 volumiobuster kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Feb 14 10:11:59 volumiobuster kernel: dwc_otg: Microframe scheduler enabled Feb 14 10:11:59 volumiobuster kernel: WARN::hcd_init_fiq:457: FIQ on core 1 Feb 14 10:11:59 volumiobuster kernel: WARN::hcd_init_fiq:458: FIQ ASM at 8070bf24 length 36 Feb 14 10:11:59 volumiobuster kernel: WARN::hcd_init_fiq:497: MPHI regs_base at be810000 Feb 14 10:11:59 volumiobuster kernel: dwc_otg 3f980000.usb: DWC OTG Controller Feb 14 10:11:59 volumiobuster kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Feb 14 10:11:59 volumiobuster kernel: dwc_otg 3f980000.usb: irq 56, io mem 0x00000000 Feb 14 10:11:59 volumiobuster kernel: Init: Port Power? op_state=1 Feb 14 10:11:59 volumiobuster kernel: Init: Power Port (0) Feb 14 10:11:59 volumiobuster kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04 Feb 14 10:11:59 volumiobuster kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 14 10:11:59 volumiobuster kernel: usb usb1: Product: DWC OTG Controller Feb 14 10:11:59 volumiobuster kernel: usb usb1: Manufacturer: Linux 5.4.83-v7+ dwc_otg_hcd Feb 14 10:11:59 volumiobuster kernel: usb usb1: SerialNumber: 3f980000.usb Feb 14 10:11:59 volumiobuster kernel: hub 1-0:1.0: USB hub found Feb 14 10:11:59 volumiobuster kernel: hub 1-0:1.0: 1 port detected Feb 14 10:11:59 volumiobuster kernel: dwc_otg: FIQ enabled Feb 14 10:11:59 volumiobuster kernel: dwc_otg: NAK holdoff enabled Feb 14 10:11:59 volumiobuster kernel: dwc_otg: FIQ split-transaction FSM enabled Feb 14 10:11:59 volumiobuster kernel: Module dwc_common_port init Feb 14 10:11:59 volumiobuster kernel: usbcore: registered new interface driver usb-storage Feb 14 10:11:59 volumiobuster kernel: mousedev: PS/2 mouse device common for all mice Feb 14 10:11:59 volumiobuster kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Feb 14 10:11:59 volumiobuster kernel: sdhci: Secure Digital Host Controller Interface driver Feb 14 10:11:59 volumiobuster kernel: sdhci: Copyright(c) Pierre Ossman Feb 14 10:11:59 volumiobuster kernel: mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe Feb 14 10:11:59 volumiobuster kernel: sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe Feb 14 10:11:59 volumiobuster kernel: sdhci-pltfm: SDHCI platform and OF driver helper Feb 14 10:11:59 volumiobuster kernel: ledtrig-cpu: registered to indicate activity on CPUs Feb 14 10:11:59 volumiobuster kernel: hidraw: raw HID events driver (C) Jiri Kosina Feb 14 10:11:59 volumiobuster kernel: usbcore: registered new interface driver usbhid Feb 14 10:11:59 volumiobuster kernel: usbhid: USB HID core driver Feb 14 10:11:59 volumiobuster kernel: vchiq: vchiq_init_state: slot_zero = (ptrval) Feb 14 10:11:59 volumiobuster kernel: [vc_sm_connected_init]: start Feb 14 10:11:59 volumiobuster kernel: [vc_sm_connected_init]: end - returning 0 Feb 14 10:11:59 volumiobuster kernel: Initializing XFRM netlink socket Feb 14 10:11:59 volumiobuster kernel: NET: Registered protocol family 17 Feb 14 10:11:59 volumiobuster kernel: Key type dns_resolver registered Feb 14 10:11:59 volumiobuster kernel: Registering SWP/SWPB emulation handler Feb 14 10:11:59 volumiobuster kernel: registered taskstats version 1 Feb 14 10:11:59 volumiobuster kernel: Loading compiled-in X.509 certificates Feb 14 10:11:59 volumiobuster kernel: Key type ._fscrypt registered Feb 14 10:11:59 volumiobuster kernel: Key type .fscrypt registered Feb 14 10:11:59 volumiobuster kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Feb 14 10:11:59 volumiobuster kernel: 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 81, base_baud = 0) is a PL011 rev2 Feb 14 10:11:59 volumiobuster kernel: printk: console [ttyAMA0] enabled Feb 14 10:11:59 volumiobuster kernel: 3f215040.serial: ttyS0 at MMIO 0x0 (irq = 53, base_baud = 50000000) is a 16550 Feb 14 10:11:59 volumiobuster kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Feb 14 10:11:59 volumiobuster kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 Feb 14 10:11:59 volumiobuster kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated Feb 14 10:11:59 volumiobuster kernel: sdhost: log_buf @ (ptrval) (fa107000) Feb 14 10:11:59 volumiobuster kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Feb 14 10:11:59 volumiobuster kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Feb 14 10:11:59 volumiobuster kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Feb 14 10:11:59 volumiobuster kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Feb 14 10:11:59 volumiobuster kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Feb 14 10:11:59 volumiobuster kernel: of_cfs_init Feb 14 10:11:59 volumiobuster kernel: of_cfs_init: OK Feb 14 10:11:59 volumiobuster kernel: Freeing unused kernel memory: 1024K Feb 14 10:11:59 volumiobuster kernel: Run /init as init process Feb 14 10:11:59 volumiobuster kernel: random: fast init done Feb 14 10:11:59 volumiobuster kernel: mmc0: host does not support reading read-only switch, assuming write-enable Feb 14 10:11:59 volumiobuster kernel: mmc0: new high speed SDHC card at address aaaa Feb 14 10:11:59 volumiobuster kernel: mmcblk0: mmc0:aaaa SL16G 14.8 GiB Feb 14 10:11:59 volumiobuster kernel: mmcblk0: p1 p2 p3 Feb 14 10:11:59 volumiobuster kernel: Indeed it is in host mode hprt0 = 00021501 Feb 14 10:11:59 volumiobuster kernel: mmc1: new high speed SDIO card at address 0001 Feb 14 10:11:59 volumiobuster initramfs: Booting Volumio for BCM2835 Feb 14 10:11:59 volumiobuster initramfs: This script mounts rootfs RO with an overlay RW layer. Feb 14 10:11:59 volumiobuster kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Feb 14 10:11:59 volumiobuster initramfs: IMGPART=/dev/mmcblk0p2 Feb 14 10:11:59 volumiobuster initramfs: IMGFILE=/volumio_current.sqsh Feb 14 10:11:59 volumiobuster initramfs: Boot delay (except first time) will be 5 seconds Feb 14 10:11:59 volumiobuster kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Feb 14 10:11:59 volumiobuster kernel: Indeed it is in host mode hprt0 = 00001101 Feb 14 10:11:59 volumiobuster initramfs: /dev/mmcblk0p2: Feb 14 10:11:59 volumiobuster kernel: EXT4-fs (mmcblk0p2): recovery complete Feb 14 10:11:59 volumiobuster kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Feb 14 10:11:59 volumiobuster initramfs: Doing a 5 second delay here to give kernel load a headstart Feb 14 10:11:59 volumiobuster kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Feb 14 10:11:59 volumiobuster kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Feb 14 10:11:59 volumiobuster kernel: hub 1-1:1.0: USB hub found Feb 14 10:11:59 volumiobuster kernel: hub 1-1:1.0: 5 ports detected Feb 14 10:11:59 volumiobuster kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Feb 14 10:11:59 volumiobuster kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Feb 14 10:11:59 volumiobuster kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Feb 14 10:11:59 volumiobuster kernel: smsc95xx v1.0.6 Feb 14 10:11:59 volumiobuster kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:c7:4c:67 Feb 14 10:11:59 volumiobuster initramfs: Checking for USB updates Feb 14 10:11:59 volumiobuster initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Feb 14 10:11:59 volumiobuster kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Feb 14 10:11:59 volumiobuster kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Feb 14 10:11:59 volumiobuster kernel: EXT4-fs (mmcblk0p3): recovery complete Feb 14 10:11:59 volumiobuster kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Feb 14 10:11:59 volumiobuster initramfs: With Option: Feb 14 10:11:59 volumiobuster initramfs: VOLUMIO_VERSION="3.114" Feb 14 10:11:59 volumiobuster initramfs: Finish initramfs, continue booting Volumio Feb 14 10:11:59 volumiobuster systemd[1]: System time before build time, advancing clock. Feb 14 10:11:59 volumiobuster kernel: NET: Registered protocol family 10 Feb 14 10:11:59 volumiobuster kernel: Segment Routing with IPv6 Feb 14 10:11:59 volumiobuster systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Feb 14 10:11:59 volumiobuster systemd[1]: Detected architecture arm. Feb 14 10:11:59 volumiobuster systemd[1]: Set hostname to . Feb 14 10:11:59 volumiobuster kernel: uart-pl011 3f201000.serial: no DMA platform data Feb 14 10:11:59 volumiobuster systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 14 10:11:59 volumiobuster systemd[1]: /etc/systemd/system/rc-local.service:10: Support for option SysVStartPriority= has been removed and it is ignored Feb 14 10:11:59 volumiobuster kernel: random: systemd: uninitialized urandom read (16 bytes read) Feb 14 10:11:59 volumiobuster kernel: random: systemd: uninitialized urandom read (16 bytes read) Feb 14 10:11:59 volumiobuster kernel: random: systemd: uninitialized urandom read (16 bytes read) Feb 14 10:11:59 volumiobuster systemd[1]: Listening on udev Control Socket. Feb 14 10:11:59 volumiobuster systemd[1]: Started Forward Password Requests to Wall Directory Watch. Feb 14 10:11:59 volumiobuster systemd[1]: Listening on udev Kernel Socket. Feb 14 10:11:59 volumiobuster systemd[1]: Created slice system-serial\x2dgetty.slice. Feb 14 10:11:59 volumiobuster systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Feb 14 10:11:59 volumiobuster kernel: i2c /dev entries driver Feb 14 10:11:59 volumiobuster systemd-journald[174]: Journal started Feb 14 10:11:59 volumiobuster systemd-journald[174]: Runtime journal (/run/log/journal/d71b28ffeb13465a78e2aceb60ffd13b) is 3.7M, max 30.0M, 26.2M free. Feb 14 10:11:59 volumiobuster systemd-modules-load[176]: Inserted module 'i2c_dev' Feb 14 10:11:59 volumiobuster systemd[1]: Started Apply Kernel Variables. Feb 14 10:11:59 volumiobuster systemd[1]: Mounted POSIX Message Queue File System. Feb 14 10:11:59 volumiobuster systemd[1]: Started Remount Root and Kernel File Systems. Feb 14 10:11:59 volumiobuster systemd[1]: Mounted Kernel Debug File System. Feb 14 10:11:59 volumiobuster systemd[1]: Mounted RPC Pipe File System. Feb 14 10:11:59 volumiobuster systemd[1]: Mounted Kernel Configuration File System. Feb 14 10:11:59 volumiobuster systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Feb 14 10:11:59 volumiobuster systemd[1]: Starting Create System Users... Feb 14 10:11:59 volumiobuster systemd[1]: Starting Load/Save Random Seed... Oct 16 03:01:31 volumiobuster fake-hwclock[184]: Sat Oct 16 03:01:31 UTC 2021 Oct 16 03:01:31 volumiobuster systemd[1]: Started Restore / save the current clock. Oct 16 03:01:31 volumiobuster systemd[1]: Started Load/Save Random Seed. Oct 16 03:01:31 volumiobuster systemd[1]: dynamicswap.service: Succeeded. Oct 16 03:01:31 volumiobuster systemd[1]: Started Create System Users. Oct 16 03:01:31 volumiobuster systemd[1]: Starting Create Static Device Nodes in /dev... Oct 16 03:01:31 volumiobuster systemd[1]: Started udev Coldplug all Devices. Oct 16 03:01:31 volumiobuster systemd[1]: Starting Helper to synchronize boot up for ifupdown... Oct 16 03:01:31 volumiobuster systemd[1]: Started Create Static Device Nodes in /dev. Oct 16 03:01:31 volumiobuster systemd[1]: Starting udev Kernel Device Manager... Oct 16 03:01:31 volumiobuster systemd[1]: Reached target Local File Systems (Pre). Oct 16 03:01:31 volumiobuster systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Oct 16 03:01:31 volumiobuster systemd[1]: Mounting /tmp... Oct 16 03:01:31 volumiobuster systemd[1]: Mounting /var/spool/cups... Oct 16 03:01:31 volumiobuster systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Oct 16 03:01:31 volumiobuster systemd[1]: Mounting /var/log... Oct 16 03:01:31 volumiobuster systemd[1]: Mounted /tmp. Oct 16 03:01:31 volumiobuster systemd[1]: Mounted /var/spool/cups. Oct 16 03:01:31 volumiobuster systemd[1]: Mounted /var/log. Oct 16 03:01:31 volumiobuster systemd[1]: Starting Flush Journal to Persistent Storage... Oct 16 03:01:31 volumiobuster systemd[1]: Mounting /var/spool/cups/tmp... Oct 16 03:01:31 volumiobuster systemd-udevd[205]: Network interface NamePolicy= disabled on kernel command line, ignoring. Oct 16 03:01:31 volumiobuster systemd[1]: Mounted /var/spool/cups/tmp. Oct 16 03:01:31 volumiobuster systemd-journald[174]: Runtime journal (/run/log/journal/d71b28ffeb13465a78e2aceb60ffd13b) is 7.5M, max 30.0M, 22.5M free. Oct 16 03:01:31 volumiobuster systemd[1]: Started udev Kernel Device Manager. Oct 16 03:01:31 volumiobuster systemd[1]: Started Flush Journal to Persistent Storage. Oct 16 03:01:31 volumiobuster systemd[1]: Starting Show Plymouth Boot Screen... Oct 16 03:01:31 volumiobuster systemd[1]: Received SIGRTMIN+20 from PID 223 (plymouthd). Oct 16 03:01:31 volumiobuster systemd[1]: Started Show Plymouth Boot Screen. Oct 16 03:01:31 volumiobuster systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Oct 16 03:01:31 volumiobuster systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Oct 16 03:01:31 volumiobuster systemd[1]: Reached target Local Encrypted Volumes. Oct 16 03:01:31 volumiobuster systemd[1]: Reached target Paths. Oct 16 03:01:31 volumiobuster kernel: Registered IR keymap rc-rc6-mce Oct 16 03:01:31 volumiobuster kernel: IR RC6 protocol handler initialized Oct 16 03:01:31 volumiobuster kernel: rc rc0: gpio_ir_recv as /devices/platform/ir-receiver@11/rc/rc0 Oct 16 03:01:31 volumiobuster kernel: rc rc0: lirc_dev: driver gpio_ir_recv registered at minor = 0, raw IR receiver, no transmitter Oct 16 03:01:31 volumiobuster kernel: input: gpio_ir_recv as /devices/platform/ir-receiver@11/rc/rc0/input0 Oct 16 03:01:31 volumiobuster kernel: mc: Linux media interface: v0.10 Oct 16 03:01:31 volumiobuster kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Oct 16 03:01:31 volumiobuster kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Oct 16 03:01:31 volumiobuster kernel: [vc_sm_connected_init]: start Oct 16 03:01:31 volumiobuster kernel: [vc_sm_connected_init]: installed successfully Oct 16 03:01:31 volumiobuster kernel: rc rc0: two consecutive events of type space Oct 16 03:01:32 volumiobuster kernel: videodev: Linux video capture interface: v2.00 Oct 16 03:01:32 volumiobuster systemd[1]: Found device /dev/ttyAMA0. Oct 16 03:01:32 volumiobuster kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Oct 16 03:01:32 volumiobuster kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Oct 16 03:01:32 volumiobuster kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Oct 16 03:01:32 volumiobuster kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Oct 16 03:01:32 volumiobuster kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Oct 16 03:01:32 volumiobuster kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Oct 16 03:01:32 volumiobuster kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Oct 16 03:01:32 volumiobuster kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Oct 16 03:01:32 volumiobuster kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Oct 16 03:01:32 volumiobuster kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Oct 16 03:01:32 volumiobuster kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Oct 16 03:01:32 volumiobuster kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Oct 16 03:01:32 volumiobuster kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Oct 16 03:01:32 volumiobuster kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Oct 16 03:01:32 volumiobuster kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Oct 16 03:01:32 volumiobuster kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Oct 16 03:01:32 volumiobuster kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Oct 16 03:01:32 volumiobuster kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Oct 16 03:01:32 volumiobuster kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Oct 16 03:01:32 volumiobuster kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Oct 16 03:01:32 volumiobuster kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Oct 16 03:01:32 volumiobuster kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Oct 16 03:01:32 volumiobuster kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Oct 16 03:01:32 volumiobuster kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Oct 16 03:01:32 volumiobuster systemd-udevd[222]: Using default interface naming scheme 'v240'. Oct 16 03:01:32 volumiobuster kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Oct 16 03:01:32 volumiobuster kernel: snd-allo-boss-dac soc:sound: ASoC: failed to init link Boss DAC: -517 Oct 16 03:01:32 volumiobuster kernel: snd-allo-boss-dac soc:sound: snd_soc_register_card() failed: -517 Oct 16 03:01:32 volumiobuster systemd-udevd[212]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 16 03:01:32 volumiobuster systemd-udevd[222]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 16 03:01:33 volumiobuster kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Oct 16 03:01:33 volumiobuster systemd[1]: Found device /dev/mmcblk0p1. Oct 16 03:01:33 volumiobuster kernel: snd-allo-boss-dac soc:sound: ASoC: failed to init link Boss DAC: -517 Oct 16 03:01:33 volumiobuster kernel: snd-allo-boss-dac soc:sound: snd_soc_register_card() failed: -517 Oct 16 03:01:33 volumiobuster kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Oct 16 03:01:33 volumiobuster kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Oct 16 03:01:33 volumiobuster kernel: usbcore: registered new interface driver brcmfmac Oct 16 03:01:33 volumiobuster kernel: snd-allo-boss-dac soc:sound: ASoC: failed to init link Boss DAC: -517 Oct 16 03:01:33 volumiobuster kernel: snd-allo-boss-dac soc:sound: snd_soc_register_card() failed: -517 Oct 16 03:01:33 volumiobuster kernel: brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43430-sdio.raspberrypi,3-model-b.txt failed with error -2 Oct 16 03:01:33 volumiobuster systemd-udevd[216]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 16 03:01:33 volumiobuster kernel: pcm512x 1-004d: 1-004d supply AVDD not found, using dummy regulator Oct 16 03:01:33 volumiobuster kernel: pcm512x 1-004d: 1-004d supply DVDD not found, using dummy regulator Oct 16 03:01:33 volumiobuster kernel: pcm512x 1-004d: 1-004d supply CPVDD not found, using dummy regulator Oct 16 03:01:33 volumiobuster kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Oct 16 03:01:33 volumiobuster kernel: brcmfmac: brcmf_c_process_clm_blob: no clm_blob available (err=-2), device may have limited channels available Oct 16 03:01:33 volumiobuster kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Oct 22 2019 01:59:28 version 7.45.98.94 (r723000 CY) FWID 01-3b33decd Oct 16 03:01:33 volumiobuster kernel: snd-allo-boss-dac soc:sound: pcm512x-hifi <-> 3f203000.i2s mapping ok Oct 16 03:01:33 volumiobuster systemd-udevd[219]: Using default interface naming scheme 'v240'. Oct 16 03:01:33 volumiobuster systemd-udevd[219]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Oct 16 03:01:34 volumiobuster systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. Oct 16 03:01:34 volumiobuster systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Oct 16 03:01:34 volumiobuster systemd[1]: Started Helper to synchronize boot up for ifupdown. Oct 16 03:01:34 volumiobuster systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 16 03:01:34 volumiobuster systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 16 03:01:34 volumiobuster systemd[1]: Condition check resulted in FUSE Control File System being skipped. Oct 16 03:01:34 volumiobuster systemd[1]: Condition check resulted in Huge Pages File System being skipped. Oct 16 03:01:34 volumiobuster systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Oct 16 03:01:34 volumiobuster systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Oct 16 03:01:34 volumiobuster systemd[1]: Starting File System Check on /dev/mmcblk0p1... Oct 16 03:01:34 volumiobuster systemd[1]: Starting Load/Save RF Kill Switch Status... Oct 16 03:01:34 volumiobuster systemd[1]: Started File System Check Daemon to report status. Oct 16 03:01:34 volumiobuster systemd[1]: Started Load/Save RF Kill Switch Status. Oct 16 03:01:34 volumiobuster systemd-fsck[343]: fsck.fat 4.1 (2017-01-24) Oct 16 03:01:34 volumiobuster systemd-fsck[343]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Oct 16 03:01:34 volumiobuster systemd-fsck[343]: Automatically removing dirty bit. Oct 16 03:01:34 volumiobuster systemd-fsck[343]: Performing changes. Oct 16 03:01:34 volumiobuster systemd-fsck[343]: /dev/mmcblk0p1: 258 files, 31121/46774 clusters Oct 16 03:01:34 volumiobuster systemd[1]: Started File System Check on /dev/mmcblk0p1. Oct 16 03:01:34 volumiobuster systemd[1]: Mounting /boot... Oct 16 03:01:35 volumiobuster systemd[1]: Mounted /boot. Oct 16 03:01:35 volumiobuster systemd[1]: Reached target Local File Systems. Oct 16 03:01:35 volumiobuster systemd[1]: Started ifup for wlan0. Oct 16 03:01:35 volumiobuster systemd[1]: Starting Raise network interfaces... Oct 16 03:01:35 volumiobuster systemd[1]: Starting Create Volatile Files and Directories... Oct 16 03:01:35 volumiobuster systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 16 03:01:35 volumiobuster systemd[1]: Started ifup for eth0. Oct 16 03:01:35 volumiobuster systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Oct 16 03:01:35 volumiobuster systemd[1]: Starting Preprocess NFS configuration... Oct 16 03:01:35 volumiobuster systemd[1]: plymouth-read-write.service: Succeeded. Oct 16 03:01:35 volumiobuster systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Oct 16 03:01:35 volumiobuster systemd[1]: Received SIGRTMIN+20 from PID 223 (plymouthd). Oct 16 03:01:35 volumiobuster systemd[1]: nfs-config.service: Succeeded. Oct 16 03:01:35 volumiobuster systemd[1]: Started Preprocess NFS configuration. Oct 16 03:01:35 volumiobuster systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Oct 16 03:01:35 volumiobuster systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Oct 16 03:01:35 volumiobuster systemd[1]: Reached target NFS client services. Oct 16 03:01:35 volumiobuster systemd[1]: Started Create Volatile Files and Directories. Oct 16 03:01:35 volumiobuster systemd[1]: Starting Update UTMP about System Boot/Shutdown... Oct 16 03:01:35 volumiobuster systemd[1]: Starting RPC bind portmap service... Oct 16 03:01:35 volumiobuster systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Oct 16 03:01:35 volumiobuster systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Oct 16 03:01:35 volumiobuster systemd[1]: Started Update UTMP about System Boot/Shutdown. Oct 16 03:01:35 volumiobuster systemd[1]: Reached target System Initialization. Oct 16 03:01:35 volumiobuster systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 16 03:01:35 volumiobuster systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 16 03:01:35 volumiobuster systemd[1]: Listening on triggerhappy.socket. Oct 16 03:01:35 volumiobuster systemd[1]: apt-daily.timer: Not using persistent file timestamp Mon 2021-10-18 01:23:22 UTC as it is in the future. Oct 16 03:01:35 volumiobuster systemd[1]: Started Daily apt download activities. Oct 16 03:01:35 volumiobuster systemd[1]: Listening on mpd.socket. Oct 16 03:01:35 volumiobuster systemd[1]: Listening on lircd.socket. Oct 16 03:01:35 volumiobuster systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Oct 16 03:01:35 volumiobuster systemd[1]: Reached target Sockets. Oct 16 03:01:35 volumiobuster systemd[1]: Reached target Basic System. Oct 16 03:01:35 volumiobuster systemd[1]: Started D-Bus System Message Bus. Oct 16 03:01:35 volumiobuster systemd[1]: Started UPnP Renderer front-end to MPD. Oct 16 03:01:35 volumiobuster systemd[1]: Starting lircd(8) initialization helper tool... Oct 16 03:01:35 volumiobuster systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Oct 16 03:01:35 volumiobuster systemd[1]: Started Manage Sound Card State (restore and store). Oct 16 03:01:35 volumiobuster kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Oct 16 03:01:35 volumiobuster systemd[1]: Starting WPA supplicant... Oct 16 03:01:35 volumiobuster alsactl[422]: alsactl 1.1.8 daemon started Oct 16 03:01:35 volumiobuster systemd[1]: Starting dhcpcd on all interfaces... Oct 16 03:01:35 volumiobuster kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 16 03:01:35 volumiobuster systemd[1]: Starting Save/Restore Sound Card State... Oct 16 03:01:35 volumiobuster systemd[1]: Starting Configure Bluetooth Modems connected by UART... Oct 16 03:01:35 volumiobuster systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Oct 16 03:01:35 volumiobuster systemd[1]: Started volumio-remote-updater.service. Oct 16 03:01:35 volumiobuster systemd[1]: Starting Login Service... Oct 16 03:01:35 volumiobuster systemd[1]: Starting triggerhappy global hotkey daemon... Oct 16 03:01:35 volumiobuster systemd[1]: Started Volumio Iptables Module. Oct 16 03:01:35 volumiobuster thd[457]: Unable to parse trigger line: Oct 16 03:01:35 volumiobuster thd[457]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Oct 16 03:01:35 volumiobuster thd[457]: Unable to parse trigger line: Oct 16 03:01:35 volumiobuster thd[457]: Found socket passed from systemd Oct 16 03:01:35 volumiobuster systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Oct 16 03:01:35 volumiobuster systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Sun 2021-10-17 14:39:45 UTC as it is in the future. Oct 16 03:01:35 volumiobuster systemd[1]: Started Daily apt upgrade and clean activities. Oct 16 03:01:35 volumiobuster systemd[1]: Reached target Timers. Oct 16 03:01:35 volumiobuster systemd[1]: Starting Wireless Services... Oct 16 03:01:35 volumiobuster dhcpcd[430]: dev: loaded udev Oct 16 03:01:35 volumiobuster systemd[1]: Starting Volumio SSH enabler... Oct 16 03:01:35 volumiobuster systemd[1]: Started RPC bind portmap service. Oct 16 03:01:35 volumiobuster volumiossh[476]: SSH file found, enabling SSH Oct 16 03:01:35 volumiobuster systemd[1]: Started triggerhappy global hotkey daemon. Oct 16 03:01:35 volumiobuster systemd[1]: Started Save/Restore Sound Card State. Oct 16 03:01:35 volumiobuster volumiossh[499]: Synchronizing state of ssh.service with SysV service script with /lib/systemd/systemd-sysv-install. Oct 16 03:01:35 volumiobuster volumiossh[499]: Executing: /lib/systemd/systemd-sysv-install enable ssh Oct 16 03:01:35 volumiobuster avahi-daemon[473]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Oct 16 03:01:35 volumiobuster avahi-daemon[473]: Successfully dropped root privileges. Oct 16 03:01:35 volumiobuster avahi-daemon[473]: avahi-daemon 0.7 starting up. Oct 16 03:01:35 volumiobuster systemd-logind[451]: Watching system buttons on /dev/input/event0 (gpio_ir_recv) Oct 16 03:01:36 volumiobuster systemd-logind[451]: New seat seat0. Oct 16 03:01:36 volumiobuster kernel: 8021q: 802.1Q VLAN Support v1.8 Oct 16 03:01:36 volumiobuster avahi-daemon[473]: Successfully called chroot(). Oct 16 03:01:36 volumiobuster avahi-daemon[473]: Successfully dropped remaining capabilities. Oct 16 03:01:36 volumiobuster avahi-daemon[473]: Loading service file /services/volumio.service. Oct 16 03:01:36 volumiobuster avahi-daemon[473]: Network interface enumeration completed. Oct 16 03:01:36 volumiobuster avahi-daemon[473]: Server startup complete. Host name is volumiobuster.local. Local service cookie is 2590215749. Oct 16 03:01:36 volumiobuster avahi-daemon[473]: Service "VolumioBuster" (/services/volumio.service) successfully established. Oct 16 03:01:36 volumiobuster dhcpcd[430]: forked to background, child pid 508 Oct 16 03:01:36 volumiobuster systemd[1]: Started dhcpcd on all interfaces. Oct 16 03:01:36 volumiobuster ifup[351]: ifup: waiting for lock on /run/network/ifstate.wlan0 Oct 16 03:01:36 volumiobuster systemd[1]: Started Avahi mDNS/DNS-SD Stack. Oct 16 03:01:36 volumiobuster sh[356]: eth0=eth0 Oct 16 03:01:36 volumiobuster systemd[1]: Started Login Service. Oct 16 03:01:36 volumiobuster sh[350]: wlan0=wlan0 Oct 16 03:01:36 volumiobuster systemd[1]: Reached target Sound Card. Oct 16 03:01:36 volumiobuster systemd[1]: Reached target RPC Port Mapper. Oct 16 03:01:36 volumiobuster systemd[1]: Reached target Remote File Systems (Pre). Oct 16 03:01:36 volumiobuster systemd[1]: Reached target Remote File Systems. Oct 16 03:01:36 volumiobuster systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Oct 16 03:01:36 volumiobuster systemd[1]: Started Raise network interfaces. Oct 16 03:01:36 volumiobuster dhcpcd-run-hooks[581]: wlan0: starting wpa_supplicant Oct 16 03:01:36 volumiobuster loadcpufreq[533]: Loading cpufreq kernel modules...done (none). Oct 16 03:01:36 volumiobuster systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Oct 16 03:01:36 volumiobuster systemd[1]: Starting LSB: set CPUFreq kernel parameters... Oct 16 03:01:37 volumiobuster kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xCDE1 Oct 16 03:01:37 volumiobuster systemd[1]: iptables.service: Succeeded. Oct 16 03:01:37 volumiobuster cpufrequtils[599]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Oct 16 03:01:37 volumiobuster systemd[1]: Started LSB: set CPUFreq kernel parameters. Oct 16 03:01:37 volumiobuster systemd[1]: Started WPA supplicant. Oct 16 03:01:37 volumiobuster systemd[1]: Reached target Network. Oct 16 03:01:37 volumiobuster wpa_supplicant[427]: Successfully initialized wpa_supplicant Oct 16 03:01:37 volumiobuster systemd[1]: Starting Network Time Service... Oct 16 03:01:37 volumiobuster systemd[1]: Starting Permit User Sessions... Oct 16 03:01:37 volumiobuster systemd[1]: Starting OpenBSD Secure Shell server... Oct 16 03:01:37 volumiobuster systemd[1]: Starting Music Player Daemon... Oct 16 03:01:37 volumiobuster systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Oct 16 03:01:37 volumiobuster systemd[1]: Reached target Network is Online. Oct 16 03:01:37 volumiobuster systemd[1]: Starting LSB: Brings up/down network automatically... Oct 16 03:01:37 volumiobuster systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 16 03:01:37 volumiobuster systemd[1]: Starting /etc/rc.local Compatibility... Oct 16 03:01:37 volumiobuster systemd[1]: Starting Samba NMB Daemon... Oct 16 03:01:37 volumiobuster systemd[1]: Started Permit User Sessions. Oct 16 03:01:37 volumiobuster kernel: random: crng init done Oct 16 03:01:37 volumiobuster kernel: random: 7 urandom warning(s) missed due to ratelimiting Oct 16 03:01:37 volumiobuster ifplugd(eth0)[647]: ifplugd 0.28 initializing. Oct 16 03:01:37 volumiobuster ifplugd(eth0)[647]: Using interface eth0/B8:27:EB:C7:4C:67 with driver (version: 22-Aug-2005) Oct 16 03:01:37 volumiobuster ifplugd(eth0)[647]: Using detection mode: SIOCETHTOOL Oct 16 03:01:37 volumiobuster ifplugd(eth0)[647]: Initialization complete, link beat detected. Oct 16 03:01:37 volumiobuster haveged[403]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Oct 16 03:01:37 volumiobuster haveged[403]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Oct 16 03:01:37 volumiobuster haveged[403]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00047 Oct 16 03:01:37 volumiobuster haveged[403]: haveged: fills: 0, generated: 0 Oct 16 03:01:37 volumiobuster ifplugd(eth0)[647]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 16 03:01:37 volumiobuster ntpd[619]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 16 03:01:37 volumiobuster systemd[1]: Started Network Time Service. Oct 16 03:01:37 volumiobuster ntpd[619]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Oct 16 03:01:37 volumiobuster ifplugd(eth0)[647]: client: ifup: interface eth0 already configured Oct 16 03:01:37 volumiobuster ifplugd(eth0)[647]: Program executed successfully. Oct 16 03:01:37 volumiobuster ifplugd[626]: Network Interface Plugging Daemon...start eth0...done. Oct 16 03:01:37 volumiobuster systemd[1]: Started LSB: Brings up/down network automatically. Oct 16 03:01:38 volumiobuster dhcpcd[508]: wlan0: connected to Access Point `' Oct 16 03:01:38 volumiobuster dhcpcd[508]: eth0: waiting for carrier Oct 16 03:01:38 volumiobuster dhcpcd[508]: wlan0: waiting for carrier Oct 16 03:01:38 volumiobuster dhcpcd[508]: eth0: carrier acquired Oct 16 03:01:38 volumiobuster dhcpcd[508]: DUID 00:01:00:01:28:92:92:2e:b8:27:eb:c7:4c:67 Oct 16 03:01:38 volumiobuster dhcpcd[508]: eth0: IAID eb:c7:4c:67 Oct 16 03:01:38 volumiobuster dhcpcd[508]: eth0: adding address fe80::ba27:ebff:fec7:4c67 Oct 16 03:01:38 volumiobuster dhcpcd[508]: ipv6_addaddr1: Permission denied Oct 16 03:01:38 volumiobuster dhcpcd[508]: eth0: probing address 192.168.0.26/24 Oct 16 03:01:38 volumiobuster ntpd[651]: proto: precision = 0.729 usec (-20) Oct 16 03:01:38 volumiobuster sshd[670]: Server listening on 0.0.0.0 port 22. Oct 16 03:01:38 volumiobuster sshd[670]: Server listening on :: port 22. Oct 16 03:01:38 volumiobuster systemd[1]: Started OpenBSD Secure Shell server. Oct 16 03:01:38 volumiobuster ntpd[651]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 16 03:01:38 volumiobuster ntpd[651]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 16 03:01:38 volumiobuster ntpd[651]: Listen and drop on 0 v6wildcard [::]:123 Oct 16 03:01:38 volumiobuster ntpd[651]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 16 03:01:38 volumiobuster ntpd[651]: Listen normally on 2 lo 127.0.0.1:123 Oct 16 03:01:38 volumiobuster ntpd[651]: Listening on routing socket on fd #19 for interface updates Oct 16 03:01:38 volumiobuster ntpd[651]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 16 03:01:38 volumiobuster ntpd[651]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 16 03:01:38 volumiobuster dhcpcd[508]: eth0: soliciting an IPv6 router Oct 16 03:01:38 volumiobuster systemd[1]: Reloading. Oct 16 03:01:38 volumiobuster volumio-remote-updater[449]: Error: No active session Oct 16 03:01:38 volumiobuster volumio-remote-updater[449]: [2021-10-16 03:01:38] [info] asio async_connect error: system:111 (Connection refused) Oct 16 03:01:38 volumiobuster volumio-remote-updater[449]: [2021-10-16 03:01:38] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 16 03:01:38 volumiobuster volumio-remote-updater[449]: [2021-10-16 03:01:38] [error] handle_connect error: Underlying Transport Error Oct 16 03:01:38 volumiobuster volumio[420]: Could not open config: /tmp/upmpdcli.conf Oct 16 03:01:38 volumiobuster lircd-setup[421]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Oct 16 03:01:38 volumiobuster lircd-setup[421]: parser = configparser.SafeConfigParser() Oct 16 03:01:38 volumiobuster 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. Oct 16 03:01:38 volumiobuster systemd[1]: /etc/systemd/system/rc-local.service:10: Support for option SysVStartPriority= has been removed and it is ignored Oct 16 03:01:39 volumiobuster dhcpcd[508]: wlan0: carrier acquired Oct 16 03:01:39 volumiobuster dhcpcd[508]: wlan0: connected to Access Point `Mapear 2.4 GHz' Oct 16 03:01:39 volumiobuster systemd[1]: volumiossh.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 16 03:01:39 volumiobuster dhcpcd[508]: wlan0: IAID eb:92:19:32 Oct 16 03:01:39 volumiobuster dhcpcd[508]: wlan0: adding address fe80::ba27:ebff:fe92:1932 Oct 16 03:01:39 volumiobuster dhcpcd[508]: ipv6_addaddr1: Permission denied Oct 16 03:01:39 volumiobuster dhcpcd[508]: wlan0: probing address 192.168.0.25/24 Oct 16 03:01:39 volumiobuster ntpd[651]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 16 03:01:39 volumiobuster nmbd[633]: [2021/10/16 03:01:39.252127, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Oct 16 03:01:39 volumiobuster nmbd[633]: started asyncdns process 692 Oct 16 03:01:39 volumiobuster nmbd[633]: [2021/10/16 03:01:39.264308, 0] ../lib/util/become_daemon.c:149(daemon_status) Oct 16 03:01:39 volumiobuster nmbd[633]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Oct 16 03:01:39 volumiobuster nmbd[633]: [2021/10/16 03:01:39.265129, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Oct 16 03:01:39 volumiobuster nmbd[633]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Oct 16 03:01:39 volumiobuster systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 16 03:01:39 volumiobuster systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 16 03:01:39 volumiobuster systemd[1]: lircd-setup.service: Succeeded. Oct 16 03:01:39 volumiobuster systemd[1]: Started lircd(8) initialization helper tool. Oct 16 03:01:39 volumiobuster systemd[1]: Starting Flexible IR remote input/output application support... Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Info: lircd: Opening log, level: Info Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Version: lircd 0.10.1 Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: System info: Linux volumiobuster 5.4.83-v7+ #1379 SMP Mon Dec 14 13:08:57 GMT 2020 armv7l GNU/Linux Oct 16 03:01:39 volumiobuster dhcpcd[508]: wlan0: soliciting an IPv6 router Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Info: Initial device: /dev/lirc0 Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Info: Initial device: /dev/lirc0 Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Info: [lirc] protocol is enabled Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Options: driver: default Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Options: output: /var/run/lirc/lircd Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Info: lircd: Opening log, level: Info Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Notice: Using systemd fd Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Options: nodaemon: 1 Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Warning: Running as root Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Options: logfile: syslog Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Options: immediate-init: 0 Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Options: permission: 666 Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Options: driver-options: Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Options: listen: 0 Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Options: connect: (null) Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Options: userelease: 0 Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Options: effective_user: (null) Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Options: release_suffix: _EVUP Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Options: allow_simulate: 0 Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Options: repeat_max: 600 Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Options: configfile: /etc/lirc/lircd.conf Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Options: dynamic_codes: (null) Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Current driver: default Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Driver API version: 3 Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Driver version: 0.10.0 Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Info: lircd: Opening log, level: Info Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: Using systemd fd Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Warning: Running as root Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Info: Using remote: devinput-64. Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Info: Using remote: devinput-64. Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MISC Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MOUSE Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_SOUTH Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MISC Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TOOL_PEN Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER_HAPPY1 Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MOUSE Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_SOUTH Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TOOL_PEN Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_WHEEL Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Info: Using remote: devinput-32. Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER_HAPPY1 Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_WHEEL Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Info: Using remote: devinput-32. Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MISC Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MOUSE Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MISC Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_SOUTH Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TOOL_PEN Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MOUSE Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER_HAPPY1 Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_SOUTH Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TOOL_PEN Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER_HAPPY1 Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_WHEEL Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_WHEEL Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Info: Using remote: sudo. Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Warning: sudo: garbage after 'name' token in line 11 ignored Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Info: Using remote: sudo. Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Warning: sudo: garbage after 'name' token in line 11 ignored Oct 16 03:01:39 volumiobuster lircd[694]: lircd-0.10.1[694]: Notice: lircd(default) ready, using /var/run/lirc/lircd Oct 16 03:01:39 volumiobuster lircd-0.10.1[694]: Notice: lircd(default) ready, using /var/run/lirc/lircd Oct 16 03:01:39 volumiobuster systemd[1]: Started Flexible IR remote input/output application support. Oct 16 03:01:39 volumiobuster systemd[1]: Reloading. Oct 16 03:01:40 volumiobuster 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. Oct 16 03:01:40 volumiobuster systemd[1]: /etc/systemd/system/rc-local.service:10: Support for option SysVStartPriority= has been removed and it is ignored Oct 16 03:01:40 volumiobuster ntpd[651]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 16 03:01:40 volumiobuster systemd[1]: systemd-rfkill.service: Succeeded. Oct 16 03:01:40 volumiobuster systemd[1]: Reloading. Oct 16 03:01:40 volumiobuster kernel: Bluetooth: Core ver 2.22 Oct 16 03:01:40 volumiobuster kernel: NET: Registered protocol family 31 Oct 16 03:01:40 volumiobuster kernel: Bluetooth: HCI device and connection manager initialized Oct 16 03:01:40 volumiobuster kernel: Bluetooth: HCI socket layer initialized Oct 16 03:01:40 volumiobuster kernel: Bluetooth: L2CAP socket layer initialized Oct 16 03:01:40 volumiobuster kernel: Bluetooth: SCO socket layer initialized Oct 16 03:01:40 volumiobuster lircd[694]: lircd-0.10.1[694]: Notice: accepted new client on /var/run/lirc/lircd Oct 16 03:01:40 volumiobuster lircd[694]: lircd-0.10.1[694]: Info: [lirc] protocol is enabled Oct 16 03:01:40 volumiobuster lircd-0.10.1[694]: Notice: accepted new client on /var/run/lirc/lircd Oct 16 03:01:40 volumiobuster lircd-0.10.1[694]: Info: [lirc] protocol is enabled Oct 16 03:01:40 volumiobuster kernel: Bluetooth: HCI UART driver ver 2.3 Oct 16 03:01:40 volumiobuster kernel: Bluetooth: HCI UART protocol H4 registered Oct 16 03:01:40 volumiobuster kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Oct 16 03:01:40 volumiobuster kernel: Bluetooth: HCI UART protocol Broadcom registered Oct 16 03:01:40 volumiobuster btuart[440]: bcm43xx_init Oct 16 03:01:40 volumiobuster btuart[440]: Flash firmware /lib/firmware/brcm/BCM43430A1.hcd Oct 16 03:01:40 volumiobuster btuart[440]: Set BDADDR UART: b8:27:eb:6d:e6:cd Oct 16 03:01:40 volumiobuster btuart[440]: Set Controller UART speed to 460800 bit/s Oct 16 03:01:40 volumiobuster btuart[440]: Device setup complete Oct 16 03:01:40 volumiobuster systemd-udevd[720]: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1. Oct 16 03:01:40 volumiobuster 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. Oct 16 03:01:41 volumiobuster systemd[1]: /etc/systemd/system/rc-local.service:10: Support for option SysVStartPriority= has been removed and it is ignored Oct 16 03:01:41 volumiobuster ntpd[651]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Oct 16 03:01:41 volumiobuster wireless.js[475]: WIRELESS DAEMON: start Oct 16 03:01:41 volumiobuster wireless.js[475]: WIRELESS: Loaded configuration Oct 16 03:01:41 volumiobuster wireless.js[475]: Cleaning previous... Oct 16 03:01:41 volumiobuster systemd[1]: Started Configure Bluetooth Modems connected by UART. Oct 16 03:01:41 volumiobuster systemd[1]: Started /etc/rc.local Compatibility. Oct 16 03:01:41 volumiobuster systemd[1]: Starting Load/Save RF Kill Switch Status... Oct 16 03:01:41 volumiobuster systemd[1]: Created slice system-bthelper.slice. Oct 16 03:01:41 volumiobuster systemd[1]: Starting Raspberry Pi bluetooth helper... Oct 16 03:01:41 volumiobuster systemd[1]: Starting Terminate Plymouth Boot Screen... Oct 16 03:01:41 volumiobuster systemd[1]: Starting Hold until boot process finishes up... Oct 16 03:01:41 volumiobuster systemd[1]: volumiossh.service: Succeeded. Oct 16 03:01:41 volumiobuster systemd[1]: Started Volumio SSH enabler. Oct 16 03:01:41 volumiobuster systemd[1]: Started Load/Save RF Kill Switch Status. Oct 16 03:01:41 volumiobuster systemd[1]: Received SIGRTMIN+21 from PID 223 (plymouthd). Oct 16 03:01:41 volumiobuster systemd[1]: plymouth-quit.service: Succeeded. Oct 16 03:01:41 volumiobuster bthelper[744]: Raspberry Pi BDADDR already set Oct 16 03:01:41 volumiobuster systemd[1]: Started Terminate Plymouth Boot Screen. Oct 16 03:01:41 volumiobuster systemd[1]: plymouth-start.service: Succeeded. Oct 16 03:01:41 volumiobuster systemd[1]: plymouth-quit-wait.service: Succeeded. Oct 16 03:01:41 volumiobuster systemd[1]: Started Hold until boot process finishes up. Oct 16 03:01:41 volumiobuster systemd[1]: Started Raspberry Pi bluetooth helper. Oct 16 03:01:41 volumiobuster systemd[1]: Received SIGRTMIN+21 from PID 223 (n/a). Oct 16 03:01:41 volumiobuster systemd[1]: Starting Bluetooth service... Oct 16 03:01:41 volumiobuster systemd[1]: Started Getty on tty1. Oct 16 03:01:41 volumiobuster systemd[1]: Started Serial Getty on ttyAMA0. Oct 16 03:01:41 volumiobuster systemd[1]: Reached target Login Prompts. Oct 16 03:01:41 volumiobuster bluetoothd[756]: Bluetooth daemon 5.50 Oct 16 03:01:41 volumiobuster bluetoothd[756]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Oct 16 03:01:41 volumiobuster systemd[1]: Started Bluetooth service. Oct 16 03:01:41 volumiobuster systemd[1]: Reached target Bluetooth. Oct 16 03:01:41 volumiobuster bluetoothd[756]: Starting SDP server Oct 16 03:01:41 volumiobuster bluetoothd[756]: Excluding (cli) sap Oct 16 03:01:41 volumiobuster dbus-daemon[419]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=756 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Oct 16 03:01:41 volumiobuster kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Oct 16 03:01:41 volumiobuster kernel: Bluetooth: BNEP filters: protocol multicast Oct 16 03:01:41 volumiobuster kernel: Bluetooth: BNEP socket layer initialized Oct 16 03:01:41 volumiobuster bluetoothd[756]: Bluetooth management interface 1.14 initialized Oct 16 03:01:41 volumiobuster sudo[760]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 16 03:01:41 volumiobuster sudo[760]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:41 volumiobuster sudo[760]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:41 volumiobuster sudo[763]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 16 03:01:41 volumiobuster sudo[763]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:41 volumiobuster systemd[1]: Starting Hostname Service... Oct 16 03:01:41 volumiobuster mpd[653]: Oct 16 03:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 16 03:01:41 volumiobuster bluetoothd[756]: Failed to set privacy: Rejected (0x0b) Oct 16 03:01:42 volumiobuster mpd[653]: Oct 16 03:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 16 03:01:42 volumiobuster mpd[653]: Oct 16 03:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 16 03:01:42 volumiobuster ntpd[651]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 16 03:01:42 volumiobuster dhcpcd[508]: eth0: using static address 192.168.0.26/24 Oct 16 03:01:42 volumiobuster sudo[763]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:42 volumiobuster avahi-daemon[473]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.26. Oct 16 03:01:42 volumiobuster avahi-daemon[473]: New relevant interface eth0.IPv4 for mDNS. Oct 16 03:01:42 volumiobuster dhcpcd[508]: eth0: adding route to 192.168.0.0/24 Oct 16 03:01:42 volumiobuster avahi-daemon[473]: Registering new address record for 192.168.0.26 on eth0.IPv4. Oct 16 03:01:42 volumiobuster dhcpcd[508]: eth0: adding default route via 192.168.0.1 Oct 16 03:01:42 volumiobuster systemd[1]: Started Music Player Daemon. Oct 16 03:01:42 volumiobuster dhcpcd[508]: wlan0: carrier lost Oct 16 03:01:42 volumiobuster wireless.js[475]: Stopped aP Oct 16 03:01:42 volumiobuster wireless.js[475]: Start wireless flow Oct 16 03:01:42 volumiobuster wireless.js[475]: Stopped hotspot (if there).. Oct 16 03:01:42 volumiobuster sudo[799]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 16 03:01:42 volumiobuster sudo[799]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:42 volumiobuster sudo[799]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:42 volumiobuster dbus-daemon[419]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 16 03:01:42 volumiobuster systemd[1]: Started Hostname Service. Oct 16 03:01:42 volumiobuster sudo[810]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 16 03:01:42 volumiobuster sudo[810]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:42 volumiobuster sudo[810]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:42 volumiobuster wireless.js[475]: DHCP IP Oct 16 03:01:42 volumiobuster wireless.js[475]: Start ap Oct 16 03:01:42 volumiobuster wpa_supplicant[824]: Successfully initialized wpa_supplicant Oct 16 03:01:42 volumiobuster sudo[827]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Oct 16 03:01:42 volumiobuster sudo[827]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:42 volumiobuster kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 16 03:01:42 volumiobuster dhcpcd[832]: sending commands to master dhcpcd process Oct 16 03:01:42 volumiobuster dhcpcd[508]: control command: /sbin/dhcpcd Oct 16 03:01:42 volumiobuster sudo[827]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:42 volumiobuster dhcpcd[508]: lo: carrier acquired Oct 16 03:01:42 volumiobuster dhcpcd[508]: lo: IAID 6c:6f:00:00 Oct 16 03:01:42 volumiobuster dhcpcd[508]: lo: ipv6_start: Operation not supported Oct 16 03:01:42 volumiobuster dhcpcd[508]: lo: using static address 127.0.0.1/8 Oct 16 03:01:42 volumiobuster dhcpcd[508]: lo: adding route to 127.0.0.0/8 Oct 16 03:01:42 volumiobuster dhcpcd[508]: arp_announce: Invalid argument Oct 16 03:01:43 volumiobuster volumio-remote-updater[449]: [2021-10-16 03:01:43] [info] asio async_connect error: system:111 (Connection refused) Oct 16 03:01:43 volumiobuster volumio-remote-updater[449]: [2021-10-16 03:01:43] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 16 03:01:43 volumiobuster volumio-remote-updater[449]: [2021-10-16 03:01:43] [error] handle_connect error: Underlying Transport Error Oct 16 03:01:43 volumiobuster wpa_supplicant[849]: wlan0: Trying to associate with SSID 'Mapear 2.4 GHz' Oct 16 03:01:43 volumiobuster wireless.js[475]: trying... Oct 16 03:01:43 volumiobuster sudo[913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 03:01:43 volumiobuster sudo[913]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:43 volumiobuster sudo[913]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:44 volumiobuster dhcpcd[508]: wlan0: carrier acquired Oct 16 03:01:44 volumiobuster wpa_supplicant[849]: wlan0: Associated with 3c:b7:4b:3a:4d:50 Oct 16 03:01:44 volumiobuster wpa_supplicant[849]: wlan0: CTRL-EVENT-CONNECTED - Connection to 3c:b7:4b:3a:4d:50 completed [id=0 id_str=] Oct 16 03:01:44 volumiobuster wpa_supplicant[849]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 16 03:01:44 volumiobuster wpa_supplicant[849]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=AR Oct 16 03:01:44 volumiobuster dhcpcd[508]: wlan0: IAID eb:92:19:32 Oct 16 03:01:44 volumiobuster dhcpcd[508]: wlan0: probing address 192.168.0.25/24 Oct 16 03:01:44 volumiobuster dhcpcd[508]: wlan0: carrier lost Oct 16 03:01:44 volumiobuster nmbd[633]: [2021/10/16 03:01:44.279192, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 16 03:01:44 volumiobuster systemd[1]: Started Samba NMB Daemon. Oct 16 03:01:44 volumiobuster nmbd[633]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 16 03:01:44 volumiobuster systemd[1]: Starting Samba Winbind Daemon... Oct 16 03:01:44 volumiobuster dhcpcd[508]: wlan0: carrier acquired Oct 16 03:01:44 volumiobuster dhcpcd[508]: wlan0: IAID eb:92:19:32 Oct 16 03:01:44 volumiobuster dhcpcd[508]: wlan0: probing address 192.168.0.25/24 Oct 16 03:01:44 volumiobuster winbindd[937]: [2021/10/16 03:01:44.601914, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Oct 16 03:01:44 volumiobuster winbindd[937]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 16 03:01:44 volumiobuster winbindd[937]: [2021/10/16 03:01:44.634915, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 16 03:01:44 volumiobuster winbindd[937]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 16 03:01:44 volumiobuster systemd[1]: Started Samba Winbind Daemon. Oct 16 03:01:44 volumiobuster systemd[1]: Starting Samba SMB Daemon... Oct 16 03:01:44 volumiobuster wireless.js[475]: trying... Oct 16 03:01:44 volumiobuster sudo[958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 03:01:44 volumiobuster sudo[958]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:44 volumiobuster sudo[958]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:45 volumiobuster winbindd[937]: [2021/10/16 03:01:45.176195, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Oct 16 03:01:45 volumiobuster winbindd[937]: res_names->count = 3, expected 4 Oct 16 03:01:45 volumiobuster smbd[956]: [2021/10/16 03:01:45.212727, 0] ../lib/util/become_daemon.c:138(daemon_ready) Oct 16 03:01:45 volumiobuster systemd[1]: Started Samba SMB Daemon. Oct 16 03:01:45 volumiobuster smbd[956]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 16 03:01:45 volumiobuster dhcpcd[508]: wlan0: soliciting an IPv6 router Oct 16 03:01:45 volumiobuster wireless.js[475]: trying... Oct 16 03:01:45 volumiobuster sudo[968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 03:01:45 volumiobuster sudo[968]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:45 volumiobuster sudo[968]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:46 volumiobuster ntpd[651]: Listen normally on 3 eth0 192.168.0.26:123 Oct 16 03:01:46 volumiobuster ntpd[651]: new interface(s) found: waking up resolver Oct 16 03:01:46 volumiobuster systemd[1]: systemd-rfkill.service: Succeeded. Oct 16 03:01:46 volumiobuster bthelper[744]: Changing power off succeeded Oct 16 03:01:46 volumiobuster wireless.js[475]: trying... Oct 16 03:01:46 volumiobuster sudo[972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 03:01:46 volumiobuster sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:46 volumiobuster sudo[972]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:46 volumiobuster bthelper[744]: [CHG] Controller B8:27:EB:6D:E6:CD Class: 0x0000041c Oct 16 03:01:46 volumiobuster bthelper[744]: Changing power on succeeded Oct 16 03:01:47 volumiobuster wireless.js[475]: trying... Oct 16 03:01:47 volumiobuster sudo[981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 03:01:47 volumiobuster sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:47 volumiobuster sudo[981]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:48 volumiobuster wireless.js[475]: trying... Oct 16 03:01:48 volumiobuster sudo[984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 03:01:48 volumiobuster sudo[984]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:48 volumiobuster sudo[984]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:49 volumiobuster wireless.js[475]: trying... Oct 16 03:01:49 volumiobuster sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 03:01:49 volumiobuster sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:49 volumiobuster sudo[987]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:49 volumiobuster dhcpcd[508]: wlan0: using static address 192.168.0.25/24 Oct 16 03:01:49 volumiobuster avahi-daemon[473]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.25. Oct 16 03:01:49 volumiobuster avahi-daemon[473]: New relevant interface wlan0.IPv4 for mDNS. Oct 16 03:01:49 volumiobuster dhcpcd[508]: wlan0: adding route to 192.168.0.0/24 Oct 16 03:01:49 volumiobuster avahi-daemon[473]: Registering new address record for 192.168.0.25 on wlan0.IPv4. Oct 16 03:01:49 volumiobuster dhcpcd[508]: wlan0: adding default route via 192.168.0.1 Oct 16 03:01:50 volumiobuster wireless.js[475]: trying... Oct 16 03:01:50 volumiobuster sudo[1011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 16 03:01:50 volumiobuster sudo[1011]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:50 volumiobuster sudo[1011]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:50 volumiobuster wireless.js[475]: Connected to: ----Mapear 2.4 GHz Oct 16 03:01:50 volumiobuster wireless.js[475]: ---- Oct 16 03:01:50 volumiobuster sudo[1014]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 03:01:50 volumiobuster sudo[1014]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:50 volumiobuster sudo[1014]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:50 volumiobuster wireless.js[475]: ... joined AP, wlan0 IPv4 is 192.168.0.25, ipV6 is undefined Oct 16 03:01:50 volumiobuster wireless.js[475]: It's done! AP Oct 16 03:01:50 volumiobuster systemd[1]: Started Wireless Services. Oct 16 03:01:50 volumiobuster systemd[1]: Started Volumio Backend Module. Oct 16 03:01:50 volumiobuster systemd[1]: Started Volumio Streaming Daemon. Oct 16 03:01:50 volumiobuster systemd[1]: Reached target Multi-User System. Oct 16 03:01:50 volumiobuster systemd[1]: Reached target Graphical Interface. Oct 16 03:01:50 volumiobuster systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 16 03:01:50 volumiobuster volumio-remote-updater[449]: [2021-10-16 03:01:50] [info] asio async_connect error: system:111 (Connection refused) Oct 16 03:01:50 volumiobuster volumio-remote-updater[449]: [2021-10-16 03:01:50] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 16 03:01:50 volumiobuster volumio-remote-updater[449]: [2021-10-16 03:01:50] [error] handle_connect error: Underlying Transport Error Oct 16 03:01:50 volumiobuster systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Oct 16 03:01:50 volumiobuster systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 16 03:01:50 volumiobuster systemd[1]: Startup finished in 11.607s (kernel) + 21.821s (userspace) = 33.429s. Oct 16 03:01:51 volumiobuster volumio-streaming-daemon[1019]: ############################ Oct 16 03:01:51 volumiobuster volumio-streaming-daemon[1019]: # Volumio Streaming Daemon # Oct 16 03:01:51 volumiobuster volumio-streaming-daemon[1019]: # Running on port 7777 # Oct 16 03:01:51 volumiobuster volumio-streaming-daemon[1019]: ############################ Oct 16 03:01:51 volumiobuster volumio-streaming-daemon[1019]: Environment: production Oct 16 03:01:51 volumiobuster ntpd[651]: Listen normally on 4 wlan0 192.168.0.25:123 Oct 16 03:01:51 volumiobuster ntpd[651]: new interface(s) found: waking up resolver Oct 16 03:01:53 volumiobuster volumio[1018]: info: ------------------------------------------- Oct 16 03:01:53 volumiobuster volumio[1018]: info: ----- Volumio2 ---- Oct 16 03:01:53 volumiobuster volumio[1018]: info: ------------------------------------------- Oct 16 03:01:53 volumiobuster volumio[1018]: info: ----- System startup ---- Oct 16 03:01:53 volumiobuster volumio[1018]: info: ------------------------------------------- Oct 16 03:01:55 volumiobuster volumio[1018]: info: MYVOLUMIO Environment detected Oct 16 03:01:55 volumiobuster volumio[1018]: info: Plugin folders cleanup Oct 16 03:01:55 volumiobuster volumio[1018]: info: Scanning into folder /volumio/app/plugins/ Oct 16 03:01:55 volumiobuster volumio[1018]: info: Scanning category audio_interface Oct 16 03:01:55 volumiobuster volumio[1018]: info: Scanning category miscellanea Oct 16 03:01:55 volumiobuster volumio[1018]: info: Scanning category music_service Oct 16 03:01:55 volumiobuster volumio[1018]: info: Scanning category plugins.json Oct 16 03:01:55 volumiobuster volumio[1018]: info: Scanning category system_controller Oct 16 03:01:55 volumiobuster volumio[1018]: info: Scanning category user_interface Oct 16 03:01:55 volumiobuster volumio[1018]: info: Scanning into folder /data/plugins/ Oct 16 03:01:55 volumiobuster volumio[1018]: info: Scanning category audio_interface Oct 16 03:01:55 volumiobuster volumio[1018]: info: Scanning category miscellanea Oct 16 03:01:55 volumiobuster volumio[1018]: info: Scanning category music_service Oct 16 03:01:55 volumiobuster volumio[1018]: info: Plugin folders cleanup completed Oct 16 03:01:55 volumiobuster volumio[1018]: info: ------------------------------------------- Oct 16 03:01:55 volumiobuster volumio[1018]: info: ----- Core plugins startup ---- Oct 16 03:01:55 volumiobuster volumio[1018]: info: ------------------------------------------- Oct 16 03:01:55 volumiobuster volumio[1018]: info: Loading plugins from folder /volumio/app/plugins/ Oct 16 03:01:55 volumiobuster volumio[1018]: info: Adding plugin upnp to MyMusic Plugins Oct 16 03:01:55 volumiobuster volumio[1018]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 16 03:01:55 volumiobuster volumio[1018]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 16 03:01:55 volumiobuster volumio[1018]: info: Loading plugins from folder /data/plugins/ Oct 16 03:01:55 volumiobuster volumio[1018]: info: Loading plugin "system"... Oct 16 03:01:55 volumiobuster volumio[1018]: info: Loading plugin "appearance"... Oct 16 03:01:56 volumiobuster volumio[1018]: info: Loading plugin "network"... Oct 16 03:01:56 volumiobuster volumio[1018]: info: Refreshing Cached IP Addresses Oct 16 03:01:56 volumiobuster volumio[1018]: info: Loading plugin "services"... Oct 16 03:01:56 volumiobuster sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 03:01:56 volumiobuster sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:56 volumiobuster volumio[1018]: info: Loading plugin "alsa_controller"... Oct 16 03:01:56 volumiobuster sudo[1043]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:56 volumiobuster sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 03:01:56 volumiobuster sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:56 volumiobuster sudo[1045]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:57 volumiobuster volumio[1018]: info: Loading plugin "wizard"... Oct 16 03:01:57 volumiobuster volumio[1018]: info: Loading plugin "volumio_command_line_client"... Oct 16 03:01:57 volumiobuster volumio[1018]: info: Loading plugin "upnp"... Oct 16 03:01:57 volumiobuster volumio[1018]: info: [1634353317186] Starting Upmpd Daemon Oct 16 03:01:57 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 03:01:57 volumiobuster volumio[1018]: info: Loading plugin "my_music"... Oct 16 03:01:57 volumiobuster volumio[1018]: info: Loading plugin "mpd"... Oct 16 03:01:57 volumiobuster volumio[1018]: info: Creating MPD Configuration file Oct 16 03:01:57 volumiobuster volumio[1018]: info: Loading plugin "upnp_browser"... Oct 16 03:01:57 volumiobuster sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 03:01:57 volumiobuster sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:57 volumiobuster sudo[1053]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:57 volumiobuster sudo[1055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 03:01:57 volumiobuster sudo[1055]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:57 volumiobuster systemd[1]: Stopping Music Player Daemon... Oct 16 03:01:58 volumiobuster systemd[1]: mpd.service: Succeeded. Oct 16 03:01:58 volumiobuster systemd[1]: Stopped Music Player Daemon. Oct 16 03:01:58 volumiobuster systemd[1]: Starting Music Player Daemon... Oct 16 03:01:58 volumiobuster volumio[1018]: info: Loading plugin "networkfs"... Oct 16 03:01:58 volumiobuster volumio[1018]: info: Starting Udev Watcher for removable devices Oct 16 03:01:58 volumiobuster sudo[1063]: 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.0.18/Musica_Flac_DSD /mnt/NAS/Musica_PC Oct 16 03:01:58 volumiobuster sudo[1063]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:01:58 volumiobuster kernel: FS-Cache: Netfs 'cifs' registered for caching Oct 16 03:01:58 volumiobuster kernel: Key type cifs.spnego registered Oct 16 03:01:58 volumiobuster kernel: Key type cifs.idmap registered Oct 16 03:01:58 volumiobuster kernel: CIFS: Attempting to mount //192.168.0.18/Musica_Flac_DSD Oct 16 03:01:58 volumiobuster kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Oct 16 03:01:59 volumiobuster sudo[1063]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:59 volumiobuster volumio[1018]: info: Ignoring mount for partition: boot Oct 16 03:01:59 volumiobuster volumio[1018]: info: Ignoring mount for partition: volumio Oct 16 03:01:59 volumiobuster volumio[1018]: info: Ignoring mount for partition: volumio_data Oct 16 03:01:59 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 03:01:59 volumiobuster volumio[1018]: info: Loading plugin "alarm-clock"... Oct 16 03:01:59 volumiobuster volumio[1018]: info: Loading plugin "airplay_emulation"... Oct 16 03:01:59 volumiobuster volumio[1018]: info: Starting Shairport Sync Oct 16 03:01:59 volumiobuster volumio[1018]: info: Loading plugin "last_100"... Oct 16 03:01:59 volumiobuster volumio[1018]: info: Loading plugin "webradio"... Oct 16 03:01:59 volumiobuster mpd[1060]: Oct 16 03:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 16 03:01:59 volumiobuster mpd[1060]: Oct 16 03:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 16 03:01:59 volumiobuster mpd[1060]: Oct 16 03:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 16 03:01:59 volumiobuster systemd[1]: Started Music Player Daemon. Oct 16 03:01:59 volumiobuster sudo[1055]: pam_unix(sudo:session): session closed for user root Oct 16 03:01:59 volumiobuster volumio[1018]: info: Loading plugin "i2s_dacs"... Oct 16 03:01:59 volumiobuster volumio[1018]: info: Loading plugin "volumiodiscovery"... Oct 16 03:01:59 volumiobuster volumio[1018]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 16 03:01:59 volumiobuster node[1018]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 16 03:01:59 volumiobuster volumio[1018]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 03:01:59 volumiobuster node[1018]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 03:01:59 volumiobuster volumio[1018]: *** WARNING *** For more information see Oct 16 03:01:59 volumiobuster node[1018]: *** WARNING *** For more information see Oct 16 03:01:59 volumiobuster volumio[1018]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 16 03:01:59 volumiobuster node[1018]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 16 03:01:59 volumiobuster volumio[1018]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 03:01:59 volumiobuster node[1018]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 16 03:01:59 volumiobuster volumio[1018]: *** WARNING *** For more information see Oct 16 03:01:59 volumiobuster node[1018]: *** WARNING *** For more information see Oct 16 03:01:59 volumiobuster volumio[1018]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 16 03:01:59 volumiobuster volumio[1018]: info: Discovery: Started advertising with name: VolumioBuster Oct 16 03:01:59 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 03:01:59 volumiobuster volumio[1018]: info: Loading plugin "auto_play"... Oct 16 03:01:59 volumiobuster volumio[1018]: info: ControllerAutoPlay - connecting mpd on host: localhost; port: 6600 Oct 16 03:01:59 volumiobuster volumio[1018]: info: Loading plugin "outputs"... Oct 16 03:01:59 volumiobuster volumio[1018]: info: Loading plugin "albumart"... Oct 16 03:01:59 volumiobuster volumio[1018]: info: Plugin example_plugin is not enabled Oct 16 03:01:59 volumiobuster volumio[1018]: info: Loading plugin "inputs"... Oct 16 03:01:59 volumiobuster volumio[1018]: info: Loading plugin "updater_comm"... Oct 16 03:02:00 volumiobuster volumio[1018]: info: Plugin mpdemulation is not enabled Oct 16 03:02:00 volumiobuster volumio[1018]: info: Loading plugin "rest_api"... Oct 16 03:02:00 volumiobuster volumio[1018]: info: Loading plugin "websocket"... Oct 16 03:02:00 volumiobuster volumio[1018]: info: Loading plugin "fusiondsp"... Oct 16 03:02:00 volumiobuster volumio[1018]: Forking 3 albumart workers Oct 16 03:02:00 volumiobuster volumio[1018]: info: Applying required configuration parameters for plugin fusiondsp Oct 16 03:02:00 volumiobuster volumio-remote-updater[449]: [2021-10-16 03:02:00] [connect] Successful connection Oct 16 03:02:00 volumiobuster volumio[1018]: info: Loading plugin "radio_paradise"... Oct 16 03:02:01 volumiobuster volumio[1018]: Starting albumart workers Oct 16 03:02:01 volumiobuster volumio[1018]: Starting albumart workers Oct 16 03:02:01 volumiobuster volumio[1018]: Starting albumart workers Oct 16 03:02:01 volumiobuster volumio[1018]: info: Applying required configuration parameters for plugin radio_paradise Oct 16 03:02:01 volumiobuster volumio[1018]: info: [1634353321515] [RadioParadise] API delay: 5 Oct 16 03:02:01 volumiobuster volumio[1018]: info: Loading plugin "volspotconnect2"... Oct 16 03:02:02 volumiobuster volumio[1018]: info: Loading i18n strings for locale es Oct 16 03:02:02 volumiobuster volumio[1018]: Updating browse sources language Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::initPlayerControls Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: Express server listening on port 3000 Oct 16 03:02:02 volumiobuster volumio[1018]: [Metrics] WebUI: 9s 743.69ms Oct 16 03:02:02 volumiobuster volumio[1018]: Volumio Calling Home Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreStateMachine::resetVolumioState Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreStateMachine::getcurrentVolume Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioRetrievevolume Oct 16 03:02:02 volumiobuster volumio[1018]: info: MPD Permissions set Oct 16 03:02:02 volumiobuster volumio-remote-updater[449]: [2021-10-16 03:02:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1634353320 101 Oct 16 03:02:02 volumiobuster volumio[1018]: info: ControllerAutoPlay - mpd ready Oct 16 03:02:02 volumiobuster volumio[1018]: info: MPD running with PID1060 Oct 16 03:02:02 volumiobuster volumio[1018]: ,establishing connection Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:02 volumiobuster volumio[1018]: info: Reloading queue from file Oct 16 03:02:02 volumiobuster volumio[1018]: info: Oct 16 03:02:02 volumiobuster volumio[1018]: ---------------------------- Client requests Volume 20 Oct 16 03:02:02 volumiobuster volumio[1018]: info: VolumeController::SetAlsaVolume20 Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreStateMachine::pushState Oct 16 03:02:03 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 16 03:02:03 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:03 volumiobuster volumio[1018]: error: updateQueue error: null Oct 16 03:02:03 volumiobuster volumio[1018]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 16 03:02:03 volumiobuster volumio[1018]: info: VolumeController:: Volume=0 Mute =false Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreStateMachine::pushState Oct 16 03:02:03 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreStateMachine::updateTrackBlock Oct 16 03:02:03 volumiobuster volumio[1018]: info: CorePlayQueue::getTrackBlock Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioRetrievevolume Oct 16 03:02:03 volumiobuster volumio[1018]: info: Setting Device type: Raspberry PI Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreStateMachine::setRepeat null single undefined Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreStateMachine::pushState Oct 16 03:02:03 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreStateMachine::setRandom null Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreStateMachine::pushState Oct 16 03:02:03 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 03:02:03 volumiobuster volumio[1018]: info: Completed loading Core Plugins Oct 16 03:02:03 volumiobuster volumio[1018]: info: Preparing to generate the ALSA configuration file Oct 16 03:02:03 volumiobuster volumio[1018]: info: Volumio called home Oct 16 03:02:03 volumiobuster volumio[1018]: info: Setting volume on startup at 20 Oct 16 03:02:03 volumiobuster volumio[1018]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 16 03:02:03 volumiobuster volumio[1018]: info: Reading ALSA contributions from plugins. Oct 16 03:02:03 volumiobuster volumio[1018]: error: updateQueue error: null Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 16 03:02:03 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:03 volumiobuster volumio[1018]: info: VolumeController:: Volume=20 Mute =false Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreStateMachine::pushState Oct 16 03:02:03 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 03:02:03 volumiobuster volumio[1018]: info: Asound.conf file unchanged, so no further update is needed Oct 16 03:02:03 volumiobuster volumio[1018]: info: Output device has changed, restarting MPD Oct 16 03:02:03 volumiobuster volumio[1018]: info: Output device has changed, restarting Shairport Sync Oct 16 03:02:03 volumiobuster sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 03:02:03 volumiobuster sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:03 volumiobuster sudo[1175]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:03 volumiobuster sudo[1177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 03:02:03 volumiobuster sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:03 volumiobuster systemd[1]: Stopping Music Player Daemon... Oct 16 03:02:03 volumiobuster volumio[1018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 03:02:03 volumiobuster volumio[1018]: info: ___________ START PLUGINS ___________ Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 03:02:03 volumiobuster volumio[1018]: info: [1634353323808] CoreMusicLibrary::Adding element Media Servers Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 03:02:03 volumiobuster volumio[1018]: Cannot find translation for sourceMedia Servers Oct 16 03:02:03 volumiobuster systemd[1]: mpd.service: Succeeded. Oct 16 03:02:03 volumiobuster systemd[1]: Stopped Music Player Daemon. Oct 16 03:02:03 volumiobuster systemd[1]: Starting Music Player Daemon... Oct 16 03:02:03 volumiobuster volumio[1018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 03:02:03 volumiobuster volumio[1018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 03:02:03 volumiobuster volumio[1018]: info: [1634353323953] CoreMusicLibrary::Adding element Last_100 Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 03:02:03 volumiobuster volumio[1018]: Cannot find translation for sourceMedia Servers Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 03:02:03 volumiobuster volumio[1018]: info: [1634353323959] CoreMusicLibrary::Adding element Webradio Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 03:02:03 volumiobuster volumio[1018]: Cannot find translation for sourceMedia Servers Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 03:02:03 volumiobuster volumio[1018]: info: Loading i18n strings for locale es Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 16 03:02:03 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 16 03:02:04 volumiobuster volumio[1018]: info: [1634353324063] CoreMusicLibrary::Adding element Radio Paradise Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 03:02:04 volumiobuster volumio[1018]: Cannot find translation for sourceMedia Servers Oct 16 03:02:04 volumiobuster volumio[1018]: Cannot find translation for sourceRadio Paradise Oct 16 03:02:04 volumiobuster volumio[1018]: [SpotifyConnect] Creating VLS config file Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: [SpotifyConnect] Starting metadata listener Oct 16 03:02:04 volumiobuster sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Oct 16 03:02:04 volumiobuster sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:04 volumiobuster volumio[1018]: info: Preparing to generate the ALSA configuration file Oct 16 03:02:04 volumiobuster systemd[1]: Started Volspotconnect2 Daemon. Oct 16 03:02:04 volumiobuster sudo[1195]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:04 volumiobuster volumio[1018]: info: Discovery: adding 08e4ca6c-0cd9-4171-adf5-4a745e3af0fa Oct 16 03:02:04 volumiobuster volumio[1018]: info: mDNS: Found device VolumioBuster Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 16 03:02:04 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:04 volumiobuster volumio[1018]: info: Discovery: this is already registered, 08e4ca6c-0cd9-4171-adf5-4a745e3af0fa Oct 16 03:02:04 volumiobuster volumio[1018]: info: mDNS: Found device VolumioBuster Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 16 03:02:04 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:04 volumiobuster volumio[1018]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 16 03:02:04 volumiobuster volumio[1018]: info: Reading ALSA contributions from plugins. Oct 16 03:02:04 volumiobuster volumio[1197]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 Oct 16 03:02:04 volumiobuster volumio[1197]: Reading Config from "volspotify.toml" Oct 16 03:02:04 volumiobuster volumio[1018]: [SpotifyConnect] Vollibrespot Daemon service started! Oct 16 03:02:04 volumiobuster volumio[1018]: [Metrics] SpotifyConnect: 0s 273.12ms Oct 16 03:02:04 volumiobuster volumio[1018]: info: Completed starting Core Plugins Oct 16 03:02:04 volumiobuster volumio[1018]: info: ------------------------------------------- Oct 16 03:02:04 volumiobuster volumio[1018]: info: ----- MyVolumio plugins startup ---- Oct 16 03:02:04 volumiobuster volumio[1018]: info: ------------------------------------------- Oct 16 03:02:04 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 16 03:02:04 volumiobuster volumio[1018]: info: MPD Permissions set Oct 16 03:02:04 volumiobuster volumio[1197]: [Vollibrespot] : Using Alsa backend with device: volumio Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: Starting Shairport Sync Oct 16 03:02:04 volumiobuster volumio[1197]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Oct 16 03:02:04 volumiobuster volumio[1018]: info: Starting Shairport Sync Oct 16 03:02:04 volumiobuster volumio[1018]: info: Starting Shairport Sync Oct 16 03:02:04 volumiobuster sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 03:02:04 volumiobuster sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:04 volumiobuster sudo[1206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 03:02:04 volumiobuster sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:04 volumiobuster volumio[1018]: info: Asound.conf file unchanged, so no further update is needed Oct 16 03:02:04 volumiobuster volumio[1018]: info: Output device has changed, restarting MPD Oct 16 03:02:04 volumiobuster sudo[1210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 03:02:04 volumiobuster sudo[1210]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:04 volumiobuster systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 16 03:02:04 volumiobuster systemd[1]: shairport-sync.service: Succeeded. Oct 16 03:02:04 volumiobuster systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 16 03:02:04 volumiobuster volumio[1018]: info: Output device has changed, restarting Shairport Sync Oct 16 03:02:04 volumiobuster sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 16 03:02:04 volumiobuster systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 16 03:02:04 volumiobuster sudo[1206]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:04 volumiobuster sudo[1204]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:04 volumiobuster sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:04 volumiobuster systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 16 03:02:04 volumiobuster sudo[1213]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:04 volumiobuster systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 16 03:02:04 volumiobuster systemd[1]: shairport-sync.service: Succeeded. Oct 16 03:02:04 volumiobuster systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 16 03:02:04 volumiobuster systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 16 03:02:04 volumiobuster sudo[1210]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:04 volumiobuster sudo[1217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 16 03:02:04 volumiobuster sudo[1217]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:04 volumiobuster volumio[1018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 16 03:02:04 volumiobuster volumio[1018]: [SpotifyConnect] Creating VLS config file Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 16 03:02:04 volumiobuster systemd[1]: mpd.service: Succeeded. Oct 16 03:02:04 volumiobuster systemd[1]: Stopped Music Player Daemon. Oct 16 03:02:04 volumiobuster systemd[1]: Starting Music Player Daemon... Oct 16 03:02:04 volumiobuster volumio[1018]: info: MPD Permissions set Oct 16 03:02:04 volumiobuster volumio[1018]: info: Shairport-Sync Started Oct 16 03:02:04 volumiobuster volumio[1018]: Error adding Membership: Error: addMembership EINVAL Oct 16 03:02:04 volumiobuster volumio[1018]: info: Shairport-Sync Started Oct 16 03:02:04 volumiobuster volumio[1018]: info: Shairport-Sync Started Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 16 03:02:04 volumiobuster volumio[1018]: info: Starting Shairport Sync Oct 16 03:02:04 volumiobuster volumio[1018]: [SpotifyConnect] Restarting Vollibrespot Daemon Oct 16 03:02:04 volumiobuster sudo[1231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 03:02:04 volumiobuster sudo[1231]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:04 volumiobuster sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service Oct 16 03:02:04 volumiobuster sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:04 volumiobuster systemd[1]: Stopping Volspotconnect2 Daemon... Oct 16 03:02:04 volumiobuster systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 16 03:02:04 volumiobuster systemd[1]: systemd-fsckd.service: Succeeded. Oct 16 03:02:04 volumiobuster systemd[1]: volspotconnect2.service: Main process exited, code=killed, status=15/TERM Oct 16 03:02:04 volumiobuster systemd[1]: volspotconnect2.service: Succeeded. Oct 16 03:02:04 volumiobuster systemd[1]: Stopped Volspotconnect2 Daemon. Oct 16 03:02:04 volumiobuster systemd[1]: shairport-sync.service: Succeeded. Oct 16 03:02:04 volumiobuster systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 16 03:02:04 volumiobuster systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 16 03:02:04 volumiobuster sudo[1231]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:04 volumiobuster systemd[1]: Started Volspotconnect2 Daemon. Oct 16 03:02:04 volumiobuster volumio[1018]: info: Shairport-Sync Started Oct 16 03:02:04 volumiobuster volumio[1238]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 Oct 16 03:02:04 volumiobuster volumio[1238]: Reading Config from "volspotify.toml" Oct 16 03:02:04 volumiobuster volumio[1238]: [Vollibrespot] : Using Alsa backend with device: volumio Oct 16 03:02:04 volumiobuster sudo[1233]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:04 volumiobuster volumio[1018]: [SpotifyConnect] Vollibrespot Daemon service restarted! Oct 16 03:02:05 volumiobuster volumio[1238]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Oct 16 03:02:06 volumiobuster mpd[1241]: Oct 16 03:02 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 16 03:02:06 volumiobuster mpd[1241]: Oct 16 03:02 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 16 03:02:06 volumiobuster mpd[1241]: Oct 16 03:02 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 16 03:02:06 volumiobuster systemd[1]: Started Music Player Daemon. Oct 16 03:02:06 volumiobuster sudo[1177]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:06 volumiobuster sudo[1217]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:06 volumiobuster volumio[1018]: info: filelength 131072 Oct 16 03:02:06 volumiobuster volumio[1018]: info: --------->filter format pcm FLOAT32LE Oct 16 03:02:06 volumiobuster volumio[1018]: info: --------->filter size 131072 Oct 16 03:02:06 volumiobuster volumio[1018]: info: File size found in array! Oct 16 03:02:06 volumiobuster volumio[1018]: info: gainmaxused,-0.5,0,1.5,1,0.5,1.5,2.5,3,0.5,1,1,1.5,2,3,3,0,0 Oct 16 03:02:06 volumiobuster volumio[1018]: info: crossatt 0 Oct 16 03:02:06 volumiobuster volumio[1018]: info: pipelinerr eq1 Oct 16 03:02:06 volumiobuster volumio[1018]: - eq2 Oct 16 03:02:06 volumiobuster volumio[1018]: - eq3 Oct 16 03:02:06 volumiobuster volumio[1018]: - eq4 Oct 16 03:02:06 volumiobuster volumio[1018]: - eq5 Oct 16 03:02:06 volumiobuster volumio[1018]: - eq6 Oct 16 03:02:06 volumiobuster volumio[1018]: - eq7 Oct 16 03:02:06 volumiobuster volumio[1018]: - eq8 Oct 16 03:02:06 volumiobuster volumio[1018]: - eq9 Oct 16 03:02:06 volumiobuster volumio[1018]: - eq10 Oct 16 03:02:06 volumiobuster volumio[1018]: - eq11 Oct 16 03:02:06 volumiobuster volumio[1018]: - eq12 Oct 16 03:02:06 volumiobuster volumio[1018]: - eq13 Oct 16 03:02:06 volumiobuster volumio[1018]: - eq14 Oct 16 03:02:06 volumiobuster volumio[1018]: - eq15 Oct 16 03:02:06 volumiobuster volumio[1018]: info: gainresult 3 number Oct 16 03:02:06 volumiobuster volumio[1018]: info: eq1: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Biquad Oct 16 03:02:06 volumiobuster volumio[1018]: parameters: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Peaking Oct 16 03:02:06 volumiobuster volumio[1018]: freq: 25 Oct 16 03:02:06 volumiobuster volumio[1018]: q: 1.85 Oct 16 03:02:06 volumiobuster volumio[1018]: gain: -0.5 Oct 16 03:02:06 volumiobuster volumio[1018]: eq2: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Biquad Oct 16 03:02:06 volumiobuster volumio[1018]: parameters: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Peaking Oct 16 03:02:06 volumiobuster volumio[1018]: freq: 40 Oct 16 03:02:06 volumiobuster volumio[1018]: q: 1.85 Oct 16 03:02:06 volumiobuster volumio[1018]: gain: 0 Oct 16 03:02:06 volumiobuster volumio[1018]: eq3: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Biquad Oct 16 03:02:06 volumiobuster volumio[1018]: parameters: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Peaking Oct 16 03:02:06 volumiobuster volumio[1018]: freq: 63 Oct 16 03:02:06 volumiobuster volumio[1018]: q: 1.85 Oct 16 03:02:06 volumiobuster volumio[1018]: gain: 1.5 Oct 16 03:02:06 volumiobuster volumio[1018]: eq4: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Biquad Oct 16 03:02:06 volumiobuster volumio[1018]: parameters: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Peaking Oct 16 03:02:06 volumiobuster volumio[1018]: freq: 100 Oct 16 03:02:06 volumiobuster volumio[1018]: q: 1.85 Oct 16 03:02:06 volumiobuster volumio[1018]: gain: 1 Oct 16 03:02:06 volumiobuster volumio[1018]: eq5: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Biquad Oct 16 03:02:06 volumiobuster volumio[1018]: parameters: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Peaking Oct 16 03:02:06 volumiobuster volumio[1018]: freq: 160 Oct 16 03:02:06 volumiobuster volumio[1018]: q: 1.85 Oct 16 03:02:06 volumiobuster volumio[1018]: gain: 0.5 Oct 16 03:02:06 volumiobuster volumio[1018]: eq6: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Biquad Oct 16 03:02:06 volumiobuster volumio[1018]: parameters: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Peaking Oct 16 03:02:06 volumiobuster volumio[1018]: freq: 250 Oct 16 03:02:06 volumiobuster volumio[1018]: q: 1.85 Oct 16 03:02:06 volumiobuster volumio[1018]: gain: 1.5 Oct 16 03:02:06 volumiobuster volumio[1018]: eq7: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Biquad Oct 16 03:02:06 volumiobuster volumio[1018]: parameters: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Peaking Oct 16 03:02:06 volumiobuster volumio[1018]: freq: 400 Oct 16 03:02:06 volumiobuster volumio[1018]: q: 1.85 Oct 16 03:02:06 volumiobuster volumio[1018]: gain: 2.5 Oct 16 03:02:06 volumiobuster volumio[1018]: eq8: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Biquad Oct 16 03:02:06 volumiobuster volumio[1018]: parameters: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Peaking Oct 16 03:02:06 volumiobuster volumio[1018]: freq: 630 Oct 16 03:02:06 volumiobuster volumio[1018]: q: 1.85 Oct 16 03:02:06 volumiobuster volumio[1018]: gain: 3 Oct 16 03:02:06 volumiobuster volumio[1018]: eq9: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Biquad Oct 16 03:02:06 volumiobuster volumio[1018]: parameters: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Peaking Oct 16 03:02:06 volumiobuster volumio[1018]: freq: 1000 Oct 16 03:02:06 volumiobuster volumio[1018]: q: 1.85 Oct 16 03:02:06 volumiobuster volumio[1018]: gain: 0.5 Oct 16 03:02:06 volumiobuster volumio[1018]: eq10: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Biquad Oct 16 03:02:06 volumiobuster volumio[1018]: parameters: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Peaking Oct 16 03:02:06 volumiobuster volumio[1018]: freq: 1600 Oct 16 03:02:06 volumiobuster volumio[1018]: q: 1.85 Oct 16 03:02:06 volumiobuster volumio[1018]: gain: 1 Oct 16 03:02:06 volumiobuster volumio[1018]: eq11: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Biquad Oct 16 03:02:06 volumiobuster volumio[1018]: parameters: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Peaking Oct 16 03:02:06 volumiobuster volumio[1018]: freq: 2500 Oct 16 03:02:06 volumiobuster volumio[1018]: q: 1.85 Oct 16 03:02:06 volumiobuster volumio[1018]: gain: 1 Oct 16 03:02:06 volumiobuster volumio[1018]: eq12: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Biquad Oct 16 03:02:06 volumiobuster volumio[1018]: parameters: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Peaking Oct 16 03:02:06 volumiobuster volumio[1018]: freq: 4000 Oct 16 03:02:06 volumiobuster volumio[1018]: q: 1.85 Oct 16 03:02:06 volumiobuster volumio[1018]: gain: 1.5 Oct 16 03:02:06 volumiobuster volumio[1018]: eq13: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Biquad Oct 16 03:02:06 volumiobuster volumio[1018]: parameters: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Peaking Oct 16 03:02:06 volumiobuster volumio[1018]: freq: 6300 Oct 16 03:02:06 volumiobuster volumio[1018]: q: 1.85 Oct 16 03:02:06 volumiobuster volumio[1018]: gain: 2 Oct 16 03:02:06 volumiobuster volumio[1018]: eq14: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Biquad Oct 16 03:02:06 volumiobuster volumio[1018]: parameters: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Peaking Oct 16 03:02:06 volumiobuster volumio[1018]: freq: 10000 Oct 16 03:02:06 volumiobuster volumio[1018]: q: 1.85 Oct 16 03:02:06 volumiobuster volumio[1018]: gain: 3 Oct 16 03:02:06 volumiobuster volumio[1018]: eq15: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Biquad Oct 16 03:02:06 volumiobuster volumio[1018]: parameters: Oct 16 03:02:06 volumiobuster volumio[1018]: type: Peaking Oct 16 03:02:06 volumiobuster volumio[1018]: freq: 16000 Oct 16 03:02:06 volumiobuster volumio[1018]: q: 1.85 Oct 16 03:02:06 volumiobuster volumio[1018]: gain: 3 Oct 16 03:02:06 volumiobuster volumio[1018]: error: MPD error: The expression evaluated to a falsy value: Oct 16 03:02:06 volumiobuster volumio[1018]: assert.ok(self.idling) Oct 16 03:02:06 volumiobuster volumio[1018]: error: updateQueue error: null Oct 16 03:02:07 volumiobuster sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 03:02:07 volumiobuster sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:07 volumiobuster sudo[1248]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:07 volumiobuster sudo[1250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 03:02:07 volumiobuster sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:07 volumiobuster sudo[1250]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:07 volumiobuster sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 16 03:02:07 volumiobuster sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:07 volumiobuster systemd[1]: Started UPnP Renderer front-end to MPD. Oct 16 03:02:07 volumiobuster sudo[1254]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:07 volumiobuster volumio[1018]: info: Upmpdcli Daemon Started Oct 16 03:02:07 volumiobuster volumio[1256]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 16 03:02:07 volumiobuster volumio[1018]: info: ControllerAutoPlay - getting queue Oct 16 03:02:07 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioGetQueue Oct 16 03:02:07 volumiobuster volumio[1018]: info: CoreStateMachine::getQueue Oct 16 03:02:07 volumiobuster volumio[1018]: info: CorePlayQueue::getQueue Oct 16 03:02:07 volumiobuster volumio[1018]: info: ControllerAutoPlay - start playing -> queue is not empty Oct 16 03:02:07 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioPlay Oct 16 03:02:07 volumiobuster volumio[1018]: UNSET VOLATILE Oct 16 03:02:07 volumiobuster volumio[1018]: info: CoreStateMachine::play index undefined Oct 16 03:02:07 volumiobuster volumio[1018]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 03:02:07 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:07 volumiobuster volumio[1018]: info: CoreStateMachine::startPlaybackTimer Oct 16 03:02:07 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:07 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 03:02:07 volumiobuster volumio[1018]: info: sendMpdCommand stop took 11 milliseconds Oct 16 03:02:07 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 03:02:07 volumiobuster volumio[1018]: info: Oct 16 03:02:07 volumiobuster volumio[1018]: ---------------------------- MPD announces system playlist update Oct 16 03:02:07 volumiobuster volumio[1018]: info: Ignoring MPD Status Update Oct 16 03:02:07 volumiobuster volumio[1018]: info: sendMpdCommand clear took 4 milliseconds Oct 16 03:02:07 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 16 03:02:07 volumiobuster volumio[1018]: info: Oct 16 03:02:07 volumiobuster volumio[1018]: ---------------------------- MPD announces system playlist update Oct 16 03:02:07 volumiobuster volumio[1018]: info: Ignoring MPD Status Update Oct 16 03:02:07 volumiobuster volumio[1018]: error: updateQueue error: null Oct 16 03:02:07 volumiobuster volumio[1018]: info: Oct 16 03:02:07 volumiobuster volumio[1018]: ---------------------------- MPD announces state update: options Oct 16 03:02:07 volumiobuster volumio[1018]: error: updateQueue error: null Oct 16 03:02:07 volumiobuster volumio[1018]: info: ------------------------------ 10ms Oct 16 03:02:07 volumiobuster volumio[1018]: info: sendMpdCommand consume 1 took 7 milliseconds Oct 16 03:02:07 volumiobuster volumio[1018]: info: ControllerMpd::getState Oct 16 03:02:07 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Oct 16 03:02:07 volumiobuster volumio[1018]: info: ------------------------------ 7ms Oct 16 03:02:07 volumiobuster volumio[1018]: info: [1634353327901] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flac Oct 16 03:02:07 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" Oct 16 03:02:07 volumiobuster volumio[1018]: info: Oct 16 03:02:07 volumiobuster volumio[1018]: ---------------------------- MPD announces state update: options Oct 16 03:02:07 volumiobuster volumio[1018]: info: ControllerMpd::getState Oct 16 03:02:07 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Oct 16 03:02:07 volumiobuster volumio[1018]: info: Oct 16 03:02:07 volumiobuster volumio[1018]: ---------------------------- MPD announces system playlist update Oct 16 03:02:07 volumiobuster volumio[1018]: info: Ignoring MPD Status Update Oct 16 03:02:07 volumiobuster volumio[1018]: info: sendMpdCommand status took 8 milliseconds Oct 16 03:02:07 volumiobuster volumio[1018]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" took 8 milliseconds Oct 16 03:02:07 volumiobuster volumio[1018]: info: sendMpdCommand status took 5 milliseconds Oct 16 03:02:07 volumiobuster volumio[1018]: verbose: ControllerMpd::parseState Oct 16 03:02:07 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand play Oct 16 03:02:07 volumiobuster volumio[1018]: verbose: ControllerMpd::parseState Oct 16 03:02:07 volumiobuster volumio[1018]: info: ControllerMpd::pushState Oct 16 03:02:07 volumiobuster volumio[1018]: info: CoreCommandRouter::servicePushState Oct 16 03:02:07 volumiobuster volumio[1018]: info: CoreStateMachine::pushState Oct 16 03:02:07 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:07 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 16 03:02:07 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 03:02:07 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:07 volumiobuster volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 16 03:02:07 volumiobuster volumio[1018]: info: ControllerMpd::pushState Oct 16 03:02:07 volumiobuster volumio[1018]: info: CoreCommandRouter::servicePushState Oct 16 03:02:07 volumiobuster volumio[1018]: info: CoreStateMachine::pushState Oct 16 03:02:07 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:07 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 16 03:02:07 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 03:02:07 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:07 volumiobuster volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 16 03:02:07 volumiobuster volumio[1018]: info: ------------------------------ 48ms Oct 16 03:02:07 volumiobuster volumio[1018]: info: ------------------------------ 51ms Oct 16 03:02:07 volumiobuster volumio[1018]: info: Oct 16 03:02:07 volumiobuster volumio[1018]: ---------------------------- MPD announces system playlist update Oct 16 03:02:07 volumiobuster volumio[1018]: info: Ignoring MPD Status Update Oct 16 03:02:07 volumiobuster volumio[1018]: info: ------------------------------ 52ms Oct 16 03:02:07 volumiobuster volumio[1018]: info: sendMpdCommand play took 42 milliseconds Oct 16 03:02:07 volumiobuster volumio[1018]: info: ------------------------------ 5ms Oct 16 03:02:07 volumiobuster volumio[1018]: info: [1634353327962] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Oct 16 03:02:08 volumiobuster volumio[1256]: ....++++ Oct 16 03:02:08 volumiobuster volumio[1018]: info: Discovery: adding 7f1fcdf1-f138-40a3-95f1-373ad79374eb Oct 16 03:02:08 volumiobuster volumio[1018]: info: mDNS: Found device Volumio2 Oct 16 03:02:08 volumiobuster volumio[1018]: info: Discovery: this is already registered, 7f1fcdf1-f138-40a3-95f1-373ad79374eb Oct 16 03:02:08 volumiobuster volumio[1018]: info: mDNS: Found device Volumio2 Oct 16 03:02:08 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 16 03:02:08 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:08 volumiobuster volumio[1018]: WebSocket error: [object Object] Oct 16 03:02:09 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 16 03:02:09 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:09 volumiobuster volumio[1018]: info: Oct 16 03:02:09 volumiobuster volumio[1018]: ---------------------------- MPD announces state update: player Oct 16 03:02:09 volumiobuster volumio[1018]: info: ControllerMpd::getState Oct 16 03:02:09 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Oct 16 03:02:09 volumiobuster volumio[1018]: info: Oct 16 03:02:09 volumiobuster volumio[1018]: ---------------------------- MPD announces state update: player Oct 16 03:02:09 volumiobuster volumio[1018]: info: ControllerMpd::getState Oct 16 03:02:09 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Oct 16 03:02:09 volumiobuster volumio[1018]: info: Oct 16 03:02:09 volumiobuster volumio[1018]: ---------------------------- MPD announces state update: player Oct 16 03:02:09 volumiobuster volumio[1018]: info: ControllerMpd::getState Oct 16 03:02:09 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Oct 16 03:02:09 volumiobuster volumio[1018]: info: sendMpdCommand status took 5 milliseconds Oct 16 03:02:09 volumiobuster volumio[1018]: info: sendMpdCommand status took 4 milliseconds Oct 16 03:02:09 volumiobuster volumio[1018]: verbose: ControllerMpd::parseState Oct 16 03:02:09 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 03:02:09 volumiobuster volumio[1018]: verbose: ControllerMpd::parseState Oct 16 03:02:09 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 03:02:09 volumiobuster volumio[1018]: info: Oct 16 03:02:09 volumiobuster volumio[1018]: ---------------------------- MPD announces state update: player Oct 16 03:02:09 volumiobuster volumio[1018]: info: ControllerMpd::getState Oct 16 03:02:09 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Oct 16 03:02:09 volumiobuster volumio[1018]: info: sendMpdCommand status took 8 milliseconds Oct 16 03:02:09 volumiobuster volumio[1018]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 16 03:02:09 volumiobuster volumio[1018]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 16 03:02:09 volumiobuster volumio[1018]: info: sendMpdCommand status took 3 milliseconds Oct 16 03:02:09 volumiobuster volumio[1018]: verbose: ControllerMpd::parseState Oct 16 03:02:09 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 03:02:09 volumiobuster volumio[1018]: verbose: ControllerMpd::parseTrackInfo Oct 16 03:02:09 volumiobuster volumio[1018]: verbose: ControllerMpd::parseTrackInfo Oct 16 03:02:09 volumiobuster volumio[1018]: verbose: ControllerMpd::parseState Oct 16 03:02:09 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 03:02:09 volumiobuster volumio[1018]: info: ControllerMpd::pushState Oct 16 03:02:09 volumiobuster volumio[1018]: info: CoreCommandRouter::servicePushState Oct 16 03:02:09 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:09 volumiobuster volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 16 03:02:09 volumiobuster volumio[1018]: info: ControllerMpd::pushState Oct 16 03:02:09 volumiobuster volumio[1018]: info: CoreCommandRouter::servicePushState Oct 16 03:02:09 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:09 volumiobuster volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 16 03:02:09 volumiobuster volumio[1018]: info: ------------------------------ 25ms Oct 16 03:02:09 volumiobuster volumio[1018]: info: ------------------------------ 23ms Oct 16 03:02:09 volumiobuster volumio[1018]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 16 03:02:09 volumiobuster volumio[1018]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 16 03:02:09 volumiobuster volumio[1018]: verbose: ControllerMpd::parseTrackInfo Oct 16 03:02:09 volumiobuster volumio[1018]: verbose: ControllerMpd::parseTrackInfo Oct 16 03:02:09 volumiobuster volumio[1018]: info: ControllerMpd::pushState Oct 16 03:02:09 volumiobuster volumio[1018]: info: CoreCommandRouter::servicePushState Oct 16 03:02:09 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:09 volumiobuster volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 16 03:02:09 volumiobuster volumio[1018]: info: ControllerMpd::pushState Oct 16 03:02:09 volumiobuster volumio[1018]: info: CoreCommandRouter::servicePushState Oct 16 03:02:09 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:09 volumiobuster volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 16 03:02:09 volumiobuster volumio[1018]: info: ------------------------------ 26ms Oct 16 03:02:09 volumiobuster volumio[1018]: info: ------------------------------ 20ms Oct 16 03:02:10 volumiobuster volumio[1018]: info: Oct 16 03:02:10 volumiobuster volumio[1018]: ---------------------------- MPD announces system playlist update Oct 16 03:02:10 volumiobuster volumio[1018]: info: Ignoring MPD Status Update Oct 16 03:02:10 volumiobuster volumio[1018]: info: Oct 16 03:02:10 volumiobuster volumio[1018]: ---------------------------- MPD announces state update: player Oct 16 03:02:10 volumiobuster volumio[1018]: info: ControllerMpd::getState Oct 16 03:02:10 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Oct 16 03:02:10 volumiobuster volumio[1018]: info: Oct 16 03:02:10 volumiobuster volumio[1018]: ---------------------------- MPD announces system playlist update Oct 16 03:02:10 volumiobuster volumio[1018]: info: Ignoring MPD Status Update Oct 16 03:02:10 volumiobuster volumio[1018]: info: Oct 16 03:02:10 volumiobuster volumio[1018]: ---------------------------- MPD announces state update: player Oct 16 03:02:10 volumiobuster volumio[1018]: info: ControllerMpd::getState Oct 16 03:02:10 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand status Oct 16 03:02:10 volumiobuster volumio[1018]: info: ------------------------------ 8ms Oct 16 03:02:10 volumiobuster volumio[1018]: info: sendMpdCommand status took 5 milliseconds Oct 16 03:02:10 volumiobuster volumio[1018]: info: ------------------------------ 5ms Oct 16 03:02:10 volumiobuster volumio[1018]: info: sendMpdCommand status took 3 milliseconds Oct 16 03:02:10 volumiobuster volumio[1018]: verbose: ControllerMpd::parseState Oct 16 03:02:10 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 03:02:10 volumiobuster volumio[1018]: verbose: ControllerMpd::parseState Oct 16 03:02:10 volumiobuster volumio[1018]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 03:02:10 volumiobuster volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 03:02:10 volumiobuster volumio[1018]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 16 03:02:10 volumiobuster volumio[1018]: verbose: ControllerMpd::parseTrackInfo Oct 16 03:02:10 volumiobuster volumio[1018]: verbose: ControllerMpd::parseTrackInfo Oct 16 03:02:10 volumiobuster volumio[1018]: info: ControllerMpd::pushState Oct 16 03:02:10 volumiobuster volumio[1018]: info: CoreCommandRouter::servicePushState Oct 16 03:02:10 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:10 volumiobuster volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 16 03:02:10 volumiobuster volumio[1018]: info: ControllerMpd::pushState Oct 16 03:02:10 volumiobuster volumio[1018]: info: CoreCommandRouter::servicePushState Oct 16 03:02:10 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:10 volumiobuster volumio[1018]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Oct 16 03:02:10 volumiobuster volumio[1018]: info: ------------------------------ 15ms Oct 16 03:02:10 volumiobuster volumio[1018]: info: ------------------------------ 11ms Oct 16 03:02:12 volumiobuster systemd[1]: systemd-hostnamed.service: Succeeded. Oct 16 03:02:13 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 16 03:02:13 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 16 03:02:13 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 16 03:02:13 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 16 03:02:13 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 16 03:02:13 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 16 03:02:13 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 16 03:02:13 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 16 03:02:13 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 16 03:02:13 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 16 03:02:13 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 16 03:02:13 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 16 03:02:13 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 16 03:02:13 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 16 03:02:13 volumiobuster volumio[1018]: info: Adding plugin bluetooth to MyMusic Plugins Oct 16 03:02:13 volumiobuster volumio[1018]: info: Adding plugin cd_controller to MyMusic Plugins Oct 16 03:02:13 volumiobuster volumio[1018]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 16 03:02:13 volumiobuster volumio[1018]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 16 03:02:13 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 16 03:02:13 volumiobuster volumio[1256]: ..................................++++ Oct 16 03:02:13 volumiobuster volumio[1256]: e is 65537 (0x010001) Oct 16 03:02:13 volumiobuster volumio[1256]: writing RSA key Oct 16 03:02:14 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 16 03:02:14 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 16 03:02:14 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 03:02:14 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 03:02:14 volumiobuster volumio[1018]: info: Starting MyVolumio Remote Streaming Endpoints Oct 16 03:02:14 volumiobuster volumio[1018]: info: MyVolumio login type: Token Oct 16 03:02:14 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 16 03:02:14 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 16 03:02:14 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 16 03:02:14 volumiobuster volumio[1018]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 16 03:02:14 volumiobuster volumio[1018]: info: Streaming services startup Oct 16 03:02:14 volumiobuster volumio[1018]: info: Starting Streaming Daemon Oct 16 03:02:15 volumiobuster sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 16 03:02:15 volumiobuster sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:15 volumiobuster systemd[1]: Stopping Volumio Streaming Daemon... Oct 16 03:02:15 volumiobuster systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Oct 16 03:02:15 volumiobuster systemd[1]: volumio-streaming-daemon.service: Succeeded. Oct 16 03:02:15 volumiobuster systemd[1]: Stopped Volumio Streaming Daemon. Oct 16 03:02:15 volumiobuster systemd[1]: Started Volumio Streaming Daemon. Oct 16 03:02:15 volumiobuster volumio-streaming-daemon[1316]: ############################ Oct 16 03:02:15 volumiobuster volumio-streaming-daemon[1316]: # Volumio Streaming Daemon # Oct 16 03:02:15 volumiobuster volumio-streaming-daemon[1316]: # Running on port 7777 # Oct 16 03:02:15 volumiobuster volumio-streaming-daemon[1316]: ############################ Oct 16 03:02:15 volumiobuster volumio-streaming-daemon[1316]: Environment: production Oct 16 03:02:15 volumiobuster sudo[1310]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:15 volumiobuster volumio[1018]: error: MyVolumio Custom Token format not valid, refreshing it Oct 16 03:02:15 volumiobuster volumio[1018]: info: [1634353335761] [RadioParadise] received new metadata: {"time":297,"artist":"Willie and Lobo","title":"Lost Caravan","album":"Between the Waters","year":"1995","cover":"https://img.radioparadise.com/covers/l/B000002JU7.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000002JU7.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000002JU7.jpg"} Oct 16 03:02:15 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:15 volumiobuster volumio[1018]: info: CoreCommandRouter::servicePushState Oct 16 03:02:15 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:15 volumiobuster volumio[1018]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"track","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000002JU7.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Willie and Lobo - Lost Caravan","title":"Lost Caravan","artist":"Radio Paradise Mellow","album":"Between the Waters","streaming":true,"disableUiControls":true,"duration":302,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Oct 16 03:02:15 volumiobuster volumio[1018]: verbose: CURRENT POSITION 0 Oct 16 03:02:15 volumiobuster volumio[1018]: info: CoreStateMachine::syncState stateService play Oct 16 03:02:15 volumiobuster volumio[1018]: info: CoreStateMachine::syncState currentStatus stop Oct 16 03:02:15 volumiobuster volumio[1018]: info: CoreStateMachine::pushState Oct 16 03:02:15 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:15 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioPushState Oct 16 03:02:15 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 03:02:15 volumiobuster volumio[1018]: info: [1634353335782] [RadioParadise] setting new timer with duration of 302000 seconds. Oct 16 03:02:15 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioGetQueue Oct 16 03:02:15 volumiobuster volumio[1018]: info: CoreStateMachine::getQueue Oct 16 03:02:15 volumiobuster volumio[1018]: info: CorePlayQueue::getQueue Oct 16 03:02:15 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:15 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 1 Oct 16 03:02:15 volumiobuster volumio[1018]: info: Prefetching next song Oct 16 03:02:16 volumiobuster volumio[1018]: info: MyVolumio login type: Token Oct 16 03:02:17 volumiobuster volumio[1018]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 16 03:02:17 volumiobuster volumio[1018]: info: MYVOLUMIO: Adding device Oct 16 03:02:17 volumiobuster volumio[1018]: info: MYVOLUMIO: Evaluating Server Oct 16 03:02:17 volumiobuster volumio-streaming-daemon[1316]: Environment: production Oct 16 03:02:17 volumiobuster volumio[1018]: info: Fetching Streaming Services browse cache Oct 16 03:02:23 volumiobuster volumio[1018]: info: MyVolumio status changed Oct 16 03:02:23 volumiobuster volumio[1018]: info: Streaming services startup Oct 16 03:02:23 volumiobuster volumio[1018]: info: Re-Starting Streaming Daemon Oct 16 03:02:23 volumiobuster volumio[1018]: info: Removing browser output: myVolumio user plan is not superstar Oct 16 03:02:23 volumiobuster volumio[1018]: info: Removing audio output: Oct 16 03:02:23 volumiobuster volumio[1018]: info: Stoppping Tunnel 1 Oct 16 03:02:23 volumiobuster sudo[1350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 16 03:02:23 volumiobuster sudo[1350]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:23 volumiobuster volumio-streaming-daemon[1316]: Restarting daemon Oct 16 03:02:23 volumiobuster volumio-streaming-daemon[1316]: Environment: production Oct 16 03:02:23 volumiobuster sudo[1350]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:23 volumiobuster volumio[1018]: info: Remote SSH Stopped Oct 16 03:02:23 volumiobuster volumio-streaming-daemon[1316]: Environment: production Oct 16 03:02:23 volumiobuster volumio[1018]: info: Fetching Streaming Services browse cache Oct 16 03:02:25 volumiobuster volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 03:02:25 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 03:02:25 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 16 03:02:25 volumiobuster sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 16 03:02:25 volumiobuster sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:25 volumiobuster sudo[1365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 16 03:02:25 volumiobuster sudo[1365]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:25 volumiobuster sudo[1359]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:25 volumiobuster sudo[1365]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:25 volumiobuster sudo[1371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 16 03:02:25 volumiobuster sudo[1371]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:25 volumiobuster sudo[1376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 16 03:02:25 volumiobuster sudo[1376]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:25 volumiobuster sudo[1371]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:25 volumiobuster sudo[1376]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:25 volumiobuster sudo[1383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 03:02:25 volumiobuster sudo[1383]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:25 volumiobuster sudo[1383]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:25 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 16 03:02:25 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 16 03:02:25 volumiobuster sudo[1387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 03:02:25 volumiobuster sudo[1387]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:25 volumiobuster sudo[1387]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:25 volumiobuster sudo[1393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 16 03:02:25 volumiobuster sudo[1393]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 03:02:26 volumiobuster sudo[1393]: pam_unix(sudo:session): session closed for user root Oct 16 03:02:29 volumiobuster volumio[1018]: info: Setting Geolocation for MyVolumio to us1 Oct 16 03:02:29 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 03:02:29 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 03:02:29 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 03:02:30 volumiobuster volumio[1018]: info: Updating MyVolumio device info Oct 16 03:02:30 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 03:02:33 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 16 03:02:33 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 03:02:35 volumiobuster volumio[1018]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 16 03:02:35 volumiobuster volumio[1018]: info: Completed starting MyVolumio Plugin Oct 16 03:02:35 volumiobuster volumio[1018]: info: BOOT COMPLETED Oct 16 03:02:35 volumiobuster volumio[1018]: [Metrics] CommandRouter: 41s 182.35ms Oct 16 03:02:35 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 16 03:02:35 volumiobuster volumio[1018]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Oct 16 03:02:35 volumiobuster volumio[1018]: Oct 16 03:02:35.087 ERRO Failed to start websocket server: Address already in use (os error 98), module: camillalib::socketserver Oct 16 03:02:35 volumiobuster volumio[1018]: Oct 16 03:02:35.113 ERRO Playback error: ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy', module: camilladsp Oct 16 03:02:35 volumiobuster volumio[1018]: aplay: pcm_write:2053: write error: No such device Oct 16 03:02:35 volumiobuster volumio[1018]: Cannot play startup sound Oct 16 03:02:35 volumiobuster volumio[1018]: info: CoreCommandRouter::Close All Modals sent Oct 16 03:02:35 volumiobuster volumio[1018]: info: CoreCommandRouter::Close All Modals sent Oct 16 03:02:36 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 16 03:02:36 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 03:02:36 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 16 03:02:44 volumiobuster ntpd[651]: Soliciting pool server 168.96.251.197 Oct 16 03:02:45 volumiobuster ntpd[651]: Soliciting pool server 162.159.200.1 Oct 16 03:02:45 volumiobuster ntpd[651]: Soliciting pool server 162.159.200.123 Oct 16 03:02:45 volumiobuster ntpd[651]: Soliciting pool server 168.96.251.195 Oct 16 03:02:46 volumiobuster ntpd[651]: Soliciting pool server 2001:1318:1029::226 Oct 16 03:02:46 volumiobuster ntpd[651]: Soliciting pool server 168.96.251.226 Oct 16 03:02:47 volumiobuster ntpd[651]: Soliciting pool server 217.114.59.3 Oct 16 03:02:48 volumiobuster ntpd[651]: Soliciting pool server 176.119.210.243 Oct 16 03:02:49 volumiobuster ntpd[651]: Soliciting pool server 157.90.24.29 Oct 16 03:02:49 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 16 03:02:49 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 16 03:02:49 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioGetQueue Oct 16 03:02:49 volumiobuster volumio[1018]: info: CoreStateMachine::getQueue Oct 16 03:02:49 volumiobuster volumio[1018]: info: CorePlayQueue::getQueue Oct 16 03:02:49 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Oct 16 03:02:50 volumiobuster ntpd[651]: Soliciting pool server 95.215.175.2 Oct 18 22:42:33 volumiobuster ntpd[651]: receive: Unexpected origin timestamp 0xe514bf5d.1817c526 does not match aorg 0000000000.00000000 from server@168.96.251.226 xmt 0xe51876d9.9f50cacc Oct 18 22:42:33 volumiobuster ntpd[651]: receive: Unexpected origin timestamp 0xe514bf5d.1819dc68 does not match aorg 0000000000.00000000 from server@168.96.251.195 xmt 0xe51876d9.9f1b32bf Oct 18 22:42:33 volumiobuster systemd[1]: Starting Daily apt download activities... Oct 18 22:42:33 volumiobuster ntpd[651]: receive: Unexpected origin timestamp 0xe514bf5d.1815845b does not match aorg 0000000000.00000000 from server@157.90.24.29 xmt 0xe51876d9.ba8bea0d Oct 18 22:42:33 volumiobuster ntpd[651]: receive: Unexpected origin timestamp 0xe514bf5d.180bb01b does not match aorg 0000000000.00000000 from server@95.215.175.2 xmt 0xe51876d9.bbcefe9c Oct 18 22:42:35 volumiobuster volumio[1018]: info: mDNS: A device disapperared from network Oct 18 22:42:35 volumiobuster volumio[1018]: info: mDNS: Device volumio2 disapperared from network Oct 18 22:42:35 volumiobuster volumio[1018]: info: mDNS: A device disapperared from network Oct 18 22:42:35 volumiobuster volumio[1018]: info: mDNS: Device volumiobuster disapperared from network Oct 18 22:42:35 volumiobuster volumio[1018]: info: mDNS: A device disapperared from network Oct 18 22:42:35 volumiobuster volumio[1018]: info: mDNS: A device disapperared from network Oct 18 22:42:36 volumiobuster volumio[1018]: info: Discovery: adding 08e4ca6c-0cd9-4171-adf5-4a745e3af0fa Oct 18 22:42:36 volumiobuster volumio[1018]: info: mDNS: Found device VolumioBuster Oct 18 22:42:36 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 18 22:42:36 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 22:42:36 volumiobuster volumio[1018]: info: Discovery: this is already registered, 08e4ca6c-0cd9-4171-adf5-4a745e3af0fa Oct 18 22:42:36 volumiobuster volumio[1018]: info: mDNS: Found device VolumioBuster Oct 18 22:42:36 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 18 22:42:36 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 22:42:37 volumiobuster systemd[1]: apt-daily.service: Succeeded. Oct 18 22:42:37 volumiobuster systemd[1]: Started Daily apt download activities. Oct 18 22:42:37 volumiobuster systemd[1]: Starting Daily apt upgrade and clean activities... Oct 18 22:42:38 volumiobuster systemd[1]: apt-daily-upgrade.service: Succeeded. Oct 18 22:42:38 volumiobuster systemd[1]: Started Daily apt upgrade and clean activities. Oct 18 22:42:41 volumiobuster volumio[1018]: info: Discovery: adding 7f1fcdf1-f138-40a3-95f1-373ad79374eb Oct 18 22:42:41 volumiobuster volumio[1018]: info: mDNS: Found device Volumio2 Oct 18 22:42:41 volumiobuster volumio[1018]: info: Discovery: this is already registered, 7f1fcdf1-f138-40a3-95f1-373ad79374eb Oct 18 22:42:41 volumiobuster volumio[1018]: info: mDNS: Found device Volumio2 Oct 18 22:42:41 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 18 22:42:41 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 22:43:05 volumiobuster sudo[1631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 22:43:05 volumiobuster sudo[1631]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 22:43:05 volumiobuster sudo[1631]: pam_unix(sudo:session): session closed for user root Oct 18 22:43:05 volumiobuster sudo[1633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 22:43:05 volumiobuster sudo[1633]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 22:43:05 volumiobuster sudo[1633]: pam_unix(sudo:session): session closed for user root Oct 18 22:43:05 volumiobuster sudo[1637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 22:43:05 volumiobuster sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 22:43:05 volumiobuster sudo[1637]: pam_unix(sudo:session): session closed for user root Oct 18 22:43:05 volumiobuster sudo[1639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 22:43:05 volumiobuster sudo[1639]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 22:43:05 volumiobuster sudo[1639]: pam_unix(sudo:session): session closed for user root Oct 18 22:43:06 volumiobuster volumio[1018]: info: CoreCommandRouter::volumioGetState Oct 18 22:43:06 volumiobuster volumio[1018]: info: CorePlayQueue::getTrack 0 Oct 18 22:43:06 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 18 22:43:06 volumiobuster volumio[1018]: info: Listing playlists Oct 18 22:43:06 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 18 22:43:06 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 18 22:43:06 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 18 22:43:06 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 18 22:43:07 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 18 22:43:07 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 18 22:43:10 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 18 22:43:10 volumiobuster volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 18 22:43:10 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 18 22:43:10 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 18 22:43:10 volumiobuster sudo[1657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 18 22:43:10 volumiobuster sudo[1657]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 22:43:10 volumiobuster sudo[1657]: pam_unix(sudo:session): session closed for user root Oct 18 22:43:10 volumiobuster sudo[1663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 18 22:43:10 volumiobuster sudo[1663]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 22:43:10 volumiobuster sudo[1663]: pam_unix(sudo:session): session closed for user root Oct 18 22:43:10 volumiobuster sudo[1670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 18 22:43:10 volumiobuster sudo[1670]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 22:43:10 volumiobuster sudo[1670]: pam_unix(sudo:session): session closed for user root Oct 18 22:43:10 volumiobuster sudo[1675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 18 22:43:10 volumiobuster sudo[1675]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 22:43:10 volumiobuster sudo[1675]: pam_unix(sudo:session): session closed for user root Oct 18 22:43:10 volumiobuster sudo[1683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 18 22:43:10 volumiobuster sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 22:43:10 volumiobuster sudo[1685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 18 22:43:10 volumiobuster sudo[1683]: pam_unix(sudo:session): session closed for user root Oct 18 22:43:10 volumiobuster sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 22:43:10 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 18 22:43:10 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 18 22:43:10 volumiobuster sudo[1685]: pam_unix(sudo:session): session closed for user root Oct 18 22:43:10 volumiobuster sudo[1689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 18 22:43:10 volumiobuster sudo[1689]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 22:43:10 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 18 22:43:11 volumiobuster sudo[1689]: pam_unix(sudo:session): session closed for user root Oct 18 22:43:25 volumiobuster volumio[1018]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Oct 18 22:43:25 volumiobuster volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Oct 18 22:43:26 volumiobuster sudo[1741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Oct 18 22:43:26 volumiobuster sudo[1741]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 18 22:43:26 volumiobuster sudo[1741]: pam_unix(sudo:session): session closed for user root Oct 18 22:43:39 volumiobuster sudo[1802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Oct 18 22:43:39 volumiobuster sudo[1802]: pam_unix(sudo:session): session opened for user root by (uid=0)